builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1471263774.88 results: success (0) buildid: 20160815030201 builduid: 781c33275abc40ecb7a992adcf58e9cc revision: 6e191a55c3d23e83e6a2e72e4e80c1dc21516493 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:54.884112) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:54.884451) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:54.884801) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:55.137592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:55.138005) ========= 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.CtLvVYBaXO/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.W60qCjxZcs/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-15 05:22:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2016-08-15 05:22:55 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.527793 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:55.697653) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:55.698002) ========= 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.CtLvVYBaXO/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.W60qCjxZcs/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.029140 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:55.748002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 05:22:55.748361) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6e191a55c3d23e83e6a2e72e4e80c1dc21516493 --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 6e191a55c3d23e83e6a2e72e4e80c1dc21516493 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtLvVYBaXO/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.W60qCjxZcs/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-15 05:22:55,824 truncating revision to first 12 chars 2016-08-15 05:22:55,825 Setting DEBUG logging. 2016-08-15 05:22:55,825 attempt 1/10 2016-08-15 05:22:55,825 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6e191a55c3d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 05:22:57,113 unpacking tar archive at: mozilla-central-6e191a55c3d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.550595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 05:22:57.321000) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:57.321364) ========= script_repo_revision: 6e191a55c3d23e83e6a2e72e4e80c1dc21516493 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:57.321770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:57.322092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 05:22:57.342455) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-15 05:22:57.342882) ========= /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.CtLvVYBaXO/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.W60qCjxZcs/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:22:57 INFO - MultiFileLogger online at 20160815 05:22:57 in /builds/slave/test 05:22:57 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 05:22:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:22:57 INFO - {'append_to_log': False, 05:22:57 INFO - 'base_work_dir': '/builds/slave/test', 05:22:57 INFO - 'blob_upload_branch': 'Firefox', 05:22:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:22:57 INFO - 'branch': 'Firefox', 05:22:57 INFO - 'buildbot_json_path': 'buildprops.json', 05:22:57 INFO - 'config_files': ('talos/mac_config.py',), 05:22:57 INFO - 'default_actions': ('clobber', 05:22:57 INFO - 'read-buildbot-config', 05:22:57 INFO - 'download-and-extract', 05:22:57 INFO - 'populate-webroot', 05:22:57 INFO - 'create-virtualenv', 05:22:57 INFO - 'install', 05:22:57 INFO - 'run-tests'), 05:22:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:22:57 INFO - 'download_minidump_stackwalk': True, 05:22:57 INFO - 'download_symbols': 'ondemand', 05:22:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:22:57 INFO - 'tooltool.py': '/tools/tooltool.py', 05:22:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:22:57 INFO - '/tools/misc-python/virtualenv.py')}, 05:22:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:22:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:22:57 INFO - 'installer_path': 'installer.exe', 05:22:57 INFO - 'log_level': 'info', 05:22:57 INFO - 'log_name': 'talos', 05:22:57 INFO - 'log_to_console': True, 05:22:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:22:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:22:57 INFO - 'opt_config_files': (), 05:22:57 INFO - 'pip_index': False, 05:22:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:57 INFO - 'cmd': ('bash', 05:22:57 INFO - '-c', 05:22:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:57 INFO - 'enabled': True, 05:22:57 INFO - 'halt_on_failure': False, 05:22:57 INFO - 'name': 'check_screen_resolution'},), 05:22:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:22:57 INFO - 'cmd': ('bash', 05:22:57 INFO - '-c', 05:22:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:22:57 INFO - 'enabled': True, 05:22:57 INFO - 'halt_on_failure': False, 05:22:57 INFO - 'name': 'check_screen_resolution'},), 05:22:57 INFO - 'run_cmd_checks_enabled': True, 05:22:57 INFO - 'sps_profile': False, 05:22:57 INFO - 'sps_profile_interval': 0, 05:22:57 INFO - 'suite': 'dromaeojs', 05:22:57 INFO - 'system_bits': '32', 05:22:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:22:57 INFO - 'title': 't-yosemite-r7-0081', 05:22:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:22:57 INFO - 'use_talos_json': True, 05:22:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:22:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:22:57 INFO - 'work_dir': 'build'} 05:22:57 INFO - [mozharness: 2016-08-15 12:22:57.539074Z] Running clobber step. 05:22:57 INFO - Running pre-action listener: _resource_record_pre_action 05:22:57 INFO - Running main action method: clobber 05:22:57 INFO - rmtree: /builds/slave/test/build 05:22:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:00 INFO - Running post-action listener: _resource_record_post_action 05:23:00 INFO - [mozharness: 2016-08-15 12:23:00.041280Z] Finished clobber step (success) 05:23:00 INFO - [mozharness: 2016-08-15 12:23:00.041408Z] Running read-buildbot-config step. 05:23:00 INFO - Running pre-action listener: _resource_record_pre_action 05:23:00 INFO - Running main action method: read_buildbot_config 05:23:00 INFO - Using buildbot properties: 05:23:00 INFO - { 05:23:00 INFO - "project": "", 05:23:00 INFO - "product": "firefox", 05:23:00 INFO - "script_repo_revision": "production", 05:23:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:23:00 INFO - "repository": "", 05:23:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:23:00 INFO - "buildid": "20160815030201", 05:23:00 INFO - "stage_platform": "macosx64", 05:23:00 INFO - "basedir": "/builds/slave/test", 05:23:00 INFO - "nightly_build": "True", 05:23:00 INFO - "buildnumber": 320, 05:23:00 INFO - "slavename": "t-yosemite-r7-0081", 05:23:00 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:23:00 INFO - "platform": "yosemite_r7", 05:23:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:23:00 INFO - "branch": "mozilla-central", 05:23:00 INFO - "repo_path": "mozilla-central", 05:23:00 INFO - "revision": "6e191a55c3d23e83e6a2e72e4e80c1dc21516493", 05:23:00 INFO - "pgo_build": "False", 05:23:00 INFO - "builduid": "781c33275abc40ecb7a992adcf58e9cc", 05:23:00 INFO - "slavebuilddir": "test" 05:23:00 INFO - } 05:23:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:23:00 INFO - Running post-action listener: _resource_record_post_action 05:23:00 INFO - [mozharness: 2016-08-15 12:23:00.044722Z] Finished read-buildbot-config step (success) 05:23:00 INFO - [mozharness: 2016-08-15 12:23:00.044824Z] Running download-and-extract step. 05:23:00 INFO - Running pre-action listener: _resource_record_pre_action 05:23:00 INFO - Running main action method: download_and_extract 05:23:00 INFO - mkdir: /builds/slave/test/build/tests 05:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:00 INFO - trying https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 05:23:00 INFO - Downloaded 1199 bytes. 05:23:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:23:00 INFO - Using the following test package requirements: 05:23:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:23:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:23:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:23:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'jsshell-mac.zip'], 05:23:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:23:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:23:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:23:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:23:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:23:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:23:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:23:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:00 INFO - trying https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:23:01 INFO - Downloaded 16330528 bytes. 05:23:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:23:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:23: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')]} 05:23:08 INFO - trying https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:23:08 INFO - Downloading https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:23:10 INFO - Downloaded 16330528 bytes. 05:23:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:16 INFO - trying https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:23:16 INFO - Downloading https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:23:17 INFO - Downloaded 14413719 bytes. 05:23:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:18 INFO - trying https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:23:18 INFO - Downloading https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:23:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 05:23:22 INFO - Downloaded 111177291 bytes. 05:23:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:23:22 INFO - mkdir: /builds/slave/test/properties 05:23:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:22 INFO - Contents: 05:23:22 INFO - build_url:https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:23:23 INFO - Running post-action listener: _resource_record_post_action 05:23:23 INFO - Running post-action listener: set_extra_try_arguments 05:23:23 INFO - [mozharness: 2016-08-15 12:23:23.501350Z] Finished download-and-extract step (success) 05:23:23 INFO - [mozharness: 2016-08-15 12:23:23.501499Z] Running populate-webroot step. 05:23:23 INFO - Running pre-action listener: _resource_record_pre_action 05:23:23 INFO - Running main action method: populate_webroot 05:23:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:23 INFO - '--tppagecycles', 05:23:23 INFO - '7'], 05:23:23 INFO - 'tests': ['tsvgm']}}, 05:23:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['damp', 'tps']}, 05:23:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'tests': ['damp', 'tps']}, 05:23:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['dromaeo_dom']}, 05:23:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['basic_compositor_video']}, 05:23:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['a11yr', 05:23:23 INFO - 'ts_paint', 05:23:23 INFO - 'tpaint', 05:23:23 INFO - 'sessionrestore', 05:23:23 INFO - 'sessionrestore_no_auto_restore', 05:23:23 INFO - 'tabpaint']}, 05:23:23 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:23 INFO - 'ts_paint', 05:23:23 INFO - 'tpaint', 05:23:23 INFO - 'sessionrestore', 05:23:23 INFO - 'sessionrestore_no_auto_restore', 05:23:23 INFO - 'tabpaint']}, 05:23:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:23 INFO - 'ts_paint', 05:23:23 INFO - 'tpaint', 05:23:23 INFO - 'sessionrestore', 05:23:23 INFO - 'sessionrestore_no_auto_restore', 05:23:23 INFO - 'tabpaint']}, 05:23:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:23 INFO - 'ts_paint', 05:23:23 INFO - 'tpaint', 05:23:23 INFO - 'sessionrestore', 05:23:23 INFO - 'sessionrestore_no_auto_restore', 05:23:23 INFO - 'tabpaint']}, 05:23:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['a11yr', 05:23:23 INFO - 'ts_paint', 05:23:23 INFO - 'tpaint', 05:23:23 INFO - 'sessionrestore', 05:23:23 INFO - 'sessionrestore_no_auto_restore', 05:23:23 INFO - 'tabpaint']}, 05:23:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['a11yr', 05:23:23 INFO - 'ts_paint', 05:23:23 INFO - 'tpaint', 05:23:23 INFO - 'sessionrestore', 05:23:23 INFO - 'sessionrestore_no_auto_restore', 05:23:23 INFO - 'tabpaint']}, 05:23:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['tsvgx', 05:23:23 INFO - 'tsvgr_opacity', 05:23:23 INFO - 'tart', 05:23:23 INFO - 'tscrollx', 05:23:23 INFO - 'cart']}, 05:23:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:23 INFO - 'tsvgr_opacity', 05:23:23 INFO - 'tart', 05:23:23 INFO - 'tscrollx', 05:23:23 INFO - 'cart']}, 05:23:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'talos_options': ['--disable-e10s'], 05:23:23 INFO - 'tests': ['tp5o']}, 05:23:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'tests': ['tp5o']}, 05:23:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'talos_options': ['--disable-e10s', 05:23:23 INFO - '--xperf_path', 05:23:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:23 INFO - 'tests': ['tp5n']}, 05:23:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:23 INFO - 'talos_options': ['--xperf_path', 05:23:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:23 INFO - 'tests': ['tp5n']}}, 05:23:23 INFO - 'talos.zip': {'path': '', 05:23:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:23 INFO - Running post-action listener: _resource_record_post_action 05:23:23 INFO - [mozharness: 2016-08-15 12:23:23.520519Z] Finished populate-webroot step (success) 05:23:23 INFO - [mozharness: 2016-08-15 12:23:23.520632Z] Running create-virtualenv step. 05:23:23 INFO - Running pre-action listener: _resource_record_pre_action 05:23:23 INFO - Running main action method: create_virtualenv 05:23:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:23:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:23 INFO - Using real prefix '/tools/python27' 05:23:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:27 INFO - Installing pip.................done. 05:23:27 INFO - Return code: 0 05:23:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:27 INFO - 'HOME': '/Users/cltbld', 05:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:27 INFO - 'LOGNAME': 'cltbld', 05:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:27 INFO - 'MOZ_NO_REMOTE': '1', 05:23:27 INFO - 'NO_EM_RESTART': '1', 05:23:27 INFO - 'PAGER': '/bin/cat', 05:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:27 INFO - 'PWD': '/builds/slave/test', 05:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:27 INFO - 'SHELL': '/bin/bash', 05:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:27 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:27 INFO - 'USER': 'cltbld', 05:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:27 INFO - 'XPC_FLAGS': '0x0', 05:23:27 INFO - 'XPC_SERVICE_NAME': '0', 05:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:23:27 INFO - Cleaning up... 05:23:27 INFO - Return code: 0 05:23:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:27 INFO - 'HOME': '/Users/cltbld', 05:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:27 INFO - 'LOGNAME': 'cltbld', 05:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:27 INFO - 'MOZ_NO_REMOTE': '1', 05:23:27 INFO - 'NO_EM_RESTART': '1', 05:23:27 INFO - 'PAGER': '/bin/cat', 05:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:27 INFO - 'PWD': '/builds/slave/test', 05:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:27 INFO - 'SHELL': '/bin/bash', 05:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:27 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:27 INFO - 'USER': 'cltbld', 05:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:27 INFO - 'XPC_FLAGS': '0x0', 05:23:27 INFO - 'XPC_SERVICE_NAME': '0', 05:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:27 INFO - Downloading/unpacking psutil>=3.1.1 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:31 INFO - Installing collected packages: psutil 05:23:31 INFO - Running setup.py install for psutil 05:23:31 INFO - building 'psutil._psutil_osx' extension 05:23:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:23:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:23:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:23:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:23:32 INFO - building 'psutil._psutil_posix' extension 05:23:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:23:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:23:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:23:32 INFO - ^ 05:23:32 INFO - 1 warning generated. 05:23:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:23:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:32 INFO - Successfully installed psutil 05:23:32 INFO - Cleaning up... 05:23:32 INFO - Return code: 0 05:23:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:32 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:23:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:32 INFO - 'HOME': '/Users/cltbld', 05:23:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:32 INFO - 'LOGNAME': 'cltbld', 05:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:32 INFO - 'MOZ_NO_REMOTE': '1', 05:23:32 INFO - 'NO_EM_RESTART': '1', 05:23:32 INFO - 'PAGER': '/bin/cat', 05:23:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:32 INFO - 'PWD': '/builds/slave/test', 05:23:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:32 INFO - 'SHELL': '/bin/bash', 05:23:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:32 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:32 INFO - 'USER': 'cltbld', 05:23:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:32 INFO - 'XPC_FLAGS': '0x0', 05:23:32 INFO - 'XPC_SERVICE_NAME': '0', 05:23:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:35 INFO - Installing collected packages: mozsystemmonitor 05:23:35 INFO - Running setup.py install for mozsystemmonitor 05:23:35 INFO - Successfully installed mozsystemmonitor 05:23:35 INFO - Cleaning up... 05:23:35 INFO - Return code: 0 05:23:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:35 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:35 INFO - 'HOME': '/Users/cltbld', 05:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:35 INFO - 'LOGNAME': 'cltbld', 05:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:35 INFO - 'MOZ_NO_REMOTE': '1', 05:23:35 INFO - 'NO_EM_RESTART': '1', 05:23:35 INFO - 'PAGER': '/bin/cat', 05:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:35 INFO - 'PWD': '/builds/slave/test', 05:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:35 INFO - 'SHELL': '/bin/bash', 05:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:35 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:35 INFO - 'USER': 'cltbld', 05:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:35 INFO - 'XPC_FLAGS': '0x0', 05:23:35 INFO - 'XPC_SERVICE_NAME': '0', 05:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:35 INFO - Downloading/unpacking jsonschema==2.5.1 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:38 INFO - warning: no files found matching '*.txt' 05:23:38 INFO - no previously-included directories found matching 'build' 05:23:38 INFO - no previously-included directories found matching 'dist' 05:23:38 INFO - no previously-included directories found matching '.git*' 05:23:38 INFO - Installing collected packages: jsonschema, functools32 05:23:38 INFO - Running setup.py install for functools32 05:23:38 INFO - warning: no files found matching '*.txt' 05:23:38 INFO - no previously-included directories found matching 'build' 05:23:38 INFO - no previously-included directories found matching 'dist' 05:23:38 INFO - no previously-included directories found matching '.git*' 05:23:38 INFO - Successfully installed jsonschema functools32 05:23:38 INFO - Cleaning up... 05:23:38 INFO - Return code: 0 05:23:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:38 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:38 INFO - 'HOME': '/Users/cltbld', 05:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:38 INFO - 'LOGNAME': 'cltbld', 05:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:38 INFO - 'MOZ_NO_REMOTE': '1', 05:23:38 INFO - 'NO_EM_RESTART': '1', 05:23:38 INFO - 'PAGER': '/bin/cat', 05:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:38 INFO - 'PWD': '/builds/slave/test', 05:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:38 INFO - 'SHELL': '/bin/bash', 05:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:38 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:38 INFO - 'USER': 'cltbld', 05:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:38 INFO - 'XPC_FLAGS': '0x0', 05:23:38 INFO - 'XPC_SERVICE_NAME': '0', 05:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:23:39 INFO - Cleaning up... 05:23:39 INFO - Return code: 0 05:23:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:39 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:39 INFO - 'HOME': '/Users/cltbld', 05:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:39 INFO - 'LOGNAME': 'cltbld', 05:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:39 INFO - 'MOZ_NO_REMOTE': '1', 05:23:39 INFO - 'NO_EM_RESTART': '1', 05:23:39 INFO - 'PAGER': '/bin/cat', 05:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:39 INFO - 'PWD': '/builds/slave/test', 05:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:39 INFO - 'SHELL': '/bin/bash', 05:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:39 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:39 INFO - 'USER': 'cltbld', 05:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:39 INFO - 'XPC_FLAGS': '0x0', 05:23:39 INFO - 'XPC_SERVICE_NAME': '0', 05:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:39 INFO - Downloading/unpacking blobuploader==1.2.4 05:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:41 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:42 INFO - Downloading docopt-0.6.1.tar.gz 05:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:43 INFO - Running setup.py install for blobuploader 05:23:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:43 INFO - Running setup.py install for requests 05:23:43 INFO - Running setup.py install for docopt 05:23:43 INFO - Successfully installed blobuploader requests docopt 05:23:43 INFO - Cleaning up... 05:23:43 INFO - Return code: 0 05:23:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:43 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:23:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:43 INFO - 'HOME': '/Users/cltbld', 05:23:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:43 INFO - 'LOGNAME': 'cltbld', 05:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:43 INFO - 'MOZ_NO_REMOTE': '1', 05:23:43 INFO - 'NO_EM_RESTART': '1', 05:23:43 INFO - 'PAGER': '/bin/cat', 05:23:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:43 INFO - 'PWD': '/builds/slave/test', 05:23:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:43 INFO - 'SHELL': '/bin/bash', 05:23:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:43 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:43 INFO - 'USER': 'cltbld', 05:23:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:43 INFO - 'XPC_FLAGS': '0x0', 05:23:43 INFO - 'XPC_SERVICE_NAME': '0', 05:23:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-DyCenE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xFi5OE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-i6dH8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-HEKkXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-pCMOfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Tb6Tvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-eeSP_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:44 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-lMDjBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-zz016G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-kmTvjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jvU5p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-c01MNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-3UZbZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-q3OM3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-te6VdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GBuoRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:45 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Owf9Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:45 INFO - Running setup.py install for manifestparser 05:23:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:46 INFO - Running setup.py install for mozcrash 05:23:46 INFO - Running setup.py install for mozdebug 05:23:46 INFO - Running setup.py install for mozdevice 05:23:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:46 INFO - Running setup.py install for mozfile 05:23:46 INFO - Running setup.py install for mozhttpd 05:23:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:46 INFO - Running setup.py install for mozinfo 05:23:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Running setup.py install for mozInstall 05:23:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Running setup.py install for mozleak 05:23:47 INFO - Running setup.py install for mozlog 05:23:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Running setup.py install for moznetwork 05:23:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Running setup.py install for mozprocess 05:23:47 INFO - Running setup.py install for mozprofile 05:23:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:47 INFO - Running setup.py install for mozrunner 05:23:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:48 INFO - Running setup.py install for mozscreenshot 05:23:48 INFO - Running setup.py install for moztest 05:23:48 INFO - Running setup.py install for mozversion 05:23:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:48 INFO - Cleaning up... 05:23:48 INFO - Return code: 0 05:23:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:48 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:48 INFO - 'HOME': '/Users/cltbld', 05:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:48 INFO - 'LOGNAME': 'cltbld', 05:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:48 INFO - 'MOZ_NO_REMOTE': '1', 05:23:48 INFO - 'NO_EM_RESTART': '1', 05:23:48 INFO - 'PAGER': '/bin/cat', 05:23:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:48 INFO - 'PWD': '/builds/slave/test', 05:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:48 INFO - 'SHELL': '/bin/bash', 05:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:48 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:48 INFO - 'USER': 'cltbld', 05:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:48 INFO - 'XPC_FLAGS': '0x0', 05:23:48 INFO - 'XPC_SERVICE_NAME': '0', 05:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-i8qjMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:48 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-qnIEHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-UR9KHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GOWG4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-8pT7H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-M2hmjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-DHLPYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-oaIEUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-IOxgS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-07P9EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:49 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-eXazlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:50 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-cXf38h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:50 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-KjxnRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:50 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ZvnayH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:50 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-h1BrxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:50 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-98lIFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:23:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:50 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-8Uk6re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:23:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:53 INFO - Downloading blessings-1.6.tar.gz 05:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:53 INFO - Installing collected packages: blessings 05:23:53 INFO - Running setup.py install for blessings 05:23:53 INFO - Successfully installed blessings 05:23:53 INFO - Cleaning up... 05:23:53 INFO - Return code: 0 05:23:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:53 INFO - Reading from file tmpfile_stdout 05:23:53 INFO - Current package versions: 05:23:53 INFO - blessings == 1.6 05:23:53 INFO - blobuploader == 1.2.4 05:23:53 INFO - docopt == 0.6.1 05:23:53 INFO - functools32 == 3.2.3-2 05:23:53 INFO - jsonschema == 2.5.1 05:23:53 INFO - manifestparser == 1.1 05:23:53 INFO - mozInstall == 1.12 05:23:53 INFO - mozcrash == 0.17 05:23:53 INFO - mozdebug == 0.1 05:23:53 INFO - mozdevice == 0.48 05:23:53 INFO - mozfile == 1.2 05:23:53 INFO - mozhttpd == 0.7 05:23:53 INFO - mozinfo == 0.9 05:23:53 INFO - mozleak == 0.1 05:23:53 INFO - mozlog == 3.3 05:23:53 INFO - moznetwork == 0.27 05:23:53 INFO - mozprocess == 0.23 05:23:53 INFO - mozprofile == 0.28 05:23:53 INFO - mozrunner == 6.12 05:23:53 INFO - mozscreenshot == 0.1 05:23:53 INFO - mozsystemmonitor == 0.3 05:23:53 INFO - moztest == 0.7 05:23:53 INFO - mozversion == 1.4 05:23:53 INFO - psutil == 3.1.1 05:23:53 INFO - requests == 1.2.3 05:23:53 INFO - wsgiref == 0.1.2 05:23:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:53 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:23:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:53 INFO - 'HOME': '/Users/cltbld', 05:23:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:53 INFO - 'LOGNAME': 'cltbld', 05:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:53 INFO - 'MOZ_NO_REMOTE': '1', 05:23:53 INFO - 'NO_EM_RESTART': '1', 05:23:53 INFO - 'PAGER': '/bin/cat', 05:23:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:53 INFO - 'PWD': '/builds/slave/test', 05:23:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:53 INFO - 'SHELL': '/bin/bash', 05:23:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:53 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:53 INFO - 'USER': 'cltbld', 05:23:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:53 INFO - 'XPC_FLAGS': '0x0', 05:23:53 INFO - 'XPC_SERVICE_NAME': '0', 05:23:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:23:54 INFO - Cleaning up... 05:23:54 INFO - Return code: 0 05:23:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:54 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 0x10222ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022c0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e1bcbf1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, '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.CtLvVYBaXO/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.W60qCjxZcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:23:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:23:54 INFO - 'HOME': '/Users/cltbld', 05:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:23:54 INFO - 'LOGNAME': 'cltbld', 05:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:54 INFO - 'MOZ_NO_REMOTE': '1', 05:23:54 INFO - 'NO_EM_RESTART': '1', 05:23:54 INFO - 'PAGER': '/bin/cat', 05:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:54 INFO - 'PWD': '/builds/slave/test', 05:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:23:54 INFO - 'SHELL': '/bin/bash', 05:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:23:54 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:23:54 INFO - 'USER': 'cltbld', 05:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:54 INFO - 'XPC_FLAGS': '0x0', 05:23:54 INFO - 'XPC_SERVICE_NAME': '0', 05:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:54 INFO - Cleaning up... 05:23:54 INFO - Return code: 0 05:23:54 INFO - Running post-action listener: _resource_record_post_action 05:23:54 INFO - Running post-action listener: _start_resource_monitoring 05:23:54 INFO - Starting resource monitoring. 05:23:54 INFO - [mozharness: 2016-08-15 12:23:54.364064Z] Finished create-virtualenv step (success) 05:23:54 INFO - [mozharness: 2016-08-15 12:23:54.365110Z] Running install step. 05:23:54 INFO - Running pre-action listener: _resource_record_pre_action 05:23:54 INFO - Running main action method: install 05:23:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:54 INFO - Reading from file tmpfile_stdout 05:23:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:54 INFO - Reading from file tmpfile_stdout 05:23:54 INFO - Output received: 05:23:54 INFO - Usage: mozinstall [options] installer 05:23:54 INFO - Options: 05:23:54 INFO - -h, --help show this help message and exit 05:23:54 INFO - -d DEST, --destination=DEST 05:23:54 INFO - Directory to install application into. [default: 05:23:54 INFO - "/builds/slave/test"] 05:23:54 INFO - --app=APP Application being installed. [default: firefox] 05:23:54 INFO - mkdir: /builds/slave/test/build/application 05:23:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:23:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:24:28 INFO - Reading from file tmpfile_stdout 05:24:28 INFO - Output received: 05:24:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:24:28 INFO - Running post-action listener: _resource_record_post_action 05:24:28 INFO - [mozharness: 2016-08-15 12:24:28.939027Z] Finished install step (success) 05:24:28 INFO - [mozharness: 2016-08-15 12:24:28.939177Z] Running run-tests step. 05:24:28 INFO - Running pre-action listener: _resource_record_pre_action 05:24:28 INFO - Running main action method: run_tests 05:24:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:28 INFO - 2016-08-15 05:24:28.954 screenresolution[1679:13227] starting screenresolution argv=screenresolution get 05:24:28 INFO - 2016-08-15 05:24:28.956 screenresolution[1679:13227] Display 0: 1600x1200x32@60 05:24:28 INFO - 2016-08-15 05:24:28.962 screenresolution[1680:13229] starting screenresolution argv=screenresolution list 05:24:28 INFO - Available Modes on Display 0 05:24:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:29 INFO - Intel Iris: 05:24:29 INFO - Chipset Model: Intel Iris 05:24:29 INFO - Type: GPU 05:24:29 INFO - Bus: Built-In 05:24:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:29 INFO - Vendor: Intel (0x8086) 05:24:29 INFO - Device ID: 0x0a2e 05:24:29 INFO - Revision ID: 0x0009 05:24:29 INFO - Displays: 05:24:29 INFO - Display: 05:24:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:29 INFO - Display Serial Number: 1600x1200 60 05:24:29 INFO - Main Display: Yes 05:24:29 INFO - Mirror: Off 05:24:29 INFO - Online: Yes 05:24:29 INFO - Rotation: Supported 05:24:29 INFO - Return code: 0 05:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:29 INFO - Python 2.7.3 05:24:29 INFO - Return code: 0 05:24:29 INFO - grabbing minidump binary from tooltool 05:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:24:29 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 0x10218fd30>, '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 0x101ef4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102187b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:24:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:24:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:24:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:29 INFO - Return code: 0 05:24:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:24:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:24:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:29 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:24:29 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtLvVYBaXO/Render', 05:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:29 INFO - 'HOME': '/Users/cltbld', 05:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:29 INFO - 'LOGNAME': 'cltbld', 05:24:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:29 INFO - 'MOZ_NO_REMOTE': '1', 05:24:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:29 INFO - 'NO_EM_RESTART': '1', 05:24:29 INFO - 'PAGER': '/bin/cat', 05:24:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:29 INFO - 'PWD': '/builds/slave/test', 05:24:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:29 INFO - 'SHELL': '/bin/bash', 05:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W60qCjxZcs/Listeners', 05:24:29 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 05:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:29 INFO - 'USER': 'cltbld', 05:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:29 INFO - 'XPC_FLAGS': '0x0', 05:24:29 INFO - 'XPC_SERVICE_NAME': '0', 05:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:29 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:24:29 INFO - mozversion application_buildid: 20160815030201 05:24:29 INFO - mozversion application_changeset: 6e191a55c3d23e83e6a2e72e4e80c1dc21516493 05:24:29 INFO - mozversion application_display_name: Nightly 05:24:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:29 INFO - mozversion application_name: Firefox 05:24:29 INFO - mozversion application_remotingname: firefox 05:24:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:24:29 INFO - mozversion application_vendor: Mozilla 05:24:29 INFO - mozversion application_version: 51.0a1 05:24:29 INFO - mozversion platform_buildid: 20160815030201 05:24:29 INFO - mozversion platform_changeset: 6e191a55c3d23e83e6a2e72e4e80c1dc21516493 05:24:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:24:29 INFO - mozversion platform_version: 51.0a1 05:24:29 INFO - using testdate: 1471263869 05:24:29 INFO - actual date: 1471263869 05:24:29 INFO - starting webserver on 'localhost:49269' 05:24:29 INFO - SUITE-START | Running 2 tests 05:24:29 INFO - TEST-START | dromaeo_css 05:24:29 INFO - operating with platform_type : mac_ 05:24:29 INFO - Initialising browser for dromaeo_css test... 05:24:29 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpaeEaBB/profile http://localhost:49269/getInfo.html) 05:24:32 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 05:24:32 INFO - PROCESS | 1690 | colorDepth:24 05:24:32 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 05:24:32 INFO - PROCESS | 1690 | __metrics 05:24:32 INFO - PROCESS | 1690 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:24:32 INFO - PROCESS | 1690 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:33 INFO - TEST-INFO | 1690: exit 0 05:24:33 INFO - Browser initialized. 05:24:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:33 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpaeEaBB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:45 INFO - PROCESS | 1694 | RSS: Main: 228442112 05:24:45 INFO - PROCESS | 1694 | 05:25:36 INFO - PROCESS | 1694 | 1471263936309 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:27:46 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 05:27:47 INFO - PROCESS | 1694 | RSS: Main: 339832832 05:27:47 INFO - PROCESS | 1694 | 05:30:50 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 05:30:51 INFO - PROCESS | 1694 | RSS: Main: 368779264 05:30:51 INFO - PROCESS | 1694 | 05:33:53 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 05:33:54 INFO - PROCESS | 1694 | RSS: Main: 359858176 05:33:54 INFO - PROCESS | 1694 | 05:36:56 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 05:36:57 INFO - PROCESS | 1694 | RSS: Main: 338296832 05:36:57 INFO - PROCESS | 1694 | 05:40:00 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 05:40:01 INFO - PROCESS | 1694 | RSS: Main: 331841536 05:40:01 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 05:43:04 INFO - PROCESS | 1694 | RSS: Main: 525139968 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | __start_tp_report 05:43:04 INFO - PROCESS | 1694 | _x_x_mozilla_page_load 05:43:04 INFO - PROCESS | 1694 | _x_x_mozilla_page_load_details 05:43:04 INFO - PROCESS | 1694 | |i|pagename|runs| 05:43:04 INFO - PROCESS | 1694 | |0;dojo.html;2876.3528206153615;3196.5205219217128;3225.693559111885;3239.1740106272896;3243.6107667079955;5176.120059589869;5253.395859476157;5299.205119232117;5324.148136298189;5342.786288548454;5263.49996750309;5280.867978300545;5303.363596368432;5311.973440132803;5336.493033161843;4869.780859861297;4905.288733133693;4917.72952487613;4921.704697718131;4981.452040275567;4831.734254615994;4917.631177661689;4956.776945037482;4964.230544265626;5006.199008158677;4985.675931064469;4998.3252260944855;4999.775055111503;5000;5002;335.19328172656316;4921.532454416824;4989.825356112518;5015.022070696215;5039.294498770175;4896.877578060541;4907.993861258434;4909.410870695539;4931;4939.160342741742;4778.235482322812;4929.827456039057;5330.1205301125265;5332.066888294533;5385;5588.748506317225;5629.971850140723;5640.971795141039;5797.159411885253;5812.534997200256;5737.9713101434645;5796.043652797283;5800.564957628195;5802.12968054791;5804.013317735995;5394;5410.621256512046;5435.510804027656;5467.20725494809;5474.753636086425;5054.418741844662;6064.787732429344;6068.059450785136;6078.483328917092;6081.513478921675;5683.119116536944;5709.143628455765;5715.571332150105;5747.770089196469;5753.395893431213;5657.858553536194;5703.885922281531;5863.384344643836;5907.2911250650195;5908.172855800191;5634.7182640867795;5696.316442026983;5698.601097923189;5728.226689396878;5756.827295181151;4779.832705855279;5719.999000174953;5733.168690539848;5740.368559458456;5744.827655170351;4552.954470455319;4651.348811166439;4665.370175026396;4666;4713.835015774432;4124.628783409507;4143.482064741907;4174.20690068886;4198.5591512891315;4224.683148763855;5358.624896257303;5359.08895487768;5371.57027437804;5381.811636592701;5411.485908838654;5842.707864606753;5843.152742852262;5844.444777746107;5851.853703657357;5865;5229.320188375486;5281.762320695578;5291.576673866081;5321.787128514816;5355.357357117171;5067.5185857343495;5080.898382032338;5084.3644544431945;5095.923561146586;5107.438181799999;6008.699565021731;6041.969790151109;6057.242844644419;6066.120412540157;6081.087836824512;5559.861003474945;5698.572607054571;5724.112762521816;5790.4209579043445;5791.797287094932;6053.455189032832;6073.939260607514;6082.878342433215;6092.847678808065;6128.724207410766;5697.57268204878;5699.5155411790465;5702.6293290935955;5721.141828725725;5726.713664316851;5599.748011339581;5720.227769251264;5735.655860648374;5759.625624334405;5805.071188609802;5584.218209450599;5640.32316122068;5655.123455864347;5659.915101273402;5704.1443783432815;5623.775048997994;5630.915536267041;5633.042382795016;5646.2942132233475;5652.519535839499;4760.285957106462;5359.088954877758;5424.430434804446;5531.668099914018;5615.185798059177;5616.747246374005;5629.746661400329;5635.323761148688;5674.404187560412;5675.148727690715;5501.119820828648;5522.475364840334;5530.668159910419;5536.280283563111;5558.277423934863 05:43:04 INFO - PROCESS | 1694 | |1;ext.html;443.2287819194601;471.4201532115496;486.2390359088027;517.932668753062;534.8609361565995;30753.692463075364;32401;32433.837830810844;39855.00362490939;41323.79338103305;42266.78866605663;42779.572204277945;42825;42865;42927.570724292746;8371.623276952536;9899.257555683316;9916.256280778935;9916.950415247913;9921.553530091127;7557.2820582044615;9062.546872656374;9072.047435019327;9793.26550508711;9929.2056635469;9002.684906028291;9060.592273347682;11935;12147.331896745676;12190.512379504853;12084.758304833898;12150.270983741004;12150.756984860252;12186.51253949841;12237.204581702206;11900;11985.700357491045;11994.100442466763;11996.580119695856;12000.759984800256;39877.80366589007;39952;40022.19955600901;40196;40251.59748402537;3377.5609170807884;3446.8104254266004;3468.9826550867083;3474.3398754236614;3565.2156525564333;30411.543826842615;31487.897923572793;31516;31583.52624710631;31882.521762173586;2758.1311886755807;2759.4067275535654;2784.8468334241606;2800.411913498168;2831.5327970884778;461942;471678.7569429525;474521;474529;475748;58237.41762582405;58584.70707646434;58634.7068264656;58644;58681.70659146762;61148;61666.76666466646;61680.383196167466;61811.90940452959;62016.06975895365;16194.676106477804;16461.423850165185;16476.928999614986;16488.505344839676;16522.2564984576;11644.2431241969;11893.821592676117;11926.284422934652;12069.879301207051;12087.87912120885;14237.827933220733;18953.43139705811;19184.13671384791;19604.411867643994;19646.11592478342;6696.732130714814;7843.745000799845;8061.2744852963515;8237.341012718967;8287.8342433151;731.9011933388924;803.8472690188845;967.811276801017;1026.697124348319;1069.8127827630133;27586.724132758816;28570.571441428394;28689;28865.422691546053;30624.741538661172;1075.8009768192753;1095.370162156747;1134.4838098665252;1137.4824454873012;1218.0925210717894;12552.686182845502;15213.847861521464;15367.923160384351;15749.370025199092;16473;687057;692334;692927;693483;694660;59693;59925;59955;60023.09965350523;60139;1202.5430336472084;1324.1856258401015;1333.8065980432782;1362.7070179911366;1362.7274545090822;27767.444651107267;28633.856830715715;31193.37613247768;31501.054968350985;31971.040868773973;49306;50092.99814003772;50121.49878501168;50174;51101;564077;564707;565790;566920;567294;26118.34704132412;26536.867315663298;26811.865940670174;26812.463750725266;43124.35313470363;45865.082698346516;46219.53780462153;46607.766961164976;46735;47009;33238.66761332356;37775.62224377721;38113.61886381212;39290.60709392984;39647.80176099101;33101.503477448336;34597;34708.305833882674;38230;39423.014424639616;36448.45327320039;37548;38266.808665956494;38513.80743096267;40410.78767636976;30674.693253067184;31089.378212436077;33348.66651333456;35105.82447087748;35361.64638353686;24515.63226551639;25426.491470170124;25766.87116564405;25817.483650327264;26698.466030679665 05:43:04 INFO - PROCESS | 1694 | |2;jquery.html;315.8925965171842;351.9401701710709;368.77873276034364;377.79787813519766;402.137415244301;15866.048037117767;17492.912535437335;20645.03872132145;21297.25459608914;21455.249066282664;21063;21092.472688182803;21663.67504487434;21754.238601648907;21907.233246836324;5140.820071297505;5760.279965004364;5947.256592925884;6029.155918171459;6033.69831508425;5883.88232235355;5992.460678538931;5995.13070604762;6015.548833837458;6016.157737916695;5710.1434784782405;5953.553483488734;5966.2840459144745;5977.013792724195;6044.96977515114;6038.909416358758;6077.513798896078;6103.725332360054;6104.694765261764;6115.021596544531;5828.2131912191735;5866.530677545828;5929.199558059693;5930.080837470199;5931.822045338647;13407.731845363136;13470.595882123553;13516.918646508257;13680.589582312447;13731.519396821162;2087.645100332947;2089.174775963501;2186.529896072336;2364.5152743687504;2649.5495765719875;9867.309288349818;10047.849282260771;10093.646722364756;10531.052205301483;10573;1788.874778765487;1806.3226290141197;1840.2179073893542;1904.352520143158;1931.652302585534;86747;96492.51753741257;120487.79512204854;121325.39337303257;121715.39142304233;11012.724681882857;11265.887341126483;11414.801445848232;11448.656540303804;11699.766004680028;12581.74836503283;14340.56978290653;14381.6404589884;14381.78427323591;14394.712105757826;8396.07643159252;8522.190391912758;8524.275436587835;8584.098669639723;8676.305895528343;8460.78848028792;8500.70247541333;8632.654693812303;8667.523286219319;8673.089325620718;4373.737575745466;4468.676288330115;4488.105172163936;4498.305736819536;4586.954130458719;1779.8932064076198;1887.9716804247948;1888.8111188881276;1892.7255547945747;1925.2299080367684;835;852.6376290076693;919.6413398774483;940.4498368454394;1024.964785566583;21247.575048498944;21973.560528789334;22046.669299960515;22079.33761987143;22137;899.1053456709006;906.40177482861;915.3272344826547;929.5166513412987;938.0806809326898;11073.280236784743;11160.772315045338;11449.48477318506;11457.656270311903;11546.884531154748;511543;539921;540319;540576;544516;93516;104571.82478911825;116588;120421.39789301173;133521.66478335287;898.501331760879;938.7043081429327;993.801239752038;1000.979980400388;1003.7992401519725;19393.61212775765;20196.014309639468;20261.885596292344;20412.18351265933;20483.795162048784;41544.37683434811;45829.54170458253;56487;57112.42887571237;57820.421795783186;78306;79085.60457197677;81597;84162;87977.56011219903;7878.172791856772;7903.3677305816545;8174.468659537111;8179.1411901751835;8448.943882014748;11009.724756881142;12854.614361569167;12894.419751111034;13425.66435839112;13559.79660305116;9453.385529940531;11064.502097405475;11398.316101033965;11543.653690389301;11746.882531174579;11096.61161859331;11185.049270811747;11470.483828227583;11625.534978600761;11800.940995294968;11317.094632429551;11913.880861191277;12062.939685301868;12077.75844483123;12078.396080196131;7140.750073747602;8020.478668886503;8031.397645176752;8112.1076681566165;8280.544570048705;8293.502389856629;10945.671629851116;11258.099352051653;11391.869773087805;11628.94185529067 05:43:04 INFO - PROCESS | 1694 | |3;mootools.html;1480.1637075052595;1515.469585645024;1653.2395098254801;1777.8311060449253;1811.7282407638852;8344.666213351466;8893.421927574704;8893.95553022234;8906.153915378038;8918.554072296383;7773.494722843011;8008.7597372078935;8354.373421993345;8563.40056196065;8660.393772435931;2329.4525786440217;2354.693889794324;2355.6231003039516;2356.257778799681;2363.3855197648563;1982.3458258774642;2337.4390146364835;2356.3048900574286;2385.5705972924866;2387.7254115776645;2339.6373562097815;2361.067378385537;2372.1460274301326;2374.252110585169;2381.833271670984;2332.6267797152545;2345.179187284445;2363.409147713072;2368.6091794854;2369.9763002369928;2345.7419683834764;2348.9647655285185;2349.77677120675;2350.200931683236;2362.161432691404;8697.608607612672;8758.211760941545;8798.51608161551;8808.207261346446;8852.247558957497;5128.666636668605;5178.40448348441;5285.49787770165;5304.469553044664;5307.150855863082;3434.8797792077407;3525.7531972761917;3614.7288953328607;3619.7104231661406;3637.909052273688;1068.631322193842;1108.1024370260034;1109.7447587055024;1125.482278152051;1128.5824245029391;11531.13516486267;11598.420078996101;11606.4196790161;11631.709207269801;11631.883681163165;4483.14820184164;4580.213716650509;4727.787249573777;4801.279808028824;4803.255495398149;9762;9764.267679924034;9774.657886973922;9790;9796.951015244878;5302.575793936476;5311.787528498894;5311.867203319948;5313.734313284321;5326.041312563698;3940.3695408734466;4394.560543945644;4564.863054108382;4838.661293709407;4900;3799.2021675448464;3839.1170030892495;3912.921741565153;3957.5053118360206;3966;1266.8163116348078;1279.3475327583;1283.1146508908823;1292.6315999939973;1297.7209899871384;820.2044017303207;854.9102344253888;910.5811326789618;922.898481167071;953.4565297780338;5272.077386457355;5757;5813.7383817728305;5957;5990.760369585255;346.63430081264005;430.23633051334133;444.14502083489157;445.2719803121919;467.04489319342025;3911.5501717302304;3949.624785645359;3954.426608141803;4071.857484988011;4074.8166332515107;9904.653337133166;10500.737481563023;10558.736031599272;10608.098311643442;10634.46827658614;10291.022352876465;10589.099926506333;10634.149268058536;10635.202359823044;10658.893411065945;7999.120096789348;8215.548144851971;8216.794580135544;8226.30076443509;8233.259006689306;5757.424257574209;5858.062709966385;5921.970390148022;5957.702114894325;6015.729292181775;9865.556049977624;9885.950570247103;9922.900770992197;9924.900750992398;9955.004499549987;9623.759406014906;9935.950320248352;9970.40177589347;10106.090451859369;10126.392416455037;6884.449244060564;7077.858442831218;7250.383717384081;7383.778486645409;7548.282913123245;8020.799480013046;8342.040665323322;8356.206160414751;8364.247217750431;8412.116727743498;7370.705171793283;7447.404207663482;7508.887366689395;7529.3223609873185;7643.503172293783;7284.417246620364;7317.085364329459;7336.37640800538;7485.326320631169;7511.3615342696485;6803.9319606805275;6965.477589180933;6974.19796723363;6976.5814051157095;6983.022376867344;4634.2585186370015;4751.429828420612;4948.604111670986;5022.949770502249;5043.596512278936;4915.877103072309;4989.675671081368;5001.599872010303;5048.217526283285;5112.872178195575 05:43:04 INFO - PROCESS | 1694 | |4;prototype.html;251.9193857965451;294.46260574451634;306.5218259515157;310.9970345635326;312.9624445066592;5036.3200967869325;5346.518813306807;5443.8094666686675;5482.890342193154;5498.890022199554;4371.535535595579;4467.843625473101;5062.202917964125;5309.681419114856;5404;4004.3793212052105;4030.778307193103;4053.7770422626745;4068.471098757172;4089.7546147231114;4020.6582440492593;4042.191561687674;4056.8580099696514;4064.800883739289;4091.713580049398;3508.4386498160297;3678.65052819983;4038.2731108400476;4058.817353219112;4079.5512493625824;4019.2966230909474;4025.9597404025876;4038.899027524306;4064.2887494688607;4073.2668119738437;3468.271662950783;3477.3045390921916;3480.5301284326797;3520.6303338149637;3529.400001999666;4759.286107083931;5003.249512573107;5268.4994925482015;5310.468953104697;5316.421535555252;4210.978945105286;4570.520095390003;4611.331356953223;4629.55562659847;4655.4878963314;5241.135212689902;5312.840614781563;5620.662760234399;5662.433756624346;5681.488666020036;3848.768394113885;3850.3646898261754;3858.202802967854;3860.806959652034;3862.9613703862883;4875.416029504368;5207.0366982108435;5324.121519949165;5878.147668588073;5881.823545293648;3514.982425087909;3728.5898551159353;3830.616938306147;3838.500994870649;3862.517185351831;3298;3885.7862817545306;3939.468171796828;3947.1513624570857;3958.9208215835524;3897.395903634941;4123.731957422758;4599.724016559017;4635.814567417266;4654.813807447732;4715.882102947454;5434.0112937884005;5620.718964051781;5661.915071273934;5778.451047150514;991.6727479931606;1166.2419427372276;1265.2598230035348;1302.4008955880402;1343.9932800335935;747.7083937264471;760.6196901549225;820.4502983001404;834.7662654456761;892.3173772064376;4408.691391602557;5240.3973543042275;5247.763850626731;5263.289455923401;5306.52241298285;5716.657000579979;5733.426657334317;5737.881113182889;5788.88422231553;5873;4618.282282762341;5048.485454363677;5183.9632073585435;5188.662736922088;5241.764120614582;2378.619420892649;2475.6905386826647;2520.3447103752787;3033.9696603034126;3037.559553864677;5587.552995760329;5802.535797136304;5914.615549989323;5953.76184952597;5955.612885162451;5971.850703732442;6011.44513581726;6011.977963746173;6028.487578555784;6036.577439579188;6042.577019608585;6053.213082299273;6054.670910299069;6059.48494377974;6062.242219722535;4918.704877707349;5562.137868630369;5810.186573879745;5849.268841394825;5861.267341582302;4731.787069581808;5260.789568417374;5754.4533269339345;5794.739236734273;5833.008388573868;4517.480489743722;5620.381758006534;5721.112033029173;6027.035674292092;6138.355472675305;2914.4171165767357;3800.5059342285326;3871.8838434847;3893.493845800141;3925.135560608688;5337.759800809051;5820.447057529697;5883.823235353032;5898.469137777621;5947.434993675594;5508.504234618904;5514.61397702157;5602.215689803349;5768.826935191951;5770.8845823082465;5661.773529058792;5674.74463649145;5711.457411545896;5711.514521265739;5753.338366087954;5324.041672498987;5500.3949565548555;5588.916166257591;5637.238972738792;5668.574856885833;4677.415323084614;5646.689432081111;5698;5725.312962444534;5775.537956963517;5542.8337149885565;5571.9721401392735;5574.386817449996;5593.231350481537;5596.356419011866 05:43:04 INFO - PROCESS | 1694 | |5;yui.html;709.1915216653016;727.9053723016004;776.3982913242237;784.211867073591;795.7175202803004;14856.92571537142;16161;16172.919135404336;16191.271392787323;16192.75710864338;13522.053456258065;13547.59357219285;13553.796693049611;13562.660933476644;13964.44142234312;676.7090151234967;698.7659134190052;830.0703212402118;846.9872951905726;868.6395146014396;846.8814365988767;853.995730021349;862.1034124510502;864.0365991919028;867.418829384314;767.8387538616897;917.2982668258787;922.7231830450871;923.861420786884;925.3013974449315;917.7705573606598;918.3158546882548;920.5305294299889;921.0789210789211;924.916757491829;528.875714207161;544.7494152689767;544.7657507271872;546.51906322436;547.654977364258;13732.038757286993;13942;13946.02377833552;13980.951428642791;13986.93006534971;14237.145771253654;14693;14712.926435367755;14764;14876.925615371854;12034.879651203464;12055.578054768128;12083.154179207459;12251.326177060258;12324.5070197192;3857.8071096445065;3887.766733995941;3920.176762879799;3947.5460322062754;4042.636162745367;112774.87225127725;115435.42282288619;116458;116595.41702291435;117868;9825.459599722084;10412.32319899204;11290.661280161608;11310.09519238459;11405.771884562262;22857.657135142654;22888.771112288665;22979.655305170436;23036.884815575813;23238;6226.750929962841;7320.304571065847;7403.333699967029;7453.105627324648;7631.046119235096;4398.736075835396;4477.619402350771;4511.977440112779;4639.37368455261;4642.9535704643195;3549.5208146900354;3673.081729567608;3680.650338217865;3681.1533347330264;3694.9445758313645;651.6090345792582;653.2226650286144;663.8008597420754;681.1383599746325;681.9965900170467;579.4553120067123;609.7195290166574;645.9967700161469;651.8435575461855;666.4568376772914;8962.417442866194;9288.628454861864;9345.813083738423;9348.626054957726;9803.852942205873;633.3160186998031;635.0093853588417;642.8200103970896;659.9836252171602;678.7115475922714;4362.585554372329;4621.6764826462495;4628.4445866496235;4681.438227412732;4714.15145273854;142164.67100124847;143769;144832;146051.2697436506;151668;121923;123326;125589;125848;130278;109301.45349273202;110878.89121108847;112816;113020;115913;8631.525266110424;8929.955350223207;8957.955210223907;8970.981529155888;9175.678851240174;60039.69980150071;61692.38307616866;62997.37002629915;63934;63940;86128;93491;94332.52833735787;94851.52574237085;96463.51768241114;10337.74155646115;11682.24065435744;12119.091068169746;12394.566190183661;12595;10926.672199834018;12142.271463712206;12354.25874447536;12666.809997849856;12787;11645.41772911368;11829.349385783864;12274.631761047183;12454.750904982031;12526.937365313479;11955.462004209658;12014.279143251433;12188.20776649515;12753.8086928698;13389.732205356033;13262.602121936357;13489.123206991515;13755.31223438844;14094.295285235903;14125.717485650435;10429.791404172025;11160.832587511031;11286.774264514499;11294.265872718248;11389.373584452604;9643.807123857623;10406.323588966692;11199.53725865644;11313.094952403953;11403.315801051964 05:43:04 INFO - PROCESS | 1694 | __end_tp_report 05:43:04 INFO - PROCESS | 1694 | __start_cc_report 05:43:04 INFO - PROCESS | 1694 | _x_x_mozilla_cycle_collect,1083 05:43:04 INFO - PROCESS | 1694 | __end_cc_report 05:43:04 INFO - PROCESS | 1694 | __startTimestamp1471264984865__endTimestamp 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | ------- Summary: start ------- 05:43:04 INFO - PROCESS | 1694 | Number of tests: 6 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | [#0] dojo.html Cycles:165 Average:5327.12 Median:5530.67 stddev:702.95 (12.7%) stddev-sans-first:678.30 05:43:04 INFO - PROCESS | 1694 | Values: 2876.4 3196.5 3225.7 3239.2 3243.6 5176.1 5253.4 5299.2 5324.1 5342.8 5263.5 5280.9 5303.4 5312.0 5336.5 4869.8 4905.3 4917.7 4921.7 4981.5 4831.7 4917.6 4956.8 4964.2 5006.2 4985.7 4998.3 4999.8 5000.0 5002.0 335.2 4921.5 4989.8 5015.0 5039.3 4896.9 4908.0 4909.4 4931.0 4939.2 4778.2 4929.8 5330.1 5332.1 5385.0 5588.7 5630.0 5641.0 5797.2 5812.5 5738.0 5796.0 5800.6 5802.1 5804.0 5394.0 5410.6 5435.5 5467.2 5474.8 5054.4 6064.8 6068.1 6078.5 6081.5 5683.1 5709.1 5715.6 5747.8 5753.4 5657.9 5703.9 5863.4 5907.3 5908.2 5634.7 5696.3 5698.6 5728.2 5756.8 4779.8 5720.0 5733.2 5740.4 5744.8 4553.0 4651.3 4665.4 4666.0 4713.8 4124.6 4143.5 4174.2 4198.6 4224.7 5358.6 5359.1 5371.6 5381.8 5411.5 5842.7 5843.2 5844.4 5851.9 5865.0 5229.3 5281.8 5291.6 5321.8 5355.4 5067.5 5080.9 5084.4 5095.9 5107.4 6008.7 6042.0 6057.2 6066.1 6081.1 5559.9 5698.6 5724.1 5790.4 5791.8 6053.5 6073.9 6082.9 6092.8 6128.7 5697.6 5699.5 5702.6 5721.1 5726.7 5599.7 5720.2 5735.7 5759.6 5805.1 5584.2 5640.3 5655.1 5659.9 5704.1 5623.8 5630.9 5633.0 5646.3 5652.5 4760.3 5359.1 5424.4 5531.7 5615.2 5616.7 5629.7 5635.3 5674.4 5675.1 5501.1 5522.5 5530.7 5536.3 5558.3 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | [#1] ext.html Cycles:180 Average:70801.03 Median:20006.83 stddev:156123.21 (780.3%) stddev-sans-first:156471.81 05:43:04 INFO - PROCESS | 1694 | Values: 443.2 471.4 486.2 517.9 534.9 30753.7 32401.0 32433.8 39855.0 41323.8 42266.8 42779.6 42825.0 42865.0 42927.6 8371.6 9899.3 9916.3 9917.0 9921.6 7557.3 9062.5 9072.0 9793.3 9929.2 9002.7 9060.6 11935.0 12147.3 12190.5 12084.8 12150.3 12150.8 12186.5 12237.2 11900.0 11985.7 11994.1 11996.6 12000.8 39877.8 39952.0 40022.2 40196.0 40251.6 3377.6 3446.8 3469.0 3474.3 3565.2 30411.5 31487.9 31516.0 31583.5 31882.5 2758.1 2759.4 2784.8 2800.4 2831.5 461942.0 471678.8 474521.0 474529.0 475748.0 58237.4 58584.7 58634.7 58644.0 58681.7 61148.0 61666.8 61680.4 61811.9 62016.1 16194.7 16461.4 16476.9 16488.5 16522.3 11644.2 11893.8 11926.3 12069.9 12087.9 14237.8 18953.4 19184.1 19604.4 19646.1 6696.7 7843.7 8061.3 8237.3 8287.8 731.9 803.8 967.8 1026.7 1069.8 27586.7 28570.6 28689.0 28865.4 30624.7 1075.8 1095.4 1134.5 1137.5 1218.1 12552.7 15213.8 15367.9 15749.4 16473.0 687057.0 692334.0 692927.0 693483.0 694660.0 59693.0 59925.0 59955.0 60023.1 60139.0 1202.5 1324.2 1333.8 1362.7 1362.7 27767.4 28633.9 31193.4 31501.1 31971.0 49306.0 50093.0 50121.5 50174.0 51101.0 564077.0 564707.0 565790.0 566920.0 567294.0 26118.3 26536.9 26811.9 26812.5 43124.4 45865.1 46219.5 46607.8 46735.0 47009.0 33238.7 37775.6 38113.6 39290.6 39647.8 33101.5 34597.0 34708.3 38230.0 39423.0 36448.5 37548.0 38266.8 38513.8 40410.8 30674.7 31089.4 33348.7 35105.8 35361.6 24515.6 25426.5 25766.9 25817.5 26698.5 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | [#2] jquery.html Cycles:180 Average:32504.07 Median:2507.03 stddev:89562.04 (3572.4%) stddev-sans-first:89780.67 05:43:04 INFO - PROCESS | 1694 | Values: 315.9 351.9 368.8 377.8 402.1 15866.0 17492.9 20645.0 21297.3 21455.2 21063.0 21092.5 21663.7 21754.2 21907.2 5140.8 5760.3 5947.3 6029.2 6033.7 5883.9 5992.5 5995.1 6015.5 6016.2 5710.1 5953.6 5966.3 5977.0 6045.0 6038.9 6077.5 6103.7 6104.7 6115.0 5828.2 5866.5 5929.2 5930.1 5931.8 13407.7 13470.6 13516.9 13680.6 13731.5 2087.6 2089.2 2186.5 2364.5 2649.5 9867.3 10047.8 10093.6 10531.1 10573.0 1788.9 1806.3 1840.2 1904.4 1931.7 86747.0 96492.5 120487.8 121325.4 121715.4 11012.7 11265.9 11414.8 11448.7 11699.8 12581.7 14340.6 14381.6 14381.8 14394.7 8396.1 8522.2 8524.3 8584.1 8676.3 8460.8 8500.7 8632.7 8667.5 8673.1 4373.7 4468.7 4488.1 4498.3 4587.0 1779.9 1888.0 1888.8 1892.7 1925.2 835.0 852.6 919.6 940.4 1025.0 21247.6 21973.6 22046.7 22079.3 22137.0 899.1 906.4 915.3 929.5 938.1 11073.3 11160.8 11449.5 11457.7 11546.9 511543.0 539921.0 540319.0 540576.0 544516.0 93516.0 104571.8 116588.0 120421.4 133521.7 898.5 938.7 993.8 1001.0 1003.8 19393.6 20196.0 20261.9 20412.2 20483.8 41544.4 45829.5 56487.0 57112.4 57820.4 78306.0 79085.6 81597.0 84162.0 87977.6 7878.2 7903.4 8174.5 8179.1 8448.9 11009.7 12854.6 12894.4 13425.7 13559.8 9453.4 11064.5 11398.3 11543.7 11746.9 11096.6 11185.0 11470.5 11625.5 11800.9 11317.1 11913.9 12062.9 12077.8 12078.4 7140.8 8020.5 8031.4 8112.1 8280.5 8293.5 10945.7 11258.1 11391.9 11628.9 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | [#3] mootools.html Cycles:180 Average:5651.85 Median:4995.64 stddev:3177.67 (63.6%) stddev-sans-first:3171.12 05:43:04 INFO - PROCESS | 1694 | Values: 1480.2 1515.5 1653.2 1777.8 1811.7 8344.7 8893.4 8894.0 8906.2 8918.6 7773.5 8008.8 8354.4 8563.4 8660.4 2329.5 2354.7 2355.6 2356.3 2363.4 1982.3 2337.4 2356.3 2385.6 2387.7 2339.6 2361.1 2372.1 2374.3 2381.8 2332.6 2345.2 2363.4 2368.6 2370.0 2345.7 2349.0 2349.8 2350.2 2362.2 8697.6 8758.2 8798.5 8808.2 8852.2 5128.7 5178.4 5285.5 5304.5 5307.2 3434.9 3525.8 3614.7 3619.7 3637.9 1068.6 1108.1 1109.7 1125.5 1128.6 11531.1 11598.4 11606.4 11631.7 11631.9 4483.1 4580.2 4727.8 4801.3 4803.3 9762.0 9764.3 9774.7 9790.0 9797.0 5302.6 5311.8 5311.9 5313.7 5326.0 3940.4 4394.6 4564.9 4838.7 4900.0 3799.2 3839.1 3912.9 3957.5 3966.0 1266.8 1279.3 1283.1 1292.6 1297.7 820.2 854.9 910.6 922.9 953.5 5272.1 5757.0 5813.7 5957.0 5990.8 346.6 430.2 444.1 445.3 467.0 3911.6 3949.6 3954.4 4071.9 4074.8 9904.7 10500.7 10558.7 10608.1 10634.5 10291.0 10589.1 10634.1 10635.2 10658.9 7999.1 8215.5 8216.8 8226.3 8233.3 5757.4 5858.1 5922.0 5957.7 6015.7 9865.6 9886.0 9922.9 9924.9 9955.0 9623.8 9936.0 9970.4 10106.1 10126.4 6884.4 7077.9 7250.4 7383.8 7548.3 8020.8 8342.0 8356.2 8364.2 8412.1 7370.7 7447.4 7508.9 7529.3 7643.5 7284.4 7317.1 7336.4 7485.3 7511.4 6803.9 6965.5 6974.2 6976.6 6983.0 4634.3 4751.4 4948.6 5022.9 5043.6 4915.9 4989.7 5001.6 5048.2 5112.9 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | [#4] prototype.html Cycles:180 Average:4571.80 Median:5254.28 stddev:1451.14 (27.6%) stddev-sans-first:1418.52 05:43:04 INFO - PROCESS | 1694 | Values: 251.9 294.5 306.5 311.0 313.0 5036.3 5346.5 5443.8 5482.9 5498.9 4371.5 4467.8 5062.2 5309.7 5404.0 4004.4 4030.8 4053.8 4068.5 4089.8 4020.7 4042.2 4056.9 4064.8 4091.7 3508.4 3678.7 4038.3 4058.8 4079.6 4019.3 4026.0 4038.9 4064.3 4073.3 3468.3 3477.3 3480.5 3520.6 3529.4 4759.3 5003.2 5268.5 5310.5 5316.4 4211.0 4570.5 4611.3 4629.6 4655.5 5241.1 5312.8 5620.7 5662.4 5681.5 3848.8 3850.4 3858.2 3860.8 3863.0 4875.4 5207.0 5324.1 5878.1 5881.8 3515.0 3728.6 3830.6 3838.5 3862.5 3298.0 3885.8 3939.5 3947.2 3958.9 3897.4 4123.7 4599.7 4635.8 4654.8 4715.9 5434.0 5620.7 5661.9 5778.5 991.7 1166.2 1265.3 1302.4 1344.0 747.7 760.6 820.5 834.8 892.3 4408.7 5240.4 5247.8 5263.3 5306.5 5716.7 5733.4 5737.9 5788.9 5873.0 4618.3 5048.5 5184.0 5188.7 5241.8 2378.6 2475.7 2520.3 3034.0 3037.6 5587.6 5802.5 5914.6 5953.8 5955.6 5971.9 6011.4 6012.0 6028.5 6036.6 6042.6 6053.2 6054.7 6059.5 6062.2 4918.7 5562.1 5810.2 5849.3 5861.3 4731.8 5260.8 5754.5 5794.7 5833.0 4517.5 5620.4 5721.1 6027.0 6138.4 2914.4 3800.5 3871.9 3893.5 3925.1 5337.8 5820.4 5883.8 5898.5 5947.4 5508.5 5514.6 5602.2 5768.8 5770.9 5661.8 5674.7 5711.5 5711.5 5753.3 5324.0 5500.4 5588.9 5637.2 5668.6 4677.4 5646.7 5698.0 5725.3 5775.5 5542.8 5572.0 5574.4 5593.2 5596.4 05:43:04 INFO - PROCESS | 1694 | 05:43:04 INFO - PROCESS | 1694 | [#5] yui.html Cycles:180 Average:24782.47 Median:3680.90 stddev:39711.42 (1078.9%) stddev-sans-first:39781.68 05:43:04 INFO - PROCESS | 1694 | Values: 709.2 727.9 776.4 784.2 795.7 14856.9 16161.0 16172.9 16191.3 16192.8 13522.1 13547.6 13553.8 13562.7 13964.4 676.7 698.8 830.1 847.0 868.6 846.9 854.0 862.1 864.0 867.4 767.8 917.3 922.7 923.9 925.3 917.8 918.3 920.5 921.1 924.9 528.9 544.7 544.8 546.5 547.7 13732.0 13942.0 13946.0 13981.0 13986.9 14237.1 14693.0 14712.9 14764.0 14876.9 12034.9 12055.6 12083.2 12251.3 12324.5 3857.8 3887.8 3920.2 3947.5 4042.6 112774.9 115435.4 116458.0 116595.4 117868.0 9825.5 10412.3 11290.7 11310.1 11405.8 22857.7 22888.8 22979.7 23036.9 23238.0 6226.8 7320.3 7403.3 7453.1 7631.0 4398.7 4477.6 4512.0 4639.4 4643.0 3549.5 3673.1 3680.7 3681.2 3694.9 651.6 653.2 663.8 681.1 682.0 579.5 609.7 646.0 651.8 666.5 8962.4 9288.6 9345.8 9348.6 9803.9 633.3 635.0 642.8 660.0 678.7 4362.6 4621.7 4628.4 4681.4 4714.2 142164.7 143769.0 144832.0 146051.3 151668.0 121923.0 123326.0 125589.0 125848.0 130278.0 109301.5 110878.9 112816.0 113020.0 115913.0 8631.5 8930.0 8958.0 8971.0 9175.7 60039.7 61692.4 62997.4 63934.0 63940.0 86128.0 93491.0 94332.5 94851.5 96463.5 10337.7 11682.2 12119.1 12394.6 12595.0 10926.7 12142.3 12354.3 12666.8 12787.0 11645.4 11829.3 12274.6 12454.8 12526.9 11955.5 12014.3 12188.2 12753.8 13389.7 13262.6 13489.1 13755.3 14094.3 14125.7 10429.8 11160.8 11286.8 11294.3 11389.4 9643.8 10406.3 11199.5 11313.1 11403.3 05:43:04 INFO - PROCESS | 1694 | -------- Summary: end -------- 05:43:04 INFO - PROCESS | 1694 | 05:43:05 INFO - TEST-INFO | 1694: exit 0 05:43:05 INFO - TEST-OK | dromaeo_css | took 1115965ms 05:43:05 INFO - TEST-START | kraken 05:43:05 INFO - operating with platform_type : mac_ 05:43:05 INFO - Initialising browser for kraken test... 05:43:05 INFO - TEST-INFO | started process 1700 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpb_8oBp/profile http://localhost:49269/getInfo.html) 05:43:07 INFO - PROCESS | 1700 | __metrics Screen width/height:1600/1200 05:43:07 INFO - PROCESS | 1700 | colorDepth:24 05:43:07 INFO - PROCESS | 1700 | Browser inner width/height: 1024/689 05:43:07 INFO - PROCESS | 1700 | __metrics 05:43:07 INFO - PROCESS | 1700 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:43:07 INFO - PROCESS | 1700 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:08 INFO - TEST-INFO | 1700: exit 0 05:43:08 INFO - Browser initialized. 05:43:08 INFO - Running cycle 1/1 for kraken test... 05:43:08 INFO - TEST-INFO | started process 1702 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpb_8oBp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:20 INFO - PROCESS | 1702 | RSS: Main: 228790272 05:43:20 INFO - PROCESS | 1702 | 05:43:23 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:24 INFO - PROCESS | 1702 | RSS: Main: 285888512 05:43:24 INFO - PROCESS | 1702 | 05:43:26 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 05:43:27 INFO - PROCESS | 1702 | RSS: Main: 295190528 05:43:27 INFO - PROCESS | 1702 | 05:43:30 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 05:43:31 INFO - PROCESS | 1702 | RSS: Main: 387682304 05:43:31 INFO - PROCESS | 1702 | 05:43:33 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 05:43:34 INFO - PROCESS | 1702 | RSS: Main: 290791424 05:43:34 INFO - PROCESS | 1702 | 05:43:36 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:36 INFO - PROCESS | 1702 | RSS: Main: 295829504 05:43:36 INFO - PROCESS | 1702 | 05:43:39 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:40 INFO - PROCESS | 1702 | RSS: Main: 333791232 05:43:40 INFO - PROCESS | 1702 | 05:43:43 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:44 INFO - PROCESS | 1702 | RSS: Main: 334704640 05:43:44 INFO - PROCESS | 1702 | 05:43:46 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 05:43:47 INFO - PROCESS | 1702 | RSS: Main: 334610432 05:43:47 INFO - PROCESS | 1702 | 05:43:49 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:49 INFO - PROCESS | 1702 | RSS: Main: 302002176 05:43:49 INFO - PROCESS | 1702 | 05:43:51 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:52 INFO - PROCESS | 1702 | RSS: Main: 308355072 05:43:52 INFO - PROCESS | 1702 | 05:43:54 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:55 INFO - PROCESS | 1702 | RSS: Main: 303362048 05:43:55 INFO - PROCESS | 1702 | 05:43:58 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:59 INFO - PROCESS | 1702 | RSS: Main: 313155584 05:43:59 INFO - PROCESS | 1702 | 05:44:01 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:02 INFO - PROCESS | 1702 | RSS: Main: 315858944 05:44:02 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 05:44:04 INFO - PROCESS | 1702 | RSS: Main: 358895616 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | __start_tp_report 05:44:04 INFO - PROCESS | 1702 | _x_x_mozilla_page_load 05:44:04 INFO - PROCESS | 1702 | _x_x_mozilla_page_load_details 05:44:04 INFO - PROCESS | 1702 | |i|pagename|runs| 05:44:04 INFO - PROCESS | 1702 | |0;ai-astar;103;112;112;112;108;114;110;113;111;113 05:44:04 INFO - PROCESS | 1702 | |1;audio-beat-detection;144;110;108;168;107;107;129;107;143;108 05:44:04 INFO - PROCESS | 1702 | |2;audio-dft;189;182;185;185;184;183;179;183;195;204 05:44:04 INFO - PROCESS | 1702 | |3;audio-fft;85;74;84;112;67;59;69;58;101;58 05:44:04 INFO - PROCESS | 1702 | |4;audio-oscillator;87;69;106;70;73;69;97;70;68;102 05:44:04 INFO - PROCESS | 1702 | |5;imaging-gaussian-blur;87;84;86;83;87;91;134;84;87;83 05:44:04 INFO - PROCESS | 1702 | |6;imaging-darkroom;92;94;93;92;93;92;105;149;126;92 05:44:04 INFO - PROCESS | 1702 | |7;imaging-desaturate;93;94;128;92;91;92;95;90;93;84 05:44:04 INFO - PROCESS | 1702 | |8;json-parse-financial;58;56;55;54;57;57;55;56;57;55 05:44:04 INFO - PROCESS | 1702 | |9;json-stringify-tinderbox;68;71;67;75;71;70;69;70;70;70 05:44:04 INFO - PROCESS | 1702 | |10;stanford-crypto-aes;95;100;105;86;86;83;85;85;88;85 05:44:04 INFO - PROCESS | 1702 | |11;stanford-crypto-ccm;139;138;151;153;132;158;156;141;216;154 05:44:04 INFO - PROCESS | 1702 | |12;stanford-crypto-pbkdf2;171;171;172;170;171;168;169;170;169;169 05:44:04 INFO - PROCESS | 1702 | |13;stanford-crypto-sha256-iterative;69;68;68;66;66;68;68;67;67;67 05:44:04 INFO - PROCESS | 1702 | __end_tp_report 05:44:04 INFO - PROCESS | 1702 | __start_cc_report 05:44:04 INFO - PROCESS | 1702 | _x_x_mozilla_cycle_collect,2050 05:44:04 INFO - PROCESS | 1702 | __end_cc_report 05:44:04 INFO - PROCESS | 1702 | __startTimestamp1471265044528__endTimestamp 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | ------- Summary: start ------- 05:44:04 INFO - PROCESS | 1702 | Number of tests: 14 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.22 (2.9%) stddev-sans-first:1.80 05:44:04 INFO - PROCESS | 1702 | Values: 103.0 112.0 112.0 112.0 108.0 114.0 110.0 113.0 111.0 113.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:119.50 stddev:21.83 (18.3%) stddev-sans-first:21.80 05:44:04 INFO - PROCESS | 1702 | Values: 144.0 110.0 108.0 168.0 107.0 107.0 129.0 107.0 143.0 108.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#2] audio-dft Cycles:10 Average:186.90 Median:185.00 stddev:7.42 (4.0%) stddev-sans-first:7.83 05:44:04 INFO - PROCESS | 1702 | Values: 189.0 182.0 185.0 185.0 184.0 183.0 179.0 183.0 195.0 204.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#3] audio-fft Cycles:10 Average:76.70 Median:68.00 stddev:18.66 (27.4%) stddev-sans-first:19.54 05:44:04 INFO - PROCESS | 1702 | Values: 85.0 74.0 84.0 112.0 67.0 59.0 69.0 58.0 101.0 58.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:70.00 stddev:15.35 (21.9%) stddev-sans-first:16.13 05:44:04 INFO - PROCESS | 1702 | Values: 87.0 69.0 106.0 70.0 73.0 69.0 97.0 70.0 68.0 102.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:86.50 stddev:15.44 (17.9%) stddev-sans-first:16.32 05:44:04 INFO - PROCESS | 1702 | Values: 87.0 84.0 86.0 83.0 87.0 91.0 134.0 84.0 87.0 83.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:92.00 stddev:19.48 (21.2%) stddev-sans-first:20.26 05:44:04 INFO - PROCESS | 1702 | Values: 92.0 94.0 93.0 92.0 93.0 92.0 105.0 149.0 126.0 92.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:92.50 stddev:11.91 (12.9%) stddev-sans-first:12.61 05:44:04 INFO - PROCESS | 1702 | Values: 93.0 94.0 128.0 92.0 91.0 92.0 95.0 90.0 93.0 84.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:1.25 (2.2%) stddev-sans-first:1.09 05:44:04 INFO - PROCESS | 1702 | Values: 58.0 56.0 55.0 54.0 57.0 57.0 55.0 56.0 57.0 55.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:2.13 (3.0%) stddev-sans-first:2.12 05:44:04 INFO - PROCESS | 1702 | Values: 68.0 71.0 67.0 75.0 71.0 70.0 69.0 70.0 70.0 70.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:85.50 stddev:7.52 (8.8%) stddev-sans-first:7.74 05:44:04 INFO - PROCESS | 1702 | Values: 95.0 100.0 105.0 86.0 86.0 83.0 85.0 85.0 88.0 85.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:153.50 stddev:23.59 (15.4%) stddev-sans-first:24.40 05:44:04 INFO - PROCESS | 1702 | Values: 139.0 138.0 151.0 153.0 132.0 158.0 156.0 141.0 216.0 154.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.50 stddev:1.25 (0.7%) stddev-sans-first:1.27 05:44:04 INFO - PROCESS | 1702 | Values: 171.0 171.0 172.0 170.0 171.0 168.0 169.0 170.0 169.0 169.0 05:44:04 INFO - PROCESS | 1702 | 05:44:04 INFO - PROCESS | 1702 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:0.83 05:44:04 INFO - PROCESS | 1702 | Values: 69.0 68.0 68.0 66.0 66.0 68.0 68.0 67.0 67.0 67.0 05:44:04 INFO - PROCESS | 1702 | -------- Summary: end -------- 05:44:04 INFO - PROCESS | 1702 | 05:44:05 INFO - TEST-INFO | 1702: exit 0 05:44:05 INFO - TEST-OK | kraken | took 59879ms 05:44:05 INFO - SUITE-END | took 1175s 05:44:05 INFO - Completed test suite (00:19:36) 05:44:05 INFO - javascript benchmark 05:44:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2876.3528206153615, 3196.5205219217128, 3225.693559111885, 3239.1740106272896, 3243.6107667079955, 5176.120059589869, 5253.395859476157, 5299.205119232117, 5324.148136298189, 5342.786288548454, 5263.49996750309, 5280.867978300545, 5303.363596368432, 5311.973440132803, 5336.493033161843, 4869.780859861297, 4905.288733133693, 4917.72952487613, 4921.704697718131, 4981.452040275567, 4831.734254615994, 4917.631177661689, 4956.776945037482, 4964.230544265626, 5006.199008158677, 4985.675931064469, 4998.3252260944855, 4999.775055111503, 5000.0, 5002.0, 335.19328172656316, 4921.532454416824, 4989.825356112518, 5015.022070696215, 5039.294498770175, 4896.877578060541, 4907.993861258434, 4909.410870695539, 4931.0, 4939.160342741742, 4778.235482322812, 4929.827456039057, 5330.1205301125265, 5332.066888294533, 5385.0, 5588.748506317225, 5629.971850140723, 5640.971795141039, 5797.159411885253, 5812.534997200256, 5737.9713101434645, 5796.043652797283, 5800.564957628195, 5802.12968054791, 5804.013317735995, 5394.0, 5410.621256512046, 5435.510804027656, 5467.20725494809, 5474.753636086425, 5054.418741844662, 6064.787732429344, 6068.059450785136, 6078.483328917092, 6081.513478921675, 5683.119116536944, 5709.143628455765, 5715.571332150105, 5747.770089196469, 5753.395893431213, 5657.858553536194, 5703.885922281531, 5863.384344643836, 5907.2911250650195, 5908.172855800191, 5634.7182640867795, 5696.316442026983, 5698.601097923189, 5728.226689396878, 5756.827295181151, 4779.832705855279, 5719.999000174953, 5733.168690539848, 5740.368559458456, 5744.827655170351, 4552.954470455319, 4651.348811166439, 4665.370175026396, 4666.0, 4713.835015774432, 4124.628783409507, 4143.482064741907, 4174.20690068886, 4198.5591512891315, 4224.683148763855, 5358.624896257303, 5359.08895487768, 5371.57027437804, 5381.811636592701, 5411.485908838654, 5842.707864606753, 5843.152742852262, 5844.444777746107, 5851.853703657357, 5865.0, 5229.320188375486, 5281.762320695578, 5291.576673866081, 5321.787128514816, 5355.357357117171, 5067.5185857343495, 5080.898382032338, 5084.3644544431945, 5095.923561146586, 5107.438181799999, 6008.699565021731, 6041.969790151109, 6057.242844644419, 6066.120412540157, 6081.087836824512, 5559.861003474945, 5698.572607054571, 5724.112762521816, 5790.4209579043445, 5791.797287094932, 6053.455189032832, 6073.939260607514, 6082.878342433215, 6092.847678808065, 6128.724207410766, 5697.57268204878, 5699.5155411790465, 5702.6293290935955, 5721.141828725725, 5726.713664316851, 5599.748011339581, 5720.227769251264, 5735.655860648374, 5759.625624334405, 5805.071188609802, 5584.218209450599, 5640.32316122068, 5655.123455864347, 5659.915101273402, 5704.1443783432815, 5623.775048997994, 5630.915536267041, 5633.042382795016, 5646.2942132233475, 5652.519535839499, 4760.285957106462, 5359.088954877758, 5424.430434804446, 5531.668099914018, 5615.185798059177, 5616.747246374005, 5629.746661400329, 5635.323761148688, 5674.404187560412, 5675.148727690715, 5501.119820828648, 5522.475364840334, 5530.668159910419, 5536.280283563111, 5558.277423934863], "value": 5286.125030958767, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.2287819194601, 471.4201532115496, 486.2390359088027, 517.932668753062, 534.8609361565995, 30753.692463075364, 32401.0, 32433.837830810844, 39855.00362490939, 41323.79338103305, 42266.78866605663, 42779.572204277945, 42825.0, 42865.0, 42927.570724292746, 8371.623276952536, 9899.257555683316, 9916.256280778935, 9916.950415247913, 9921.553530091127, 7557.2820582044615, 9062.546872656374, 9072.047435019327, 9793.26550508711, 9929.2056635469, 9002.684906028291, 9060.592273347682, 11935.0, 12147.331896745676, 12190.512379504853, 12084.758304833898, 12150.270983741004, 12150.756984860252, 12186.51253949841, 12237.204581702206, 11900.0, 11985.700357491045, 11994.100442466763, 11996.580119695856, 12000.759984800256, 39877.80366589007, 39952.0, 40022.19955600901, 40196.0, 40251.59748402537, 3377.5609170807884, 3446.8104254266004, 3468.9826550867083, 3474.3398754236614, 3565.2156525564333, 30411.543826842615, 31487.897923572793, 31516.0, 31583.52624710631, 31882.521762173586, 2758.1311886755807, 2759.4067275535654, 2784.8468334241606, 2800.411913498168, 2831.5327970884778, 461942.0, 471678.7569429525, 474521.0, 474529.0, 475748.0, 58237.41762582405, 58584.70707646434, 58634.7068264656, 58644.0, 58681.70659146762, 61148.0, 61666.76666466646, 61680.383196167466, 61811.90940452959, 62016.06975895365, 16194.676106477804, 16461.423850165185, 16476.928999614986, 16488.505344839676, 16522.2564984576, 11644.2431241969, 11893.821592676117, 11926.284422934652, 12069.879301207051, 12087.87912120885, 14237.827933220733, 18953.43139705811, 19184.13671384791, 19604.411867643994, 19646.11592478342, 6696.732130714814, 7843.745000799845, 8061.2744852963515, 8237.341012718967, 8287.8342433151, 731.9011933388924, 803.8472690188845, 967.811276801017, 1026.697124348319, 1069.8127827630133, 27586.724132758816, 28570.571441428394, 28689.0, 28865.422691546053, 30624.741538661172, 1075.8009768192753, 1095.370162156747, 1134.4838098665252, 1137.4824454873012, 1218.0925210717894, 12552.686182845502, 15213.847861521464, 15367.923160384351, 15749.370025199092, 16473.0, 687057.0, 692334.0, 692927.0, 693483.0, 694660.0, 59693.0, 59925.0, 59955.0, 60023.09965350523, 60139.0, 1202.5430336472084, 1324.1856258401015, 1333.8065980432782, 1362.7070179911366, 1362.7274545090822, 27767.444651107267, 28633.856830715715, 31193.37613247768, 31501.054968350985, 31971.040868773973, 49306.0, 50092.99814003772, 50121.49878501168, 50174.0, 51101.0, 564077.0, 564707.0, 565790.0, 566920.0, 567294.0, 26118.34704132412, 26536.867315663298, 26811.865940670174, 26812.463750725266, 43124.35313470363, 45865.082698346516, 46219.53780462153, 46607.766961164976, 46735.0, 47009.0, 33238.66761332356, 37775.62224377721, 38113.61886381212, 39290.60709392984, 39647.80176099101, 33101.503477448336, 34597.0, 34708.305833882674, 38230.0, 39423.014424639616, 36448.45327320039, 37548.0, 38266.808665956494, 38513.80743096267, 40410.78767636976, 30674.693253067184, 31089.378212436077, 33348.66651333456, 35105.82447087748, 35361.64638353686, 24515.63226551639, 25426.491470170124, 25766.87116564405, 25817.483650327264, 26698.466030679665], "value": 20067.310971321964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.8925965171842, 351.9401701710709, 368.77873276034364, 377.79787813519766, 402.137415244301, 15866.048037117767, 17492.912535437335, 20645.03872132145, 21297.25459608914, 21455.249066282664, 21063.0, 21092.472688182803, 21663.67504487434, 21754.238601648907, 21907.233246836324, 5140.820071297505, 5760.279965004364, 5947.256592925884, 6029.155918171459, 6033.69831508425, 5883.88232235355, 5992.460678538931, 5995.13070604762, 6015.548833837458, 6016.157737916695, 5710.1434784782405, 5953.553483488734, 5966.2840459144745, 5977.013792724195, 6044.96977515114, 6038.909416358758, 6077.513798896078, 6103.725332360054, 6104.694765261764, 6115.021596544531, 5828.2131912191735, 5866.530677545828, 5929.199558059693, 5930.080837470199, 5931.822045338647, 13407.731845363136, 13470.595882123553, 13516.918646508257, 13680.589582312447, 13731.519396821162, 2087.645100332947, 2089.174775963501, 2186.529896072336, 2364.5152743687504, 2649.5495765719875, 9867.309288349818, 10047.849282260771, 10093.646722364756, 10531.052205301483, 10573.0, 1788.874778765487, 1806.3226290141197, 1840.2179073893542, 1904.352520143158, 1931.652302585534, 86747.0, 96492.51753741257, 120487.79512204854, 121325.39337303257, 121715.39142304233, 11012.724681882857, 11265.887341126483, 11414.801445848232, 11448.656540303804, 11699.766004680028, 12581.74836503283, 14340.56978290653, 14381.6404589884, 14381.78427323591, 14394.712105757826, 8396.07643159252, 8522.190391912758, 8524.275436587835, 8584.098669639723, 8676.305895528343, 8460.78848028792, 8500.70247541333, 8632.654693812303, 8667.523286219319, 8673.089325620718, 4373.737575745466, 4468.676288330115, 4488.105172163936, 4498.305736819536, 4586.954130458719, 1779.8932064076198, 1887.9716804247948, 1888.8111188881276, 1892.7255547945747, 1925.2299080367684, 835.0, 852.6376290076693, 919.6413398774483, 940.4498368454394, 1024.964785566583, 21247.575048498944, 21973.560528789334, 22046.669299960515, 22079.33761987143, 22137.0, 899.1053456709006, 906.40177482861, 915.3272344826547, 929.5166513412987, 938.0806809326898, 11073.280236784743, 11160.772315045338, 11449.48477318506, 11457.656270311903, 11546.884531154748, 511543.0, 539921.0, 540319.0, 540576.0, 544516.0, 93516.0, 104571.82478911825, 116588.0, 120421.39789301173, 133521.66478335287, 898.501331760879, 938.7043081429327, 993.801239752038, 1000.979980400388, 1003.7992401519725, 19393.61212775765, 20196.014309639468, 20261.885596292344, 20412.18351265933, 20483.795162048784, 41544.37683434811, 45829.54170458253, 56487.0, 57112.42887571237, 57820.421795783186, 78306.0, 79085.60457197677, 81597.0, 84162.0, 87977.56011219903, 7878.172791856772, 7903.3677305816545, 8174.468659537111, 8179.1411901751835, 8448.943882014748, 11009.724756881142, 12854.614361569167, 12894.419751111034, 13425.66435839112, 13559.79660305116, 9453.385529940531, 11064.502097405475, 11398.316101033965, 11543.653690389301, 11746.882531174579, 11096.61161859331, 11185.049270811747, 11470.483828227583, 11625.534978600761, 11800.940995294968, 11317.094632429551, 11913.880861191277, 12062.939685301868, 12077.75844483123, 12078.396080196131, 7140.750073747602, 8020.478668886503, 8031.397645176752, 8112.1076681566165, 8280.544570048705, 8293.502389856629, 10945.671629851116, 11258.099352051653, 11391.869773087805, 11628.94185529067], "value": 9373.682389522757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.1637075052595, 1515.469585645024, 1653.2395098254801, 1777.8311060449253, 1811.7282407638852, 8344.666213351466, 8893.421927574704, 8893.95553022234, 8906.153915378038, 8918.554072296383, 7773.494722843011, 8008.7597372078935, 8354.373421993345, 8563.40056196065, 8660.393772435931, 2329.4525786440217, 2354.693889794324, 2355.6231003039516, 2356.257778799681, 2363.3855197648563, 1982.3458258774642, 2337.4390146364835, 2356.3048900574286, 2385.5705972924866, 2387.7254115776645, 2339.6373562097815, 2361.067378385537, 2372.1460274301326, 2374.252110585169, 2381.833271670984, 2332.6267797152545, 2345.179187284445, 2363.409147713072, 2368.6091794854, 2369.9763002369928, 2345.7419683834764, 2348.9647655285185, 2349.77677120675, 2350.200931683236, 2362.161432691404, 8697.608607612672, 8758.211760941545, 8798.51608161551, 8808.207261346446, 8852.247558957497, 5128.666636668605, 5178.40448348441, 5285.49787770165, 5304.469553044664, 5307.150855863082, 3434.8797792077407, 3525.7531972761917, 3614.7288953328607, 3619.7104231661406, 3637.909052273688, 1068.631322193842, 1108.1024370260034, 1109.7447587055024, 1125.482278152051, 1128.5824245029391, 11531.13516486267, 11598.420078996101, 11606.4196790161, 11631.709207269801, 11631.883681163165, 4483.14820184164, 4580.213716650509, 4727.787249573777, 4801.279808028824, 4803.255495398149, 9762.0, 9764.267679924034, 9774.657886973922, 9790.0, 9796.951015244878, 5302.575793936476, 5311.787528498894, 5311.867203319948, 5313.734313284321, 5326.041312563698, 3940.3695408734466, 4394.560543945644, 4564.863054108382, 4838.661293709407, 4900.0, 3799.2021675448464, 3839.1170030892495, 3912.921741565153, 3957.5053118360206, 3966.0, 1266.8163116348078, 1279.3475327583, 1283.1146508908823, 1292.6315999939973, 1297.7209899871384, 820.2044017303207, 854.9102344253888, 910.5811326789618, 922.898481167071, 953.4565297780338, 5272.077386457355, 5757.0, 5813.7383817728305, 5957.0, 5990.760369585255, 346.63430081264005, 430.23633051334133, 444.14502083489157, 445.2719803121919, 467.04489319342025, 3911.5501717302304, 3949.624785645359, 3954.426608141803, 4071.857484988011, 4074.8166332515107, 9904.653337133166, 10500.737481563023, 10558.736031599272, 10608.098311643442, 10634.46827658614, 10291.022352876465, 10589.099926506333, 10634.149268058536, 10635.202359823044, 10658.893411065945, 7999.120096789348, 8215.548144851971, 8216.794580135544, 8226.30076443509, 8233.259006689306, 5757.424257574209, 5858.062709966385, 5921.970390148022, 5957.702114894325, 6015.729292181775, 9865.556049977624, 9885.950570247103, 9922.900770992197, 9924.900750992398, 9955.004499549987, 9623.759406014906, 9935.950320248352, 9970.40177589347, 10106.090451859369, 10126.392416455037, 6884.449244060564, 7077.858442831218, 7250.383717384081, 7383.778486645409, 7548.282913123245, 8020.799480013046, 8342.040665323322, 8356.206160414751, 8364.247217750431, 8412.116727743498, 7370.705171793283, 7447.404207663482, 7508.887366689395, 7529.3223609873185, 7643.503172293783, 7284.417246620364, 7317.085364329459, 7336.37640800538, 7485.326320631169, 7511.3615342696485, 6803.9319606805275, 6965.477589180933, 6974.19796723363, 6976.5814051157095, 6983.022376867344, 4634.2585186370015, 4751.429828420612, 4948.604111670986, 5022.949770502249, 5043.596512278936, 4915.877103072309, 4989.675671081368, 5001.599872010303, 5048.217526283285, 5112.872178195575], "value": 4469.101504504918, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.9193857965451, 294.46260574451634, 306.5218259515157, 310.9970345635326, 312.9624445066592, 5036.3200967869325, 5346.518813306807, 5443.8094666686675, 5482.890342193154, 5498.890022199554, 4371.535535595579, 4467.843625473101, 5062.202917964125, 5309.681419114856, 5404.0, 4004.3793212052105, 4030.778307193103, 4053.7770422626745, 4068.471098757172, 4089.7546147231114, 4020.6582440492593, 4042.191561687674, 4056.8580099696514, 4064.800883739289, 4091.713580049398, 3508.4386498160297, 3678.65052819983, 4038.2731108400476, 4058.817353219112, 4079.5512493625824, 4019.2966230909474, 4025.9597404025876, 4038.899027524306, 4064.2887494688607, 4073.2668119738437, 3468.271662950783, 3477.3045390921916, 3480.5301284326797, 3520.6303338149637, 3529.400001999666, 4759.286107083931, 5003.249512573107, 5268.4994925482015, 5310.468953104697, 5316.421535555252, 4210.978945105286, 4570.520095390003, 4611.331356953223, 4629.55562659847, 4655.4878963314, 5241.135212689902, 5312.840614781563, 5620.662760234399, 5662.433756624346, 5681.488666020036, 3848.768394113885, 3850.3646898261754, 3858.202802967854, 3860.806959652034, 3862.9613703862883, 4875.416029504368, 5207.0366982108435, 5324.121519949165, 5878.147668588073, 5881.823545293648, 3514.982425087909, 3728.5898551159353, 3830.616938306147, 3838.500994870649, 3862.517185351831, 3298.0, 3885.7862817545306, 3939.468171796828, 3947.1513624570857, 3958.9208215835524, 3897.395903634941, 4123.731957422758, 4599.724016559017, 4635.814567417266, 4654.813807447732, 4715.882102947454, 5434.0112937884005, 5620.718964051781, 5661.915071273934, 5778.451047150514, 991.6727479931606, 1166.2419427372276, 1265.2598230035348, 1302.4008955880402, 1343.9932800335935, 747.7083937264471, 760.6196901549225, 820.4502983001404, 834.7662654456761, 892.3173772064376, 4408.691391602557, 5240.3973543042275, 5247.763850626731, 5263.289455923401, 5306.52241298285, 5716.657000579979, 5733.426657334317, 5737.881113182889, 5788.88422231553, 5873.0, 4618.282282762341, 5048.485454363677, 5183.9632073585435, 5188.662736922088, 5241.764120614582, 2378.619420892649, 2475.6905386826647, 2520.3447103752787, 3033.9696603034126, 3037.559553864677, 5587.552995760329, 5802.535797136304, 5914.615549989323, 5953.76184952597, 5955.612885162451, 5971.850703732442, 6011.44513581726, 6011.977963746173, 6028.487578555784, 6036.577439579188, 6042.577019608585, 6053.213082299273, 6054.670910299069, 6059.48494377974, 6062.242219722535, 4918.704877707349, 5562.137868630369, 5810.186573879745, 5849.268841394825, 5861.267341582302, 4731.787069581808, 5260.789568417374, 5754.4533269339345, 5794.739236734273, 5833.008388573868, 4517.480489743722, 5620.381758006534, 5721.112033029173, 6027.035674292092, 6138.355472675305, 2914.4171165767357, 3800.5059342285326, 3871.8838434847, 3893.493845800141, 3925.135560608688, 5337.759800809051, 5820.447057529697, 5883.823235353032, 5898.469137777621, 5947.434993675594, 5508.504234618904, 5514.61397702157, 5602.215689803349, 5768.826935191951, 5770.8845823082465, 5661.773529058792, 5674.74463649145, 5711.457411545896, 5711.514521265739, 5753.338366087954, 5324.041672498987, 5500.3949565548555, 5588.916166257591, 5637.238972738792, 5668.574856885833, 4677.415323084614, 5646.689432081111, 5698.0, 5725.312962444534, 5775.537956963517, 5542.8337149885565, 5571.9721401392735, 5574.386817449996, 5593.231350481537, 5596.356419011866], "value": 4092.534194243195, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.1915216653016, 727.9053723016004, 776.3982913242237, 784.211867073591, 795.7175202803004, 14856.92571537142, 16161.0, 16172.919135404336, 16191.271392787323, 16192.75710864338, 13522.053456258065, 13547.59357219285, 13553.796693049611, 13562.660933476644, 13964.44142234312, 676.7090151234967, 698.7659134190052, 830.0703212402118, 846.9872951905726, 868.6395146014396, 846.8814365988767, 853.995730021349, 862.1034124510502, 864.0365991919028, 867.418829384314, 767.8387538616897, 917.2982668258787, 922.7231830450871, 923.861420786884, 925.3013974449315, 917.7705573606598, 918.3158546882548, 920.5305294299889, 921.0789210789211, 924.916757491829, 528.875714207161, 544.7494152689767, 544.7657507271872, 546.51906322436, 547.654977364258, 13732.038757286993, 13942.0, 13946.02377833552, 13980.951428642791, 13986.93006534971, 14237.145771253654, 14693.0, 14712.926435367755, 14764.0, 14876.925615371854, 12034.879651203464, 12055.578054768128, 12083.154179207459, 12251.326177060258, 12324.5070197192, 3857.8071096445065, 3887.766733995941, 3920.176762879799, 3947.5460322062754, 4042.636162745367, 112774.87225127725, 115435.42282288619, 116458.0, 116595.41702291435, 117868.0, 9825.459599722084, 10412.32319899204, 11290.661280161608, 11310.09519238459, 11405.771884562262, 22857.657135142654, 22888.771112288665, 22979.655305170436, 23036.884815575813, 23238.0, 6226.750929962841, 7320.304571065847, 7403.333699967029, 7453.105627324648, 7631.046119235096, 4398.736075835396, 4477.619402350771, 4511.977440112779, 4639.37368455261, 4642.9535704643195, 3549.5208146900354, 3673.081729567608, 3680.650338217865, 3681.1533347330264, 3694.9445758313645, 651.6090345792582, 653.2226650286144, 663.8008597420754, 681.1383599746325, 681.9965900170467, 579.4553120067123, 609.7195290166574, 645.9967700161469, 651.8435575461855, 666.4568376772914, 8962.417442866194, 9288.628454861864, 9345.813083738423, 9348.626054957726, 9803.852942205873, 633.3160186998031, 635.0093853588417, 642.8200103970896, 659.9836252171602, 678.7115475922714, 4362.585554372329, 4621.6764826462495, 4628.4445866496235, 4681.438227412732, 4714.15145273854, 142164.67100124847, 143769.0, 144832.0, 146051.2697436506, 151668.0, 121923.0, 123326.0, 125589.0, 125848.0, 130278.0, 109301.45349273202, 110878.89121108847, 112816.0, 113020.0, 115913.0, 8631.525266110424, 8929.955350223207, 8957.955210223907, 8970.981529155888, 9175.678851240174, 60039.69980150071, 61692.38307616866, 62997.37002629915, 63934.0, 63940.0, 86128.0, 93491.0, 94332.52833735787, 94851.52574237085, 96463.51768241114, 10337.74155646115, 11682.24065435744, 12119.091068169746, 12394.566190183661, 12595.0, 10926.672199834018, 12142.271463712206, 12354.25874447536, 12666.809997849856, 12787.0, 11645.41772911368, 11829.349385783864, 12274.631761047183, 12454.750904982031, 12526.937365313479, 11955.462004209658, 12014.279143251433, 12188.20776649515, 12753.8086928698, 13389.732205356033, 13262.602121936357, 13489.123206991515, 13755.31223438844, 14094.295285235903, 14125.717485650435, 10429.791404172025, 11160.832587511031, 11286.774264514499, 11294.265872718248, 11389.373584452604, 9643.807123857623, 10406.323588966692, 11199.53725865644, 11313.094952403953, 11403.315801051964], "value": 7682.591161215718, "unit": "score"}], "name": "dromaeo_css", "value": 7203.6215080317215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 112.0, 108.0, 114.0, 110.0, 113.0, 111.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 108.0, 168.0, 107.0, 107.0, 129.0, 107.0, 143.0, 108.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 182.0, 185.0, 185.0, 184.0, 183.0, 179.0, 183.0, 195.0, 204.0], "value": 186.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 74.0, 84.0, 112.0, 67.0, 59.0, 69.0, 58.0, 101.0, 58.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 106.0, 70.0, 73.0, 69.0, 97.0, 70.0, 68.0, 102.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 83.0, 87.0, 91.0, 134.0, 84.0, 87.0, 83.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 93.0, 92.0, 93.0, 92.0, 105.0, 149.0, 126.0, 92.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 128.0, 92.0, 91.0, 92.0, 95.0, 90.0, 93.0, 84.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 55.0, 54.0, 57.0, 57.0, 55.0, 56.0, 57.0, 55.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 67.0, 75.0, 71.0, 70.0, 69.0, 70.0, 70.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 105.0, 86.0, 86.0, 83.0, 85.0, 85.0, 88.0, 85.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 151.0, 153.0, 132.0, 158.0, 156.0, 141.0, 216.0, 154.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 172.0, 170.0, 171.0, 168.0, 169.0, 170.0, 169.0, 169.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 66.0, 66.0, 68.0, 68.0, 67.0, 67.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1474.3, "alertThreshold": 2.0}]} 05:44:05 INFO - Return code: 0 05:44:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:44:05 INFO - # TBPL SUCCESS # 05:44:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:44:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:44:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:44:05 INFO - 2016-08-15 05:44:05.874 screenresolution[1706:15458] starting screenresolution argv=screenresolution get 05:44:05 INFO - 2016-08-15 05:44:05.877 screenresolution[1706:15458] Display 0: 1600x1200x32@60 05:44:05 INFO - 2016-08-15 05:44:05.883 screenresolution[1707:15461] starting screenresolution argv=screenresolution list 05:44:05 INFO - Available Modes on Display 0 05:44:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:44:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:44:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:44:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:44:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:44:06 INFO - Intel Iris: 05:44:06 INFO - Chipset Model: Intel Iris 05:44:06 INFO - Type: GPU 05:44:06 INFO - Bus: Built-In 05:44:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:44:06 INFO - Vendor: Intel (0x8086) 05:44:06 INFO - Device ID: 0x0a2e 05:44:06 INFO - Revision ID: 0x0009 05:44:06 INFO - Displays: 05:44:06 INFO - Display: 05:44:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:44:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:44:06 INFO - Display Serial Number: 1600x1200 60 05:44:06 INFO - Main Display: Yes 05:44:06 INFO - Mirror: Off 05:44:06 INFO - Online: Yes 05:44:06 INFO - Rotation: Supported 05:44:06 INFO - Return code: 0 05:44:06 INFO - Running post-action listener: _resource_record_post_action 05:44:06 INFO - [mozharness: 2016-08-15 12:44:06.120796Z] Finished run-tests step (success) 05:44:06 INFO - Running post-run listener: _resource_record_post_run 05:44:06 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32857088; Write bytes: 705802752; Read time: 307; Write time: 2230 05:44:06 INFO - TinderboxPrint: CPU usage
53.4% 05:44:06 INFO - TinderboxPrint: I/O read bytes / time
32,857,088 / 307 05:44:06 INFO - TinderboxPrint: I/O write bytes / time
705,802,752 / 2,230 05:44:06 INFO - TinderboxPrint: CPU idle
2,259.1 (46.6%) 05:44:06 INFO - TinderboxPrint: CPU system
967.3 (20.0%) 05:44:06 INFO - TinderboxPrint: CPU user
1,619.2 (33.4%) 05:44:06 INFO - TinderboxPrint: Swap in / out
433,332,224 / 0 05:44:06 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396212736; Write bytes: 394698240; Read time: 29468; Write time: 1040 05:44:06 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30977024; Write bytes: 296379392; Read time: 288; Write time: 1151 05:44:06 INFO - Running post-run listener: _upload_blobber_files 05:44:06 INFO - Blob upload gear active. 05:44:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:44:06 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'] 05:44:06 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 05:44:07 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:44:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:44:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:07 INFO - (blobuploader) - INFO - Done attempting. 05:44:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:44:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:08 INFO - (blobuploader) - INFO - Done attempting. 05:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:10 INFO - (blobuploader) - INFO - Done attempting. 05:44:10 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:10 INFO - Return code: 0 05:44:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b9ce7598502f9ba7f30a1a9d85a99d57f52b7f9cfcf49d4cae16c6a5704dad6e0f9fc78f1032023d8c632e2fbeaaae1b01c3d790ed336a79a13f026e11331b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d7f2a979c3290e7a136ec683a97d1abc6872aae772606c55e0bbd7e0fd72bdc59bd9d05509aa82574784f69d8f3dfcc1ac036e39a808853fb0ab6b1d28aa3de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a6643e8c154ebf4dda26e1b0ef0a9ecd18e4837d6ad5714342aca33453a7701544ea09db01b5e59fc216cd839f56203257931cd55251f27cf4e48987f7a855"} 05:44:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:10 INFO - Contents: 05:44:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b9ce7598502f9ba7f30a1a9d85a99d57f52b7f9cfcf49d4cae16c6a5704dad6e0f9fc78f1032023d8c632e2fbeaaae1b01c3d790ed336a79a13f026e11331b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d7f2a979c3290e7a136ec683a97d1abc6872aae772606c55e0bbd7e0fd72bdc59bd9d05509aa82574784f69d8f3dfcc1ac036e39a808853fb0ab6b1d28aa3de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a6643e8c154ebf4dda26e1b0ef0a9ecd18e4837d6ad5714342aca33453a7701544ea09db01b5e59fc216cd839f56203257931cd55251f27cf4e48987f7a855"} 05:44:10 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:10 INFO - Copying logs to upload dir... 05:44:10 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.518109 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-15 05:44:11.184744) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 05:44:11.188237) ========= 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.CtLvVYBaXO/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.W60qCjxZcs/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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/8b9ce7598502f9ba7f30a1a9d85a99d57f52b7f9cfcf49d4cae16c6a5704dad6e0f9fc78f1032023d8c632e2fbeaaae1b01c3d790ed336a79a13f026e11331b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d7f2a979c3290e7a136ec683a97d1abc6872aae772606c55e0bbd7e0fd72bdc59bd9d05509aa82574784f69d8f3dfcc1ac036e39a808853fb0ab6b1d28aa3de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a6643e8c154ebf4dda26e1b0ef0a9ecd18e4837d6ad5714342aca33453a7701544ea09db01b5e59fc216cd839f56203257931cd55251f27cf4e48987f7a855"} build_url:https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011099 build_url: 'https://queue.taskcluster.net/v1/task/XhRfaybQTl-z13CcRmuf9w/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/8b9ce7598502f9ba7f30a1a9d85a99d57f52b7f9cfcf49d4cae16c6a5704dad6e0f9fc78f1032023d8c632e2fbeaaae1b01c3d790ed336a79a13f026e11331b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d7f2a979c3290e7a136ec683a97d1abc6872aae772606c55e0bbd7e0fd72bdc59bd9d05509aa82574784f69d8f3dfcc1ac036e39a808853fb0ab6b1d28aa3de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a6643e8c154ebf4dda26e1b0ef0a9ecd18e4837d6ad5714342aca33453a7701544ea09db01b5e59fc216cd839f56203257931cd55251f27cf4e48987f7a855"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 05:44:11.222694) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:44:11.223029) ========= 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.CtLvVYBaXO/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.W60qCjxZcs/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005416 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:44:11.271640) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 05:44:11.271962) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 05:44:11.272297) ========= ========= Total master_lag: 0.47 =========