builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0179 starttime: 1485714147.17 results: success (0) buildid: 20170129090243 builduid: 92920da917384210874e87f176c5708a revision: 77af15f468e84d9a5ff659641737343ffa0444cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.172770) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.173452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.173912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.351629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.352067) ========= 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.zeI3ngHb3k/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.95SvTOklM0/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 --2017-01-29 10:22:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2017-01-29 10:22:27 (12.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.505658 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.889730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.891630) ========= 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.zeI3ngHb3k/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.95SvTOklM0/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.028668 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.941264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 10:22:27.941610) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 77af15f468e84d9a5ff659641737343ffa0444cf --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 77af15f468e84d9a5ff659641737343ffa0444cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zeI3ngHb3k/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.95SvTOklM0/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 2017-01-29 10:22:28,044 truncating revision to first 12 chars 2017-01-29 10:22:28,044 Setting DEBUG logging. 2017-01-29 10:22:28,044 attempt 1/10 2017-01-29 10:22:28,044 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/77af15f468e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-29 10:22:29,156 unpacking tar archive at: mozilla-central-77af15f468e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.395843 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 10:22:29.385658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.385961) ========= script_repo_revision: 77af15f468e84d9a5ff659641737343ffa0444cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.386410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.386687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.422931) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-29 10:22:29.423193) ========= /tools/buildbot/bin/python -u 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', '-u', '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.zeI3ngHb3k/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.95SvTOklM0/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:22:29 INFO - MultiFileLogger online at 20170129 10:22:29 in /builds/slave/test 10:22:29 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 10:22:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:22:29 INFO - {'append_to_log': False, 10:22:29 INFO - 'base_work_dir': '/builds/slave/test', 10:22:29 INFO - 'blob_upload_branch': 'Firefox', 10:22:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:22:29 INFO - 'branch': 'Firefox', 10:22:29 INFO - 'buildbot_json_path': 'buildprops.json', 10:22:29 INFO - 'config_files': ('talos/mac_config.py',), 10:22:29 INFO - 'default_actions': ('clobber', 10:22:29 INFO - 'read-buildbot-config', 10:22:29 INFO - 'download-and-extract', 10:22:29 INFO - 'populate-webroot', 10:22:29 INFO - 'create-virtualenv', 10:22:29 INFO - 'install', 10:22:29 INFO - 'run-tests'), 10:22:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:22:29 INFO - 'download_minidump_stackwalk': True, 10:22:29 INFO - 'download_symbols': 'ondemand', 10:22:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:22:29 INFO - 'tooltool.py': '/tools/tooltool.py', 10:22:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:22:29 INFO - '/tools/misc-python/virtualenv.py')}, 10:22:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:22:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:22:29 INFO - 'installer_path': 'installer.exe', 10:22:29 INFO - 'log_level': 'info', 10:22:29 INFO - 'log_name': 'talos', 10:22:29 INFO - 'log_to_console': True, 10:22:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:22:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:22:29 INFO - 'opt_config_files': (), 10:22:29 INFO - 'pip_index': False, 10:22:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:22:29 INFO - 'cmd': ('bash', 10:22:29 INFO - '-c', 10:22:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:22:29 INFO - 'enabled': True, 10:22:29 INFO - 'halt_on_failure': False, 10:22:29 INFO - 'name': 'check_screen_resolution'},), 10:22:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:22:29 INFO - 'cmd': ('bash', 10:22:29 INFO - '-c', 10:22:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:22:29 INFO - 'enabled': True, 10:22:29 INFO - 'halt_on_failure': False, 10:22:29 INFO - 'name': 'check_screen_resolution'},), 10:22:29 INFO - 'run_cmd_checks_enabled': True, 10:22:29 INFO - 'sps_profile': False, 10:22:29 INFO - 'sps_profile_interval': 0, 10:22:29 INFO - 'suite': 'dromaeojs', 10:22:29 INFO - 'system_bits': '32', 10:22:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:22:29 INFO - 'title': 't-yosemite-r7-0179', 10:22:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:22:29 INFO - 'use_talos_json': True, 10:22:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:22:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:22:29 INFO - 'work_dir': 'build'} 10:22:29 INFO - [mozharness: 2017-01-29 18:22:29.644220Z] Running clobber step. 10:22:29 INFO - Running pre-action listener: _resource_record_pre_action 10:22:29 INFO - Running main action method: clobber 10:22:29 INFO - rmtree: /builds/slave/test/build 10:22:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:22:32 INFO - Running post-action listener: _resource_record_post_action 10:22:32 INFO - [mozharness: 2017-01-29 18:22:32.163809Z] Finished clobber step (success) 10:22:32 INFO - [mozharness: 2017-01-29 18:22:32.163944Z] Running read-buildbot-config step. 10:22:32 INFO - Running pre-action listener: _resource_record_pre_action 10:22:32 INFO - Running main action method: read_buildbot_config 10:22:32 INFO - Using buildbot properties: 10:22:32 INFO - { 10:22:32 INFO - "project": "", 10:22:32 INFO - "product": "firefox", 10:22:32 INFO - "script_repo_revision": "production", 10:22:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:22:32 INFO - "repository": "", 10:22:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:22:32 INFO - "buildid": "20170129090243", 10:22:32 INFO - "pgo_build": "False", 10:22:32 INFO - "basedir": "/builds/slave/test", 10:22:32 INFO - "buildnumber": 78, 10:22:32 INFO - "slavename": "t-yosemite-r7-0179", 10:22:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:22:32 INFO - "platform": "yosemite_r7", 10:22:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:22:32 INFO - "branch": "mozilla-central", 10:22:32 INFO - "repo_path": "mozilla-central", 10:22:32 INFO - "revision": "77af15f468e84d9a5ff659641737343ffa0444cf", 10:22:32 INFO - "stage_platform": "macosx64", 10:22:32 INFO - "builduid": "92920da917384210874e87f176c5708a", 10:22:32 INFO - "slavebuilddir": "test" 10:22:32 INFO - } 10:22:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 10:22:32 INFO - Running post-action listener: _resource_record_post_action 10:22:32 INFO - [mozharness: 2017-01-29 18:22:32.167329Z] Finished read-buildbot-config step (success) 10:22:32 INFO - [mozharness: 2017-01-29 18:22:32.167468Z] Running download-and-extract step. 10:22:32 INFO - Running pre-action listener: _resource_record_pre_action 10:22:32 INFO - Running main action method: download_and_extract 10:22:32 INFO - mkdir: /builds/slave/test/build/tests 10:22: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')]} 10:22:32 INFO - trying https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 10:22:32 INFO - Downloading https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 10:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 10:22:33 INFO - Downloaded 1199 bytes. 10:22:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 10:22:33 INFO - Using the following test package requirements: 10:22:33 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 10:22:33 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 10:22:33 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 10:22:33 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'jsshell-mac.zip'], 10:22:33 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 10:22:33 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 10:22:33 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 10:22:33 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 10:22:33 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 10:22:33 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:33 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 10:22:33 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:22:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 10:22:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:22:33 INFO - Fetch https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 10:22:34 INFO - Expected file size: 16364573 10:22:34 INFO - Obtained file size: 16364573 10:22:40 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:22:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 10:22:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:22:40 INFO - Fetch https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 10:22:41 INFO - Expected file size: 16364573 10:22:41 INFO - Obtained file size: 16364573 10:22:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 10:22:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:22:48 INFO - Fetch https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 10:22:49 INFO - Expected file size: 14403454 10:22:49 INFO - Obtained file size: 14403454 10:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:49 INFO - trying https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 10:22:49 INFO - Downloading https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 10:22:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 10:22:51 INFO - Downloaded 70155787 bytes. 10:22:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 10:22:51 INFO - mkdir: /builds/slave/test/properties 10:22:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:22:51 INFO - Writing to file /builds/slave/test/properties/build_url 10:22:51 INFO - Contents: 10:22:51 INFO - build_url:https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 10:22:52 INFO - Running post-action listener: _resource_record_post_action 10:22:52 INFO - Running post-action listener: set_extra_try_arguments 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.455457Z] Finished download-and-extract step (success) 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.455586Z] Running populate-webroot step. 10:22:52 INFO - Running pre-action listener: _resource_record_pre_action 10:22:52 INFO - Running main action method: populate_webroot 10:22:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:22:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:22:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:22:52 INFO - '--tppagecycles', 10:22:52 INFO - '7'], 10:22:52 INFO - 'tests': ['tsvgm']}}, 10:22:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:22:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:22:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:22:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:22:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['damp', 'tps']}, 10:22:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'tests': ['damp', 'tps']}, 10:22:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['dromaeo_dom']}, 10:22:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:22:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['basic_compositor_video']}, 10:22:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:22:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other-e10s': {'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tsvgx', 10:22:52 INFO - 'tsvgr_opacity', 10:22:52 INFO - 'tart', 10:22:52 INFO - 'tscrollx', 10:22:52 INFO - 'cart', 10:22:52 INFO - 'tsvg_static']}, 10:22:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:22:52 INFO - 'tsvgr_opacity', 10:22:52 INFO - 'tart', 10:22:52 INFO - 'tscrollx', 10:22:52 INFO - 'cart', 10:22:52 INFO - 'tsvg_static']}, 10:22:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tp5o']}, 10:22:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:22:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s', 10:22:52 INFO - '--xperf_path', 10:22:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:52 INFO - 'tests': ['tp5n']}, 10:22:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--xperf_path', 10:22:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:52 INFO - 'tests': ['tp5n']}}, 10:22:52 INFO - 'talos.zip': {'path': '', 10:22:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:22:52 INFO - Running post-action listener: _resource_record_post_action 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.473972Z] Finished populate-webroot step (success) 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.474111Z] Running create-virtualenv step. 10:22:52 INFO - Running pre-action listener: _resource_record_pre_action 10:22:52 INFO - Running main action method: create_virtualenv 10:22:52 INFO - Creating virtualenv /builds/slave/test/build/venv 10:22:52 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 10:22:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:22:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:22:52 INFO - Using real prefix '/tools/python27' 10:22:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:22:53 INFO - Installing distribute.............................................................................................................................................................................................done. 10:22:56 INFO - Installing pip.................done. 10:22:56 INFO - Return code: 0 10:22:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:22:56 INFO - Reading from file tmpfile_stdout 10:22:56 INFO - Output received: 10:22:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:22:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:22:56 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 10:22:56 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 10:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:56 INFO - 'HOME': '/Users/cltbld', 10:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:56 INFO - 'LOGNAME': 'cltbld', 10:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:56 INFO - 'MOZ_NO_REMOTE': '1', 10:22:56 INFO - 'NO_EM_RESTART': '1', 10:22:56 INFO - 'PAGER': '/bin/cat', 10:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:56 INFO - 'PWD': '/builds/slave/test', 10:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:56 INFO - 'SHELL': '/bin/bash', 10:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:22:56 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:56 INFO - 'USER': 'cltbld', 10:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:56 INFO - 'XPC_FLAGS': '0x0', 10:22:56 INFO - 'XPC_SERVICE_NAME': '0', 10:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:56 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 10:22:56 INFO - Cleaning up... 10:22:56 INFO - Return code: 0 10:22:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:22:56 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 10:22:56 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 10:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:56 INFO - 'HOME': '/Users/cltbld', 10:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:56 INFO - 'LOGNAME': 'cltbld', 10:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:56 INFO - 'MOZ_NO_REMOTE': '1', 10:22:56 INFO - 'NO_EM_RESTART': '1', 10:22:56 INFO - 'PAGER': '/bin/cat', 10:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:56 INFO - 'PWD': '/builds/slave/test', 10:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:56 INFO - 'SHELL': '/bin/bash', 10:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:22:56 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:56 INFO - 'USER': 'cltbld', 10:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:56 INFO - 'XPC_FLAGS': '0x0', 10:22:56 INFO - 'XPC_SERVICE_NAME': '0', 10:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:57 INFO - Downloading/unpacking psutil>=3.1.1 10:22:57 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 10:22:57 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 10:22:57 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 10:22:57 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 10:22:57 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 10:22:57 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 10:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:23:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:01 INFO - Installing collected packages: psutil 10:23:01 INFO - Running setup.py install for psutil 10:23:01 INFO - building 'psutil._psutil_osx' extension 10:23:01 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 10:23:01 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 10:23:01 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 10:23:02 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 10:23:02 INFO - building 'psutil._psutil_posix' extension 10:23:02 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 10:23:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:23:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:23:02 INFO - ^ 10:23:02 INFO - 1 warning generated. 10:23:02 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 10:23:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:02 INFO - Successfully installed psutil 10:23:02 INFO - Cleaning up... 10:23:02 INFO - Return code: 0 10:23:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:02 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:02 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 10:23:02 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 10:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:02 INFO - 'HOME': '/Users/cltbld', 10:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:02 INFO - 'LOGNAME': 'cltbld', 10:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:02 INFO - 'MOZ_NO_REMOTE': '1', 10:23:02 INFO - 'NO_EM_RESTART': '1', 10:23:02 INFO - 'PAGER': '/bin/cat', 10:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:02 INFO - 'PWD': '/builds/slave/test', 10:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:02 INFO - 'SHELL': '/bin/bash', 10:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:02 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:02 INFO - 'USER': 'cltbld', 10:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:02 INFO - 'XPC_FLAGS': '0x0', 10:23:02 INFO - 'XPC_SERVICE_NAME': '0', 10:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:23:02 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 10:23:02 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 10:23:02 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 10:23:02 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 10:23:02 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 10:23:02 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 10:23:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:23:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:23:05 INFO - Installing collected packages: mozsystemmonitor 10:23:05 INFO - Running setup.py install for mozsystemmonitor 10:23:05 INFO - Successfully installed mozsystemmonitor 10:23:05 INFO - Cleaning up... 10:23:05 INFO - Return code: 0 10:23:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:23:05 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')]} 10:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:05 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')]} 10:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:05 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:05 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 10:23:05 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 10:23:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:05 INFO - 'HOME': '/Users/cltbld', 10:23:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:05 INFO - 'LOGNAME': 'cltbld', 10:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:05 INFO - 'MOZ_NO_REMOTE': '1', 10:23:05 INFO - 'NO_EM_RESTART': '1', 10:23:05 INFO - 'PAGER': '/bin/cat', 10:23:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:05 INFO - 'PWD': '/builds/slave/test', 10:23:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:05 INFO - 'SHELL': '/bin/bash', 10:23:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:05 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:05 INFO - 'USER': 'cltbld', 10:23:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:05 INFO - 'XPC_FLAGS': '0x0', 10:23:05 INFO - 'XPC_SERVICE_NAME': '0', 10:23:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:05 INFO - Downloading/unpacking jsonschema==2.5.1 10:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:23:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:08 INFO - Downloading functools32-3.2.3-2.tar.gz 10:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:23:09 INFO - warning: no files found matching '*.txt' 10:23:09 INFO - no previously-included directories found matching 'build' 10:23:09 INFO - no previously-included directories found matching 'dist' 10:23:09 INFO - no previously-included directories found matching '.git*' 10:23:09 INFO - Installing collected packages: jsonschema, functools32 10:23:09 INFO - Running setup.py install for functools32 10:23:09 INFO - warning: no files found matching '*.txt' 10:23:09 INFO - no previously-included directories found matching 'build' 10:23:09 INFO - no previously-included directories found matching 'dist' 10:23:09 INFO - no previously-included directories found matching '.git*' 10:23:09 INFO - Successfully installed jsonschema functools32 10:23:09 INFO - Cleaning up... 10:23:09 INFO - Return code: 0 10:23:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:23:09 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')]} 10:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:09 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')]} 10:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:09 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:09 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 10:23:09 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 10:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:09 INFO - 'HOME': '/Users/cltbld', 10:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:09 INFO - 'LOGNAME': 'cltbld', 10:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:09 INFO - 'MOZ_NO_REMOTE': '1', 10:23:09 INFO - 'NO_EM_RESTART': '1', 10:23:09 INFO - 'PAGER': '/bin/cat', 10:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:09 INFO - 'PWD': '/builds/slave/test', 10:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:09 INFO - 'SHELL': '/bin/bash', 10:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:09 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:09 INFO - 'USER': 'cltbld', 10:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:09 INFO - 'XPC_FLAGS': '0x0', 10:23:09 INFO - 'XPC_SERVICE_NAME': '0', 10:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:23:09 INFO - Cleaning up... 10:23:09 INFO - Return code: 0 10:23:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:23:09 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')]} 10:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:09 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')]} 10:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:09 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:09 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 10:23:09 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 10:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:09 INFO - 'HOME': '/Users/cltbld', 10:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:09 INFO - 'LOGNAME': 'cltbld', 10:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:09 INFO - 'MOZ_NO_REMOTE': '1', 10:23:09 INFO - 'NO_EM_RESTART': '1', 10:23:09 INFO - 'PAGER': '/bin/cat', 10:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:09 INFO - 'PWD': '/builds/slave/test', 10:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:09 INFO - 'SHELL': '/bin/bash', 10:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:09 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:09 INFO - 'USER': 'cltbld', 10:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:09 INFO - 'XPC_FLAGS': '0x0', 10:23:09 INFO - 'XPC_SERVICE_NAME': '0', 10:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:09 INFO - Downloading/unpacking blobuploader==1.2.4 10:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:12 INFO - Downloading blobuploader-1.2.4.tar.gz 10:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:23:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:23:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:13 INFO - Downloading docopt-0.6.1.tar.gz 10:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:23:13 INFO - Installing collected packages: blobuploader, requests, docopt 10:23:13 INFO - Running setup.py install for blobuploader 10:23:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:23:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:23:13 INFO - Running setup.py install for requests 10:23:14 INFO - Running setup.py install for docopt 10:23:14 INFO - Successfully installed blobuploader requests docopt 10:23:14 INFO - Cleaning up... 10:23:14 INFO - Return code: 0 10:23:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:14 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')]} 10:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:14 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')]} 10:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:14 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:14 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 10:23:14 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 10:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:14 INFO - 'HOME': '/Users/cltbld', 10:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:14 INFO - 'LOGNAME': 'cltbld', 10:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:14 INFO - 'MOZ_NO_REMOTE': '1', 10:23:14 INFO - 'NO_EM_RESTART': '1', 10:23:14 INFO - 'PAGER': '/bin/cat', 10:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:14 INFO - 'PWD': '/builds/slave/test', 10:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:14 INFO - 'SHELL': '/bin/bash', 10:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:14 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:14 INFO - 'USER': 'cltbld', 10:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:14 INFO - 'XPC_FLAGS': '0x0', 10:23:14 INFO - 'XPC_SERVICE_NAME': '0', 10:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-KOR8Ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-BPHZR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-DCeMFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-4IeWnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ogeUsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-k83cH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-8VqHFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1Z3yI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-l_g3FW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-BVMIUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Uhl4QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:16 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-8Y7x13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:16 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-btXKeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:16 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-qVEhsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:16 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-AwqANq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:16 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-dYhQ2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:16 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-HsEjb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:23:16 INFO - Running setup.py install for manifestparser 10:23:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:23:16 INFO - Running setup.py install for mozcrash 10:23:16 INFO - Running setup.py install for mozdebug 10:23:17 INFO - Running setup.py install for mozdevice 10:23:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozfile 10:23:17 INFO - Running setup.py install for mozhttpd 10:23:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozinfo 10:23:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozInstall 10:23:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozleak 10:23:17 INFO - Running setup.py install for mozlog 10:23:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Running setup.py install for moznetwork 10:23:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Running setup.py install for mozprocess 10:23:18 INFO - Running setup.py install for mozprofile 10:23:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Running setup.py install for mozrunner 10:23:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Running setup.py install for mozscreenshot 10:23:18 INFO - Running setup.py install for moztest 10:23:19 INFO - Running setup.py install for mozversion 10:23:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:23:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:23:19 INFO - Cleaning up... 10:23:19 INFO - Return code: 0 10:23:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:23:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:19 INFO - 'HOME': '/Users/cltbld', 10:23:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:19 INFO - 'LOGNAME': 'cltbld', 10:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:19 INFO - 'MOZ_NO_REMOTE': '1', 10:23:19 INFO - 'NO_EM_RESTART': '1', 10:23:19 INFO - 'PAGER': '/bin/cat', 10:23:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:19 INFO - 'PWD': '/builds/slave/test', 10:23:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:19 INFO - 'SHELL': '/bin/bash', 10:23:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:19 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:19 INFO - 'USER': 'cltbld', 10:23:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:19 INFO - 'XPC_FLAGS': '0x0', 10:23:19 INFO - 'XPC_SERVICE_NAME': '0', 10:23:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-nTpPIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-MaFWaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-S6CAwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-xhIkNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-xg_Wt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-U5cUOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-EKc6Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-_N42BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-cqIBxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-HwetXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-UbeSkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-xVrNJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-JtbvxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-2aZlKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-SOvn3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-w6nVKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 10:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-dSLWHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:23:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:23 INFO - Downloading blessings-1.6.tar.gz 10:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:23:24 INFO - Installing collected packages: blessings 10:23:24 INFO - Running setup.py install for blessings 10:23:24 INFO - Successfully installed blessings 10:23:24 INFO - Cleaning up... 10:23:24 INFO - Return code: 0 10:23:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:24 INFO - Reading from file tmpfile_stdout 10:23:24 INFO - Current package versions: 10:23:24 INFO - blessings == 1.6 10:23:24 INFO - blobuploader == 1.2.4 10:23:24 INFO - docopt == 0.6.1 10:23:24 INFO - functools32 == 3.2.3-2 10:23:24 INFO - jsonschema == 2.5.1 10:23:24 INFO - manifestparser == 1.1 10:23:24 INFO - mozInstall == 1.12 10:23:24 INFO - mozcrash == 1.0 10:23:24 INFO - mozdebug == 0.1 10:23:24 INFO - mozdevice == 0.49 10:23:24 INFO - mozfile == 1.2 10:23:24 INFO - mozhttpd == 0.7 10:23:24 INFO - mozinfo == 0.9 10:23:24 INFO - mozleak == 0.1 10:23:24 INFO - mozlog == 3.4 10:23:24 INFO - moznetwork == 0.27 10:23:24 INFO - mozprocess == 0.24 10:23:24 INFO - mozprofile == 0.28 10:23:24 INFO - mozrunner == 6.13 10:23:24 INFO - mozscreenshot == 0.1 10:23:24 INFO - mozsystemmonitor == 0.3 10:23:24 INFO - moztest == 0.8 10:23:24 INFO - mozversion == 1.4 10:23:24 INFO - psutil == 3.1.1 10:23:24 INFO - requests == 1.2.3 10:23:24 INFO - wsgiref == 0.1.2 10:23:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:24 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')]} 10:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 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')]} 10:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:24 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 10:23:24 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 10:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:24 INFO - 'HOME': '/Users/cltbld', 10:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:24 INFO - 'LOGNAME': 'cltbld', 10:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:24 INFO - 'MOZ_NO_REMOTE': '1', 10:23:24 INFO - 'NO_EM_RESTART': '1', 10:23:24 INFO - 'PAGER': '/bin/cat', 10:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:24 INFO - 'PWD': '/builds/slave/test', 10:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:24 INFO - 'SHELL': '/bin/bash', 10:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:24 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:24 INFO - 'USER': 'cltbld', 10:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:24 INFO - 'XPC_FLAGS': '0x0', 10:23:24 INFO - 'XPC_SERVICE_NAME': '0', 10:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 INFO - Cleaning up... 10:23:24 INFO - Return code: 0 10:23:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:23:24 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')]} 10:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 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')]} 10:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 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 0x104c54b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c57768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdfe3ce5930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, '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.zeI3ngHb3k/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.95SvTOklM0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 10:23:24 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 10:23:24 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 10:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:24 INFO - 'HOME': '/Users/cltbld', 10:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:24 INFO - 'LOGNAME': 'cltbld', 10:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:24 INFO - 'MOZ_NO_REMOTE': '1', 10:23:24 INFO - 'NO_EM_RESTART': '1', 10:23:24 INFO - 'PAGER': '/bin/cat', 10:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:24 INFO - 'PWD': '/builds/slave/test', 10:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:24 INFO - 'SHELL': '/bin/bash', 10:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:24 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:24 INFO - 'USER': 'cltbld', 10:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:24 INFO - 'XPC_FLAGS': '0x0', 10:23:24 INFO - 'XPC_SERVICE_NAME': '0', 10:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:23:25 INFO - Cleaning up... 10:23:25 INFO - Return code: 0 10:23:25 INFO - Running post-action listener: _resource_record_post_action 10:23:25 INFO - Running post-action listener: _start_resource_monitoring 10:23:25 INFO - Starting resource monitoring. 10:23:25 INFO - [mozharness: 2017-01-29 18:23:25.143099Z] Finished create-virtualenv step (success) 10:23:25 INFO - [mozharness: 2017-01-29 18:23:25.144048Z] Running install step. 10:23:25 INFO - Running pre-action listener: _resource_record_pre_action 10:23:25 INFO - Running main action method: install 10:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:25 INFO - Reading from file tmpfile_stdout 10:23:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:23:25 INFO - Reading from file tmpfile_stdout 10:23:25 INFO - Output received: 10:23:25 INFO - Usage: mozinstall [options] installer 10:23:25 INFO - Options: 10:23:25 INFO - -h, --help show this help message and exit 10:23:25 INFO - -d DEST, --destination=DEST 10:23:25 INFO - Directory to install application into. [default: 10:23:25 INFO - "/builds/slave/test"] 10:23:25 INFO - --app=APP Application being installed. [default: firefox] 10:23:25 INFO - mkdir: /builds/slave/test/build/application 10:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:23:47 INFO - Reading from file tmpfile_stdout 10:23:47 INFO - Output received: 10:23:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:23:47 INFO - Running post-action listener: _resource_record_post_action 10:23:47 INFO - [mozharness: 2017-01-29 18:23:47.342151Z] Finished install step (success) 10:23:47 INFO - [mozharness: 2017-01-29 18:23:47.342296Z] Running run-tests step. 10:23:47 INFO - Running pre-action listener: _resource_record_pre_action 10:23:47 INFO - Running main action method: run_tests 10:23:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:23:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:23:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:23:47 INFO - 2017-01-29 10:23:47.356 screenresolution[1635:8612] starting screenresolution argv=screenresolution get 10:23:47 INFO - 2017-01-29 10:23:47.359 screenresolution[1635:8612] Display 0: 1600x1200x32@60 10:23:47 INFO - 2017-01-29 10:23:47.364 screenresolution[1636:8614] starting screenresolution argv=screenresolution list 10:23:47 INFO - Available Modes on Display 0 10:23:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:23:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:23:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:23:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:23:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:23:47 INFO - Intel Iris: 10:23:47 INFO - Chipset Model: Intel Iris 10:23:47 INFO - Type: GPU 10:23:47 INFO - Bus: Built-In 10:23:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:23:47 INFO - Vendor: Intel (0x8086) 10:23:47 INFO - Device ID: 0x0a2e 10:23:47 INFO - Revision ID: 0x0009 10:23:47 INFO - Displays: 10:23:47 INFO - Display: 10:23:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:23:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:23:47 INFO - Display Serial Number: 1600x1200 60 10:23:47 INFO - Main Display: Yes 10:23:47 INFO - Mirror: Off 10:23:47 INFO - Online: Yes 10:23:47 INFO - Rotation: Supported 10:23:47 INFO - Return code: 0 10:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:23:47 INFO - Python 2.7.3 10:23:47 INFO - Return code: 0 10:23:47 INFO - grabbing minidump binary from tooltool 10:23:47 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')]} 10:23:47 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 0x104a32d30>, '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 0x104959db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a27be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:23:47 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 10:23:47 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 10:23:47 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 10:23:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:23:47 INFO - Return code: 0 10:23:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:23:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:23:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:23:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:23:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 10:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --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 10:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeI3ngHb3k/Render', 10:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:47 INFO - 'HOME': '/Users/cltbld', 10:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:47 INFO - 'LOGNAME': 'cltbld', 10:23:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:23:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:47 INFO - 'MOZ_NO_REMOTE': '1', 10:23:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:23:47 INFO - 'NO_EM_RESTART': '1', 10:23:47 INFO - 'PAGER': '/bin/cat', 10:23:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:47 INFO - 'PWD': '/builds/slave/test', 10:23:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:47 INFO - 'SHELL': '/bin/bash', 10:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.95SvTOklM0/Listeners', 10:23:47 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 10:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:47 INFO - 'USER': 'cltbld', 10:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:47 INFO - 'XPC_FLAGS': '0x0', 10:23:47 INFO - 'XPC_SERVICE_NAME': '0', 10:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 10:23:47 INFO - mozversion application_buildid: 20170129090243 10:23:47 INFO - mozversion application_changeset: 77af15f468e84d9a5ff659641737343ffa0444cf 10:23:47 INFO - mozversion application_display_name: Nightly 10:23:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:23:47 INFO - mozversion application_name: Firefox 10:23:47 INFO - mozversion application_remotingname: firefox 10:23:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:23:47 INFO - mozversion application_vendor: Mozilla 10:23:47 INFO - mozversion application_version: 54.0a1 10:23:47 INFO - mozversion platform_buildid: 20170129090243 10:23:47 INFO - mozversion platform_changeset: 77af15f468e84d9a5ff659641737343ffa0444cf 10:23:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:23:47 INFO - mozversion platform_version: 54.0a1 10:23:47 INFO - using testdate: 1485714227 10:23:47 INFO - actual date: 1485714227 10:23:47 INFO - starting webserver on 'localhost:49241' 10:23:47 INFO - SUITE-START | Running 2 tests 10:23:47 INFO - TEST-START | dromaeo_css 10:23:47 INFO - operating with platform_type : mac_ 10:23:47 INFO - Initialising browser for dromaeo_css test... 10:23:47 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpLpcqKc/profile http://localhost:49241/getInfo.html) 10:23:50 INFO - PROCESS | 1646 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 10:23:50 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 10:23:50 INFO - PROCESS | 1646 | colorDepth:24 10:23:50 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 10:23:50 INFO - PROCESS | 1646 | __metrics 10:23:50 INFO - PROCESS | 1646 | 1485714230478 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 10:23:50 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:23:50 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:23:51 INFO - TEST-INFO | 1646: exit 0 10:23:51 INFO - Browser initialized. 10:23:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:23:51 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpLpcqKc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:03 INFO - PROCESS | 1649 | RSS: Main: 236294144 10:24:03 INFO - PROCESS | 1649 | 10:27:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:27:08 INFO - PROCESS | 1649 | RSS: Main: 414806016 10:27:08 INFO - PROCESS | 1649 | 10:28:52 INFO - PROCESS | 1649 | ************************* 10:28:52 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 10:28:52 INFO - PROCESS | 1649 | 10:28:52 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:28:52 INFO - PROCESS | 1649 | Full stack: 10:28:52 INFO - PROCESS | 1649 | ************************* 10:30:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:30:12 INFO - PROCESS | 1649 | RSS: Main: 384630784 10:30:12 INFO - PROCESS | 1649 | 10:33:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:33:15 INFO - PROCESS | 1649 | RSS: Main: 356601856 10:33:15 INFO - PROCESS | 1649 | 10:36:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:36:18 INFO - PROCESS | 1649 | RSS: Main: 351195136 10:36:18 INFO - PROCESS | 1649 | 10:39:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:39:22 INFO - PROCESS | 1649 | RSS: Main: 333021184 10:39:22 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:42:25 INFO - PROCESS | 1649 | RSS: Main: 452870144 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | __start_tp_report 10:42:25 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 10:42:25 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 10:42:25 INFO - PROCESS | 1649 | |i|pagename|runs| 10:42:25 INFO - PROCESS | 1649 | |0;dojo.html;3129.217695576106;3295.340931813638;3302.3395320935806;3322.053214833773;3354.7819391739536;5508.14623733321;5529.944700552994;5592.832215033545;5597.6641401515935;5605.990921634104;4749.358836557064;5559.360673522546;5591.8042868499615;5597.524210442107;5650.5479561635075;5079.517445842639;5103.668261563005;5115.13042782726;5145.588352931775;5164.741762911858;5134.614903882205;5210.296609957664;5225.790968361261;5234.18870075139;5277.999999999981;5092.8217512387255;5105.642605017651;5202.713850738208;5206.947930520684;5226.92159617606;5089.54194122527;5126.179811230185;5143.614228932807;5148.588112950993;5200.427952925213;5080.415752188509;5136.794528218867;5140.460251673595;5149.2791009258735;5152.046871328816;5645.463680950304;5646.322441307028;5655.236543066716;5655.26481557399;5668.43315668434;6000.819975400745;6025.909611355789;6028.608140470856;6078.179445774808;6130.509559235251;6173.475254603364;6243.687815609247;6244;6260.15487909131;6261.154744109533;5732.3121225453215;5739.024365857814;5743.540516758733;5749.856253593652;5765.913511297334;6166.229221347332;6203.503719702413;6208.255009398901;6252.562320637512;6283.622982621057;6027.78902738402;6034.125051867454;6042.516598672096;6059.575829691968;6106.328303886569;6026.849328766728;6192.716622844065;6227.252729672468;6228.719707613168;6236.532260080512;5980.611260268069;6025.6685882276015;6043.637381757108;6068.908966365508;6090.756369745161;6161.537884658668;6171.290301615282;6174.228221472316;6178.165947597017;6193.81418557444;4886.926696099561;4928.802847886116;4937.160682683881;4937.456879743226;4969.975150124226;4375.16871794358;4393.934090988638;4409.867703968886;4480.775961201927;4518.028623845889;5671.035923892948;5678.744456499468;5679.148127780866;5680.488756011979;5686.658800471985;6104.550104788525;6139.400144844221;6145.293291271471;6148.1085242640465;6163.599366041193;5652.095664693629;5674.716264186774;5676.971615141898;5763;5770.451807078321;5391.353037635509;5393.460653934653;5394.94798514286;5397.27136836522;5405.7837686492885;6276.403741644536;6278.30938596754;6289.088082228142;6292.685365731695;6294.307626161119;6058.151858739692;6093.482054025458;6099.298580663103;6119.112728654409;6122.855605810045;6266.937330626818;6268.968655156695;6287.339829317856;6298.433141017331;6300.21247344082;6101.908471373022;6102.664353460602;6106.877862442638;6116.541259405473;6117.938820611737;6050.697465126638;6125.632462052291;6143.078538219303;6216.098665693358;6216.844578885564;5255.448177941261;5807.070868660994;5870.236869206976;5940.732667030081;6025.276966764016;6011.368806275278;6014.849628759141;6014.879702406015;6015.368386319548;6043.425874541911;5615.185798059177;5801.810628820997;5819.505342045804;5835.79574714883;5877.265341832271;5792.536597072309;5866.6186697864505;5871.6770577618645;5873.853153671193;5875.970620146872;5712.228849105318;5723.427657234243;5723.771049158154;5733.885322293614;5740.827775166752 10:42:25 INFO - PROCESS | 1649 | |1;ext.html;456.72368217228575;479.16385906592956;495.6307550874599;516.2205070343781;519.8388499565135;30362.544561831564;31883;32170.356592868127;41473.377899331535;41500;41053.58946410535;42635.3604695929;42682;42697.57302426975;42850.142997140036;9183.265338772899;9349.111834375723;9359.251259899225;9364.204042656382;9375.765605859873;8347.624356903923;8556.144385561456;10982.670519884416;11187.4965626547;11225.214235003554;11131.666050018512;11180.273282236629;11184.44077796111;11187.49656265466;11223.60717374892;11160.218784685076;11230.32618042916;11232.831507527393;11250.043746281575;11262;8259.710910118118;8505.659773609048;8507.31941444689;8621.870671939925;8626.482411055356;38840.41739373912;39727.60272397297;39735.40396894049;41670.79164604157;41706.582934170874;4005.959940400588;4011.859584914543;4044.4539987101657;4047.3524236122375;4164;30251.243718906982;30852;30964.535531967038;30981;31072.378552428825;3051.8016328938547;3180.475221588435;3221.1624977505785;3281.753868459875;3288.9506657400157;474680;478253;478411;478654;478909;60588.39411605916;61104.38895611076;61117.08324375138;61121;61180.38819611836;64138.02067710524;64175.679121605026;64204;64213;64382.67808660927;15922.840771592368;16142.596435089217;16159.353625855068;16175;16243.350265989464;11205.99146076857;12481.063920206021;12552.184108032949;12570.434330455151;12572.748545029232;13265.801012984813;14547.636309092357;16667.249973750968;16751.994880307222;17171.31314747396;6096.359882212392;6227.567659438355;7268.527545709512;7284.890726639104;7361.705531778776;773.8258891749311;803.3894240377356;856.4176360075209;876.1501343696605;898.7573355194061;28342.71657283442;29158.459892620333;29173.56239656407;29901.25246868802;29969.40061198764;957.9568919398505;1048.6015314180563;1049.3231865446744;1081.8701755789198;1083.3283364314175;15196.468123615841;15324.923375383052;15349.002314849498;15448.536543903701;15557.922210389102;639397;644068;644493;647101;647467;60531.69734151389;60934;60946;61145.08282375768;61196.38803611907;1259.974800504003;1417.340936464549;1437.4681367893945;1437.8993470456967;1446.7178900114377;27923.860380697966;28612;29018.419631606826;29409.705902940696;29562.113136605938;49584.75207623939;49966.50033499764;49976.75011624918;50046.749766250934;50110.24834627556;555428;556883;559456;560491;562177.1891140518;25865.612015820156;26204.344891377867;26227.868860656337;26242;26273.080442184433;47032;47386;47628.7618561905;48031.79920502016;48626.756866215444;38694.6130538691;38968.025799355244;39266.62566810148;39837.80486585407;40812.59187408088;38306.23387532178;38985.805070974464;39043.804780975915;39592.2752142297;41018;37020;37553;37893.24213515769;37980.62019379771;38069.619303806605;30404.847975759978;33001.66998329986;33403.83298083494;33499;33604.495932560545;24326.51346973015;24460.755392445848;24506.75493245116;24572.75427245776;25319.86060627313 10:42:25 INFO - PROCESS | 1649 | |2;jquery.html;371.85125949620146;378.5514165713631;396.9186316805054;397.8070635741665;411.5452425070296;15446.691066178684;15577.922110389447;18532.629347413043;20778.376648700527;21310.89344553279;21536.246231381905;21635.891820540914;21858.89070554649;21935.122595096178;22216.666749998723;5769.423057694239;5821.941780582193;5956.16613674086;6048;6067.666278354689;5917.437843404891;5965.61223520472;5973.372795856438;5977.551683623724;5981.312149102865;5675.574331925122;5933;5979.701014949257;6012.879742405149;6072.72672729726;5801.3328467225965;5985.132155837401;6028.0355143176885;6033.09503574463;6034.36639152887;5823.67969761663;5914.349421563625;5915.349311575723;5918.585699001072;5941.584089113764;22468.438289042904;25128;25828.612570811452;26011;26078.73921260782;2293.598620241451;2888.494513460157;2994.625671791026;3010.412969470954;3096.9535456968165;10021.599136034623;12102.455389507493;15099;15622.843771562366;15820.971636843567;1852.8332450079422;1870.962580748391;1893.346795355614;1895.2229585869866;1904.4096330137702;115666.42166789196;138385;139657;139863.99999999898;142390.2880485591;11427.60003399877;11626.128040397005;11722.824157637642;11817.409129543488;11957.820632690517;15460.304286307142;15491.690166196613;15511.146886921102;15517.146556939251;15547.689046219013;8317.148294195033;8706.085860984633;8721.694740684172;8805.69180078694;8831.779205519913;8270.37972152079;8405.789855253544;8461.11158328379;8589.742307730778;8592.8711069334;4665.790039448233;4683.110209060268;4683.203855344532;4706.3646407735205;4809.350737650442;1958.177565422526;2001.9899900500695;2004.5489764803094;2011.718359429681;2018.9192432302543;791.600241877848;828.5318794880873;830.4850992384639;840.4495055738502;851.9616617252238;21375.67936480954;22114.557708845732;22255;22276.443088922908;22414.215502457337;836.3894357119337;852.861429990969;858.9742307730778;868.4268382867277;872.7861673889811;9842.852357214648;10485.947570262253;11189.944050279859;11522.884771152349;11585.189036767346;670370;721154;734473.3276333691;739745.3012734902;741016;157991;159192;162514.18742906448;167093;167239;883.5847151838625;971.3055165556661;984.7193549838262;1009.2884516415909;1009.9446078847621;16110.275037623102;17040.57398565046;18084.101169377027;19275.4217373479;19562.315318963767;59043;59303;59456;59645.403545965724;60458.697706511186;84123;88121;89527.55236223777;90231.54884225538;91047;7845.3723702104835;8089.150639182801;8199.22107399796;8239.423240373117;8394.24451799341;12040.819387709016;12905.870941290466;13083.869161308647;13401.86598134006;13414.195148291134;11055.889441105486;11224.214304998572;11361.318320900773;11432.628084629898;11507.597234096766;9372.625094996123;11295.661130166109;11319.320840749582;11340.716482087684;11459;9999.600015999278;10553.947230263799;11051.005409512862;11644.825327620261;11654.592089276835;8146.185381462044;8227.300679442315;8234.547099909323;8304.750857474286;8506.445030607723;8744.606492707717;10130.79738405213;10361.4301213434;11269.098472122374;11300.886991130312 10:42:25 INFO - PROCESS | 1649 | |3;mootools.html;1307.3659275251496;1419.453510398497;1432.7779194224893;1444.162385816227;1444.1840360196488;7414.777556673302;7767.106782719989;7944.96027519863;8254.133316001824;8264.297534709542;7929.682812687485;8153.184681531859;8163.714270000552;8167.346612271019;8217.21936416041;2055.290924631;2254.579614842647;2297.3797074789795;2310.3992961829877;2371.4427109629223;2128.8296936245065;2260.9095636174534;2325.197806756666;2333.9066437342485;2338.3920180752957;2276.9203077892275;2281.1445707859552;2300.3559003478967;2302.1251924268754;2302.4934514406805;2285.4514916420108;2306.6770652108717;2308.35366097493;2309.8383113182085;2311.5954707926044;2251.8311126665567;2313.2944451942153;2316.3630001749484;2317.733460652013;2323.0591610397814;8132.430729848913;8192.016957965023;8226.326443905695;8257.710980115668;8280.958595207047;4470.8435204767675;4965.801367945278;5133.614978876599;5134.64057515974;5136.332276803992;3007.8496075196153;3348.4809854472596;3492.3015396920464;3550.3254381667657;3558.395072837624;1203.3201241298725;1211.4669545399995;1226.564569577796;1287.3949243855373;1290.870912908711;10352.637657681946;10514.369137851676;10563.577456901714;10564.313319634277;10602.893971060268;4400.559944005638;4565.703229290086;4651.558101980307;4660.161170989187;4667.929981050287;9342.90657093434;9357.26890525255;9361.578728957213;9362.859557106513;9390.389624674512;5201.973990130025;5205.531502164823;5208.869778255574;5211.1922651989;5212.687238765686;4554.134714404318;4565.771711414416;4711.175544279738;4734.384530011076;4764.95235047652;3481.373352796521;3784.905377365533;3812.2375524895133;3846.1730727893637;3893.980530097331;1263.9620811375673;1289.9226046437243;1293.4373547506864;1308.483149156078;1314.835645544307;840.9243168114899;900.4777229207044;907.7322189953848;929.3076657889916;944.9412885743485;5353.785848566014;5455.908068948289;5472.2338872557875;5486.423925487846;5594.580406469531;550.3313474128953;551.1127085392515;557.1948534367881;572.7365411910484;576.0955300178763;3198.3603279344225;3444.689977901951;3555.9644403555635;3593.6406359363855;3607.6933460655614;9305.209057230211;9733.318667693193;9739.707808765748;9760.609575617038;9814.75463113428;9674.03259674041;9704.75738106553;9705.369151005161;9719.65981190655;9735.65925192629;7570.091589009354;7691.46159768822;7702.460827742117;7702.691892324245;7752.069751629728;4393.472783266028;4630.86107416778;5522.447755224445;5607.635503692247;5679.886402271849;8838.734837954615;8861.867071993796;8869.556522173994;8895.777605559913;9099.545022748704;8957.641694332153;9108.544572771467;9123;9202.769930751785;9230.215431688113;6594.142761440982;6822.590644561342;6848.931510685029;7000.964995174992;7193.892091618734;7824.491408058458;7913.841723165619;8000.279974802295;8002.719904803537;8004.318553284715;7047.929520704933;7091.82270443243;7103.431725462054;7136.536125151849;7149.106361704787;6888.655567221719;6964.408025317702;6971;6988.79033628992;7009.684564194726;6248.812535623939;6345.238571371465;6379.393957574023;6384.712687928962;6387.616742995435;4770.0936822003705;4885.291632713308;4946.604271658331;4977.129002424634;4988.201887697951;4835.758212089452;4923;5026.070177017264;5029.823956161663;5117.43708192106 10:42:25 INFO - PROCESS | 1649 | |4;prototype.html;305.55999360920276;353.9716822654188;370.7330721880246;374.6253746253746;375.4368447329006;4363.454568178978;5010.398752149738;5203.401608814987;5265.289185959894;5332.760025798839;4431.645468362532;5131.358580177478;5139.640225184238;5176.249443830642;5268.9473105269035;3524.4537096750046;4179.582041795811;4184.372344148372;4185.623293903548;4197.139586384799;3598.9280214395826;4013.5585085640555;4199.1391764688315;4200.348945913388;4206.411102445645;3349.3133907548895;3538.7345949053924;4121.896952576195;4138.965258685329;4147.8963025924295;4135.421041054255;4136.786819051823;4141.337386018237;4144.689148313858;4156.314208155651;3121.5161649944293;3677.17263615687;3683.28176005679;3685.9126557665554;3705.5923848376656;4302.41917341158;4461.486929003174;5113.642045056848;5147.819826306099;5205.219217117463;3939.428782826503;4004.9199016019807;4301.827926882921;4728.69263497875;4821.566059054702;4961.957988822352;5390;5562.833115006556;5589.217509548666;5598.86002849932;3865.260632715261;4041.353383458662;4103.528094269167;4107.322291821846;4114.086949568436;5098.745062746885;5804.390538993387;5825.825225243207;5836.941630583639;5853.912191317134;3797.8290976906105;3844.6539811416546;3846.807659617008;4110.856120035784;4137.358709400048;3920.5883382244456;4067.8372865085657;4161.708680392343;4260.783108752872;4267.253230684618;4229.746215227096;4606.723596584216;4744.461712983537;4758.381410416693;4800.831970881002;4578.214496346428;5145.61407894402;5611.8597035073635;5612.520237452723;5657.464084946908;1179.988200118005;1265.8987281017496;1282.762688902556;1299.382793173235;1331.5406184866206;963.6530848894392;977.9340518834505;989.5101924547251;993.6869885985891;997.9471657401366;4730.858074257778;4976.308055261228;4993.700377977333;5002.623753717028;5031.52200540948;4754.36449461772;4798.113528042297;4914.950850491521;5343.251944727741;5438.972805136028;4664.813407463731;4938.777755001033;4966.751662416865;4978.854863381443;4984.825531106394;2460.6678098456478;2575.0343621142074;2697.2063577720596;2841.3891013432212;2862.266535458632;5005.3242812220615;5138.715321169708;5226.425093239741;5261.5264626183825;5538.363088244903;5207.4271830099415;5509.729959281509;5559.77760889568;5566.888662226814;5578.330600327987;4625.468071171858;5066.873328166752;5227.503387178288;5499.395066542757;5612.494875461228;5209.505097015777;5420.972895135499;5444.455554444524;5451.4276001019325;5463.552158678033;4550.361869726962;5077.949220507748;5347.652402593819;5366.604682782427;5368.677879327252;4918.483559226373;5497.821655541897;5587.916181257203;5589.5248903842;5636;3951;3952.7430717003303;3955.5055618047745;3956.6043395660204;3957.5053118360206;4392.538783427822;4554.88612784683;4738.607003463229;4892.730899800546;4917.28699338601;4463.419755431773;5408.648437851527;5454.29094217765;5517.393086760373;5610.326760788732;4716.599089077329;4940.702573303288;5554.777808887761;5569.331680198402;5574.296466213802;4974.154393753143;5095.185050127594;5416.45835416471;5428.891422171456;5440.510354067995;4798.416522547481;5233.685978841282;5510.504054635102;5521.585881058856;5654.802081927113;5272.945410917755;5305.912287980877;5499.560035197094;5500.147477141049;5501.367342755634 10:42:25 INFO - PROCESS | 1649 | |5;yui.html;617.9474744646704;652.259685257233;762.5500954436884;827.5489858027374;839.2195258409677;15047.623809404771;16114.952528085667;16135.112568808725;16141.11223882686;16175.757363639525;13492.66268343292;14644.341004654789;14689.265536723175;14757.409703611842;14786.40854365824;877.4428238068808;878.6090189865513;894.66897248018;896.2740180453853;896.668232753882;889.6219106879582;891.3849443883732;892.687559354224;893.7631527645179;895.9462432254053;937.7608709778997;938.4885237545554;939.1125386509751;939.783849714566;939.9859002114973;939.8073394954052;939.8543225800012;940.5720397219249;940.7695114696909;940.8635747816596;701.5369855895136;704.3132945378267;704.5807744392079;705.28765946394;707.0419581467141;13832.585022449342;14038.227897465635;14956.775648365283;15382.923085384502;15581.90926635136;13824.516141934984;13904.513342033082;13906.860931390758;13948.442062317496;14098.224597647126;9864.90135098647;11518.884811151866;11652.184347095706;11665;12180.330081845495;3633.854645814164;3699.6855267302312;3728.869489567879;3734.5705243897028;3776.754510956779;81152.59423702903;102940.94118117596;104768.95231047591;104946.95053049448;106048.46975765149;10546.472676366151;10657.360558366523;10825;11110.61112861046;11157.218994670457;21303.25438609641;21602.56794864094;21625;22034.8982550875;22049.33851984443;6281.811545653638;7263.455240856957;7345.669444874993;7357.456140350899;7477.214892436325;4255.638270746959;4296.892577685583;4333.090051089306;4348.369486424451;4436.977815110904;3527.8588856445967;3590.174259920233;3590.4434812604086;3615.6022837487853;3630.7821530708243;500.61452681435094;500.68206688752906;503.5442924153647;504.100181176598;509.10143596552115;634.194572892424;663.271114542959;677.576514678326;686.4679873098389;688.2243810967691;9080.22818060459;9379.296552758571;9683.983181765954;9772.413655180711;9811.803763924825;599.4724642314823;621.254494606474;647.8574713563008;650.5316172355896;651.8826611209932;4678.672492925462;4832.8791780205775;4836.733979631154;4886.144924638245;4908.8772780680765;101806.49096754617;104921;106055;106349.93650063401;107222.92777072129;63151;65378;85016.57491712502;87318.12681873227;87956.56021719979;75346.94448888152;78378;78565.21434785693;80046;80610;9698.660546880825;9985.201183905416;10100.898991009995;10183.439910804977;10351.482425878827;38579.42130867983;47415.52584474111;48385.27422088601;48614;49191.754041229564;66057;67028;67193.66403167952;68630;69533.65233173802;11524.82712759326;11715.297082175097;11871.940640297089;12187.268763874195;12308.876911230773;13693.515240052255;13785.51750688721;14062.929685351508;14170;14326.283685815875;12666.556670516487;12943.935280323538;13008.739825203254;13078.607641770763;13509.729805404033;12788.232706037668;12811.615651530468;12887.29119898377;12941.805872912102;13313.800292995806;12295.69260768488;12594.496220151457;12826.166299190523;12967.610971670865;12968.870311296767;10246;10534.94732526363;10716.464176790972;10745.570177192825;10836.783264334827;10066.144377727682;10305.330153539995;10314.071733544017;10343.689689309333;10363.222758293008 10:42:25 INFO - PROCESS | 1649 | __end_tp_report 10:42:25 INFO - PROCESS | 1649 | __start_cc_report 10:42:25 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1980 10:42:25 INFO - PROCESS | 1649 | __end_cc_report 10:42:25 INFO - PROCESS | 1649 | __startTimestamp1485715345379__endTimestamp 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | ------- Summary: start ------- 10:42:25 INFO - PROCESS | 1649 | Number of tests: 6 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5661.07 Median:5792.54 stddev:625.76 (10.8%) stddev-sans-first:595.32 10:42:25 INFO - PROCESS | 1649 | Values: 3129.2 3295.3 3302.3 3322.1 3354.8 5508.1 5529.9 5592.8 5597.7 5606.0 4749.4 5559.4 5591.8 5597.5 5650.5 5079.5 5103.7 5115.1 5145.6 5164.7 5134.6 5210.3 5225.8 5234.2 5278.0 5092.8 5105.6 5202.7 5206.9 5226.9 5089.5 5126.2 5143.6 5148.6 5200.4 5080.4 5136.8 5140.5 5149.3 5152.0 5645.5 5646.3 5655.2 5655.3 5668.4 6000.8 6025.9 6028.6 6078.2 6130.5 6173.5 6243.7 6244.0 6260.2 6261.2 5732.3 5739.0 5743.5 5749.9 5765.9 6166.2 6203.5 6208.3 6252.6 6283.6 6027.8 6034.1 6042.5 6059.6 6106.3 6026.8 6192.7 6227.3 6228.7 6236.5 5980.6 6025.7 6043.6 6068.9 6090.8 6161.5 6171.3 6174.2 6178.2 6193.8 4886.9 4928.8 4937.2 4937.5 4970.0 4375.2 4393.9 4409.9 4480.8 4518.0 5671.0 5678.7 5679.1 5680.5 5686.7 6104.6 6139.4 6145.3 6148.1 6163.6 5652.1 5674.7 5677.0 5763.0 5770.5 5391.4 5393.5 5394.9 5397.3 5405.8 6276.4 6278.3 6289.1 6292.7 6294.3 6058.2 6093.5 6099.3 6119.1 6122.9 6266.9 6269.0 6287.3 6298.4 6300.2 6101.9 6102.7 6106.9 6116.5 6117.9 6050.7 6125.6 6143.1 6216.1 6216.8 5255.4 5807.1 5870.2 5940.7 6025.3 6011.4 6014.8 6014.9 6015.4 6043.4 5615.2 5801.8 5819.5 5835.8 5877.3 5792.5 5866.6 5871.7 5873.9 5876.0 5712.2 5723.4 5723.8 5733.9 5740.8 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:69447.82 Median:39155.22 stddev:150834.43 (385.2%) stddev-sans-first:151168.62 10:42:25 INFO - PROCESS | 1649 | Values: 456.7 479.2 495.6 516.2 519.8 30362.5 31883.0 32170.4 41473.4 41500.0 41053.6 42635.4 42682.0 42697.6 42850.1 9183.3 9349.1 9359.3 9364.2 9375.8 8347.6 8556.1 10982.7 11187.5 11225.2 11131.7 11180.3 11184.4 11187.5 11223.6 11160.2 11230.3 11232.8 11250.0 11262.0 8259.7 8505.7 8507.3 8621.9 8626.5 38840.4 39727.6 39735.4 41670.8 41706.6 4006.0 4011.9 4044.5 4047.4 4164.0 30251.2 30852.0 30964.5 30981.0 31072.4 3051.8 3180.5 3221.2 3281.8 3289.0 474680.0 478253.0 478411.0 478654.0 478909.0 60588.4 61104.4 61117.1 61121.0 61180.4 64138.0 64175.7 64204.0 64213.0 64382.7 15922.8 16142.6 16159.4 16175.0 16243.4 11206.0 12481.1 12552.2 12570.4 12572.7 13265.8 14547.6 16667.2 16752.0 17171.3 6096.4 6227.6 7268.5 7284.9 7361.7 773.8 803.4 856.4 876.2 898.8 28342.7 29158.5 29173.6 29901.3 29969.4 958.0 1048.6 1049.3 1081.9 1083.3 15196.5 15324.9 15349.0 15448.5 15557.9 639397.0 644068.0 644493.0 647101.0 647467.0 60531.7 60934.0 60946.0 61145.1 61196.4 1260.0 1417.3 1437.5 1437.9 1446.7 27923.9 28612.0 29018.4 29409.7 29562.1 49584.8 49966.5 49976.8 50046.7 50110.2 555428.0 556883.0 559456.0 560491.0 562177.2 25865.6 26204.3 26227.9 26242.0 26273.1 47032.0 47386.0 47628.8 48031.8 48626.8 38694.6 38968.0 39266.6 39837.8 40812.6 38306.2 38985.8 39043.8 39592.3 41018.0 37020.0 37553.0 37893.2 37980.6 38069.6 30404.8 33001.7 33403.8 33499.0 33604.5 24326.5 24460.8 24506.8 24572.8 25319.9 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:40532.16 Median:3002.52 stddev:120787.62 (4022.9%) stddev-sans-first:121088.81 10:42:25 INFO - PROCESS | 1649 | Values: 371.9 378.6 396.9 397.8 411.5 15446.7 15577.9 18532.6 20778.4 21310.9 21536.2 21635.9 21858.9 21935.1 22216.7 5769.4 5821.9 5956.2 6048.0 6067.7 5917.4 5965.6 5973.4 5977.6 5981.3 5675.6 5933.0 5979.7 6012.9 6072.7 5801.3 5985.1 6028.0 6033.1 6034.4 5823.7 5914.3 5915.3 5918.6 5941.6 22468.4 25128.0 25828.6 26011.0 26078.7 2293.6 2888.5 2994.6 3010.4 3097.0 10021.6 12102.5 15099.0 15622.8 15821.0 1852.8 1871.0 1893.3 1895.2 1904.4 115666.4 138385.0 139657.0 139864.0 142390.3 11427.6 11626.1 11722.8 11817.4 11957.8 15460.3 15491.7 15511.1 15517.1 15547.7 8317.1 8706.1 8721.7 8805.7 8831.8 8270.4 8405.8 8461.1 8589.7 8592.9 4665.8 4683.1 4683.2 4706.4 4809.4 1958.2 2002.0 2004.5 2011.7 2018.9 791.6 828.5 830.5 840.4 852.0 21375.7 22114.6 22255.0 22276.4 22414.2 836.4 852.9 859.0 868.4 872.8 9842.9 10485.9 11189.9 11522.9 11585.2 670370.0 721154.0 734473.3 739745.3 741016.0 157991.0 159192.0 162514.2 167093.0 167239.0 883.6 971.3 984.7 1009.3 1009.9 16110.3 17040.6 18084.1 19275.4 19562.3 59043.0 59303.0 59456.0 59645.4 60458.7 84123.0 88121.0 89527.6 90231.5 91047.0 7845.4 8089.2 8199.2 8239.4 8394.2 12040.8 12905.9 13083.9 13401.9 13414.2 11055.9 11224.2 11361.3 11432.6 11507.6 9372.6 11295.7 11319.3 11340.7 11459.0 9999.6 10553.9 11051.0 11644.8 11654.6 8146.2 8227.3 8234.5 8304.8 8506.4 8744.6 10130.8 10361.4 11269.1 11300.9 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5332.58 Median:5211.94 stddev:2903.82 (55.7%) stddev-sans-first:2896.21 10:42:25 INFO - PROCESS | 1649 | Values: 1307.4 1419.5 1432.8 1444.2 1444.2 7414.8 7767.1 7945.0 8254.1 8264.3 7929.7 8153.2 8163.7 8167.3 8217.2 2055.3 2254.6 2297.4 2310.4 2371.4 2128.8 2260.9 2325.2 2333.9 2338.4 2276.9 2281.1 2300.4 2302.1 2302.5 2285.5 2306.7 2308.4 2309.8 2311.6 2251.8 2313.3 2316.4 2317.7 2323.1 8132.4 8192.0 8226.3 8257.7 8281.0 4470.8 4965.8 5133.6 5134.6 5136.3 3007.8 3348.5 3492.3 3550.3 3558.4 1203.3 1211.5 1226.6 1287.4 1290.9 10352.6 10514.4 10563.6 10564.3 10602.9 4400.6 4565.7 4651.6 4660.2 4667.9 9342.9 9357.3 9361.6 9362.9 9390.4 5202.0 5205.5 5208.9 5211.2 5212.7 4554.1 4565.8 4711.2 4734.4 4765.0 3481.4 3784.9 3812.2 3846.2 3894.0 1264.0 1289.9 1293.4 1308.5 1314.8 840.9 900.5 907.7 929.3 944.9 5353.8 5455.9 5472.2 5486.4 5594.6 550.3 551.1 557.2 572.7 576.1 3198.4 3444.7 3556.0 3593.6 3607.7 9305.2 9733.3 9739.7 9760.6 9814.8 9674.0 9704.8 9705.4 9719.7 9735.7 7570.1 7691.5 7702.5 7702.7 7752.1 4393.5 4630.9 5522.4 5607.6 5679.9 8838.7 8861.9 8869.6 8895.8 9099.5 8957.6 9108.5 9123.0 9202.8 9230.2 6594.1 6822.6 6848.9 7001.0 7193.9 7824.5 7913.8 8000.3 8002.7 8004.3 7047.9 7091.8 7103.4 7136.5 7149.1 6888.7 6964.4 6971.0 6988.8 7009.7 6248.8 6345.2 6379.4 6384.7 6387.6 4770.1 4885.3 4946.6 4977.1 4988.2 4835.8 4923.0 5026.1 5029.8 5117.4 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4430.26 Median:4928.63 stddev:1292.69 (26.2%) stddev-sans-first:1258.70 10:42:25 INFO - PROCESS | 1649 | Values: 305.6 354.0 370.7 374.6 375.4 4363.5 5010.4 5203.4 5265.3 5332.8 4431.6 5131.4 5139.6 5176.2 5268.9 3524.5 4179.6 4184.4 4185.6 4197.1 3598.9 4013.6 4199.1 4200.3 4206.4 3349.3 3538.7 4121.9 4139.0 4147.9 4135.4 4136.8 4141.3 4144.7 4156.3 3121.5 3677.2 3683.3 3685.9 3705.6 4302.4 4461.5 5113.6 5147.8 5205.2 3939.4 4004.9 4301.8 4728.7 4821.6 4962.0 5390.0 5562.8 5589.2 5598.9 3865.3 4041.4 4103.5 4107.3 4114.1 5098.7 5804.4 5825.8 5836.9 5853.9 3797.8 3844.7 3846.8 4110.9 4137.4 3920.6 4067.8 4161.7 4260.8 4267.3 4229.7 4606.7 4744.5 4758.4 4800.8 4578.2 5145.6 5611.9 5612.5 5657.5 1180.0 1265.9 1282.8 1299.4 1331.5 963.7 977.9 989.5 993.7 997.9 4730.9 4976.3 4993.7 5002.6 5031.5 4754.4 4798.1 4915.0 5343.3 5439.0 4664.8 4938.8 4966.8 4978.9 4984.8 2460.7 2575.0 2697.2 2841.4 2862.3 5005.3 5138.7 5226.4 5261.5 5538.4 5207.4 5509.7 5559.8 5566.9 5578.3 4625.5 5066.9 5227.5 5499.4 5612.5 5209.5 5421.0 5444.5 5451.4 5463.6 4550.4 5077.9 5347.7 5366.6 5368.7 4918.5 5497.8 5587.9 5589.5 5636.0 3951.0 3952.7 3955.5 3956.6 3957.5 4392.5 4554.9 4738.6 4892.7 4917.3 4463.4 5408.6 5454.3 5517.4 5610.3 4716.6 4940.7 5554.8 5569.3 5574.3 4974.2 5095.2 5416.5 5428.9 5440.5 4798.4 5233.7 5510.5 5521.6 5654.8 5272.9 5305.9 5499.6 5500.1 5501.4 10:42:25 INFO - PROCESS | 1649 | 10:42:25 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:19845.91 Median:21178.09 stddev:28494.17 (134.5%) stddev-sans-first:28537.52 10:42:25 INFO - PROCESS | 1649 | Values: 617.9 652.3 762.6 827.5 839.2 15047.6 16115.0 16135.1 16141.1 16175.8 13492.7 14644.3 14689.3 14757.4 14786.4 877.4 878.6 894.7 896.3 896.7 889.6 891.4 892.7 893.8 895.9 937.8 938.5 939.1 939.8 940.0 939.8 939.9 940.6 940.8 940.9 701.5 704.3 704.6 705.3 707.0 13832.6 14038.2 14956.8 15382.9 15581.9 13824.5 13904.5 13906.9 13948.4 14098.2 9864.9 11518.9 11652.2 11665.0 12180.3 3633.9 3699.7 3728.9 3734.6 3776.8 81152.6 102940.9 104769.0 104947.0 106048.5 10546.5 10657.4 10825.0 11110.6 11157.2 21303.3 21602.6 21625.0 22034.9 22049.3 6281.8 7263.5 7345.7 7357.5 7477.2 4255.6 4296.9 4333.1 4348.4 4437.0 3527.9 3590.2 3590.4 3615.6 3630.8 500.6 500.7 503.5 504.1 509.1 634.2 663.3 677.6 686.5 688.2 9080.2 9379.3 9684.0 9772.4 9811.8 599.5 621.3 647.9 650.5 651.9 4678.7 4832.9 4836.7 4886.1 4908.9 101806.5 104921.0 106055.0 106349.9 107222.9 63151.0 65378.0 85016.6 87318.1 87956.6 75346.9 78378.0 78565.2 80046.0 80610.0 9698.7 9985.2 10100.9 10183.4 10351.5 38579.4 47415.5 48385.3 48614.0 49191.8 66057.0 67028.0 67193.7 68630.0 69533.7 11524.8 11715.3 11871.9 12187.3 12308.9 13693.5 13785.5 14062.9 14170.0 14326.3 12666.6 12943.9 13008.7 13078.6 13509.7 12788.2 12811.6 12887.3 12941.8 13313.8 12295.7 12594.5 12826.2 12967.6 12968.9 10246.0 10534.9 10716.5 10745.6 10836.8 10066.1 10305.3 10314.1 10343.7 10363.2 10:42:25 INFO - PROCESS | 1649 | -------- Summary: end -------- 10:42:25 INFO - PROCESS | 1649 | 10:42:26 INFO - TEST-INFO | 1649: exit 0 10:42:26 INFO - TEST-OK | dromaeo_css | took 1118103ms 10:42:26 INFO - TEST-START | kraken 10:42:26 INFO - operating with platform_type : mac_ 10:42:26 INFO - Initialising browser for kraken test... 10:42:26 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpjG2yCT/profile http://localhost:49241/getInfo.html) 10:42:28 INFO - PROCESS | 1656 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 10:42:28 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 10:42:28 INFO - PROCESS | 1656 | colorDepth:24 10:42:28 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 10:42:28 INFO - PROCESS | 1656 | __metrics 10:42:28 INFO - PROCESS | 1656 | 1485715348531 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 10:42:28 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:42:28 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:29 INFO - TEST-INFO | 1656: exit 0 10:42:29 INFO - Browser initialized. 10:42:29 INFO - Running cycle 1/1 for kraken test... 10:42:29 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpjG2yCT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:41 INFO - PROCESS | 1657 | RSS: Main: 236711936 10:42:41 INFO - PROCESS | 1657 | 10:42:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:42:44 INFO - PROCESS | 1657 | RSS: Main: 288591872 10:42:44 INFO - PROCESS | 1657 | 10:42:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:42:48 INFO - PROCESS | 1657 | RSS: Main: 289435648 10:42:48 INFO - PROCESS | 1657 | 10:42:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:42:52 INFO - PROCESS | 1657 | RSS: Main: 379187200 10:42:52 INFO - PROCESS | 1657 | 10:42:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:42:54 INFO - PROCESS | 1657 | RSS: Main: 283209728 10:42:54 INFO - PROCESS | 1657 | 10:42:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:42:57 INFO - PROCESS | 1657 | RSS: Main: 286388224 10:42:57 INFO - PROCESS | 1657 | 10:43:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:43:00 INFO - PROCESS | 1657 | RSS: Main: 329822208 10:43:00 INFO - PROCESS | 1657 | 10:43:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:43:04 INFO - PROCESS | 1657 | RSS: Main: 329662464 10:43:04 INFO - PROCESS | 1657 | 10:43:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:43:07 INFO - PROCESS | 1657 | RSS: Main: 326496256 10:43:07 INFO - PROCESS | 1657 | 10:43:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:43:10 INFO - PROCESS | 1657 | RSS: Main: 304197632 10:43:10 INFO - PROCESS | 1657 | 10:43:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:43:12 INFO - PROCESS | 1657 | RSS: Main: 308314112 10:43:12 INFO - PROCESS | 1657 | 10:43:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:43:15 INFO - PROCESS | 1657 | RSS: Main: 310112256 10:43:15 INFO - PROCESS | 1657 | 10:43:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:43:19 INFO - PROCESS | 1657 | RSS: Main: 329256960 10:43:19 INFO - PROCESS | 1657 | 10:43:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:43:22 INFO - PROCESS | 1657 | RSS: Main: 323153920 10:43:22 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:43:24 INFO - PROCESS | 1657 | RSS: Main: 368246784 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | __start_tp_report 10:43:24 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 10:43:24 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 10:43:24 INFO - PROCESS | 1657 | |i|pagename|runs| 10:43:24 INFO - PROCESS | 1657 | |0;ai-astar;114;115;114;115;115;113;114;113;113;114 10:43:24 INFO - PROCESS | 1657 | |1;audio-beat-detection;144;110;106;145;114;110;109;111;131;117 10:43:24 INFO - PROCESS | 1657 | |2;audio-dft;187;184;184;184;182;185;182;181;181;183 10:43:24 INFO - PROCESS | 1657 | |3;audio-fft;77;58;57;83;47;74;77;80;78;59 10:43:24 INFO - PROCESS | 1657 | |4;audio-oscillator;83;71;87;69;69;86;87;69;69;83 10:43:24 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;89;90;89;85;129;123;91;86 10:43:24 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;97;97;98;97;99;99;111;157;93 10:43:24 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;87;85;87;86;84;85;88;86;85 10:43:24 INFO - PROCESS | 1657 | |8;json-parse-financial;57;56;55;55;53;55;54;53;54;53 10:43:24 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;64;71;69;75;70;69;69;71;69;71 10:43:24 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;90;89;86;87;85;85;87;90;87;97 10:43:24 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;142;140;140;137;143;158;139;131;187;175 10:43:24 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;172;173;174;172;176;176;173;172;172;172 10:43:24 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;68;68;67;68;67;67;67;67;64 10:43:24 INFO - PROCESS | 1657 | __end_tp_report 10:43:24 INFO - PROCESS | 1657 | __start_cc_report 10:43:24 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1981 10:43:24 INFO - PROCESS | 1657 | __end_cc_report 10:43:24 INFO - PROCESS | 1657 | __startTimestamp1485715404816__endTimestamp 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | ------- Summary: start ------- 10:43:24 INFO - PROCESS | 1657 | Number of tests: 14 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 10:43:24 INFO - PROCESS | 1657 | Values: 114.0 115.0 114.0 115.0 115.0 113.0 114.0 113.0 113.0 114.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:115.50 stddev:14.77 (12.8%) stddev-sans-first:12.79 10:43:24 INFO - PROCESS | 1657 | Values: 144.0 110.0 106.0 145.0 114.0 110.0 109.0 111.0 131.0 117.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.89 (1.0%) stddev-sans-first:1.45 10:43:24 INFO - PROCESS | 1657 | Values: 187.0 184.0 184.0 184.0 182.0 185.0 182.0 181.0 181.0 183.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:69.00 Median:77.00 stddev:12.47 (16.2%) stddev-sans-first:12.89 10:43:24 INFO - PROCESS | 1657 | Values: 77.0 58.0 57.0 83.0 47.0 74.0 77.0 80.0 78.0 59.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:83.00 stddev:8.46 (10.2%) stddev-sans-first:8.72 10:43:24 INFO - PROCESS | 1657 | Values: 83.0 71.0 87.0 69.0 69.0 86.0 87.0 69.0 69.0 83.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:89.00 stddev:16.15 (18.1%) stddev-sans-first:16.94 10:43:24 INFO - PROCESS | 1657 | Values: 89.0 86.0 89.0 90.0 89.0 85.0 129.0 123.0 91.0 86.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:97.00 stddev:19.03 (19.6%) stddev-sans-first:19.99 10:43:24 INFO - PROCESS | 1657 | Values: 97.0 97.0 97.0 98.0 97.0 99.0 99.0 111.0 157.0 93.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.20 (1.4%) stddev-sans-first:1.27 10:43:24 INFO - PROCESS | 1657 | Values: 86.0 87.0 85.0 87.0 86.0 84.0 85.0 88.0 86.0 85.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.35 (2.5%) stddev-sans-first:1.09 10:43:24 INFO - PROCESS | 1657 | Values: 57.0 56.0 55.0 55.0 53.0 55.0 54.0 53.0 54.0 53.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.50 stddev:2.74 (3.9%) stddev-sans-first:1.94 10:43:24 INFO - PROCESS | 1657 | Values: 64.0 71.0 69.0 75.0 70.0 69.0 69.0 71.0 69.0 71.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:88.00 stddev:3.56 (4.0%) stddev-sans-first:3.72 10:43:24 INFO - PROCESS | 1657 | Values: 90.0 89.0 86.0 87.0 85.0 85.0 87.0 90.0 87.0 97.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:142.50 stddev:18.30 (12.8%) stddev-sans-first:19.23 10:43:24 INFO - PROCESS | 1657 | Values: 142.0 140.0 140.0 137.0 143.0 158.0 139.0 131.0 187.0 175.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:173.00 stddev:1.62 (0.9%) stddev-sans-first:1.66 10:43:24 INFO - PROCESS | 1657 | Values: 172.0 173.0 174.0 172.0 176.0 176.0 173.0 172.0 172.0 172.0 10:43:24 INFO - PROCESS | 1657 | 10:43:24 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 10:43:24 INFO - PROCESS | 1657 | Values: 67.0 68.0 68.0 67.0 68.0 67.0 67.0 67.0 67.0 64.0 10:43:24 INFO - PROCESS | 1657 | -------- Summary: end -------- 10:43:24 INFO - PROCESS | 1657 | 10:43:25 INFO - TEST-INFO | 1657: exit 0 10:43:25 INFO - TEST-OK | kraken | took 59721ms 10:43:25 INFO - SUITE-END | took 1177s 10:43:25 INFO - Completed test suite (00:19:38) 10:43:25 INFO - javascript benchmark 10:43:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7219.400149382499, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.217695576106, 3295.340931813638, 3302.3395320935806, 3322.053214833773, 3354.7819391739536, 5508.14623733321, 5529.944700552994, 5592.832215033545, 5597.6641401515935, 5605.990921634104, 4749.358836557064, 5559.360673522546, 5591.8042868499615, 5597.524210442107, 5650.5479561635075, 5079.517445842639, 5103.668261563005, 5115.13042782726, 5145.588352931775, 5164.741762911858, 5134.614903882205, 5210.296609957664, 5225.790968361261, 5234.18870075139, 5277.999999999981, 5092.8217512387255, 5105.642605017651, 5202.713850738208, 5206.947930520684, 5226.92159617606, 5089.54194122527, 5126.179811230185, 5143.614228932807, 5148.588112950993, 5200.427952925213, 5080.415752188509, 5136.794528218867, 5140.460251673595, 5149.2791009258735, 5152.046871328816, 5645.463680950304, 5646.322441307028, 5655.236543066716, 5655.26481557399, 5668.43315668434, 6000.819975400745, 6025.909611355789, 6028.608140470856, 6078.179445774808, 6130.509559235251, 6173.475254603364, 6243.687815609247, 6244.0, 6260.15487909131, 6261.154744109533, 5732.3121225453215, 5739.024365857814, 5743.540516758733, 5749.856253593652, 5765.913511297334, 6166.229221347332, 6203.503719702413, 6208.255009398901, 6252.562320637512, 6283.622982621057, 6027.78902738402, 6034.125051867454, 6042.516598672096, 6059.575829691968, 6106.328303886569, 6026.849328766728, 6192.716622844065, 6227.252729672468, 6228.719707613168, 6236.532260080512, 5980.611260268069, 6025.6685882276015, 6043.637381757108, 6068.908966365508, 6090.756369745161, 6161.537884658668, 6171.290301615282, 6174.228221472316, 6178.165947597017, 6193.81418557444, 4886.926696099561, 4928.802847886116, 4937.160682683881, 4937.456879743226, 4969.975150124226, 4375.16871794358, 4393.934090988638, 4409.867703968886, 4480.775961201927, 4518.028623845889, 5671.035923892948, 5678.744456499468, 5679.148127780866, 5680.488756011979, 5686.658800471985, 6104.550104788525, 6139.400144844221, 6145.293291271471, 6148.1085242640465, 6163.599366041193, 5652.095664693629, 5674.716264186774, 5676.971615141898, 5763.0, 5770.451807078321, 5391.353037635509, 5393.460653934653, 5394.94798514286, 5397.27136836522, 5405.7837686492885, 6276.403741644536, 6278.30938596754, 6289.088082228142, 6292.685365731695, 6294.307626161119, 6058.151858739692, 6093.482054025458, 6099.298580663103, 6119.112728654409, 6122.855605810045, 6266.937330626818, 6268.968655156695, 6287.339829317856, 6298.433141017331, 6300.21247344082, 6101.908471373022, 6102.664353460602, 6106.877862442638, 6116.541259405473, 6117.938820611737, 6050.697465126638, 6125.632462052291, 6143.078538219303, 6216.098665693358, 6216.844578885564, 5255.448177941261, 5807.070868660994, 5870.236869206976, 5940.732667030081, 6025.276966764016, 6011.368806275278, 6014.849628759141, 6014.879702406015, 6015.368386319548, 6043.425874541911, 5615.185798059177, 5801.810628820997, 5819.505342045804, 5835.79574714883, 5877.265341832271, 5792.536597072309, 5866.6186697864505, 5871.6770577618645, 5873.853153671193, 5875.970620146872, 5712.228849105318, 5723.427657234243, 5723.771049158154, 5733.885322293614, 5740.827775166752], "value": 5620.659334433592, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.72368217228575, 479.16385906592956, 495.6307550874599, 516.2205070343781, 519.8388499565135, 30362.544561831564, 31883.0, 32170.356592868127, 41473.377899331535, 41500.0, 41053.58946410535, 42635.3604695929, 42682.0, 42697.57302426975, 42850.142997140036, 9183.265338772899, 9349.111834375723, 9359.251259899225, 9364.204042656382, 9375.765605859873, 8347.624356903923, 8556.144385561456, 10982.670519884416, 11187.4965626547, 11225.214235003554, 11131.666050018512, 11180.273282236629, 11184.44077796111, 11187.49656265466, 11223.60717374892, 11160.218784685076, 11230.32618042916, 11232.831507527393, 11250.043746281575, 11262.0, 8259.710910118118, 8505.659773609048, 8507.31941444689, 8621.870671939925, 8626.482411055356, 38840.41739373912, 39727.60272397297, 39735.40396894049, 41670.79164604157, 41706.582934170874, 4005.959940400588, 4011.859584914543, 4044.4539987101657, 4047.3524236122375, 4164.0, 30251.243718906982, 30852.0, 30964.535531967038, 30981.0, 31072.378552428825, 3051.8016328938547, 3180.475221588435, 3221.1624977505785, 3281.753868459875, 3288.9506657400157, 474680.0, 478253.0, 478411.0, 478654.0, 478909.0, 60588.39411605916, 61104.38895611076, 61117.08324375138, 61121.0, 61180.38819611836, 64138.02067710524, 64175.679121605026, 64204.0, 64213.0, 64382.67808660927, 15922.840771592368, 16142.596435089217, 16159.353625855068, 16175.0, 16243.350265989464, 11205.99146076857, 12481.063920206021, 12552.184108032949, 12570.434330455151, 12572.748545029232, 13265.801012984813, 14547.636309092357, 16667.249973750968, 16751.994880307222, 17171.31314747396, 6096.359882212392, 6227.567659438355, 7268.527545709512, 7284.890726639104, 7361.705531778776, 773.8258891749311, 803.3894240377356, 856.4176360075209, 876.1501343696605, 898.7573355194061, 28342.71657283442, 29158.459892620333, 29173.56239656407, 29901.25246868802, 29969.40061198764, 957.9568919398505, 1048.6015314180563, 1049.3231865446744, 1081.8701755789198, 1083.3283364314175, 15196.468123615841, 15324.923375383052, 15349.002314849498, 15448.536543903701, 15557.922210389102, 639397.0, 644068.0, 644493.0, 647101.0, 647467.0, 60531.69734151389, 60934.0, 60946.0, 61145.08282375768, 61196.38803611907, 1259.974800504003, 1417.340936464549, 1437.4681367893945, 1437.8993470456967, 1446.7178900114377, 27923.860380697966, 28612.0, 29018.419631606826, 29409.705902940696, 29562.113136605938, 49584.75207623939, 49966.50033499764, 49976.75011624918, 50046.749766250934, 50110.24834627556, 555428.0, 556883.0, 559456.0, 560491.0, 562177.1891140518, 25865.612015820156, 26204.344891377867, 26227.868860656337, 26242.0, 26273.080442184433, 47032.0, 47386.0, 47628.7618561905, 48031.79920502016, 48626.756866215444, 38694.6130538691, 38968.025799355244, 39266.62566810148, 39837.80486585407, 40812.59187408088, 38306.23387532178, 38985.805070974464, 39043.804780975915, 39592.2752142297, 41018.0, 37020.0, 37553.0, 37893.24213515769, 37980.62019379771, 38069.619303806605, 30404.847975759978, 33001.66998329986, 33403.83298083494, 33499.0, 33604.495932560545, 24326.51346973015, 24460.755392445848, 24506.75493245116, 24572.75427245776, 25319.86060627313], "value": 19823.918451485035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.85125949620146, 378.5514165713631, 396.9186316805054, 397.8070635741665, 411.5452425070296, 15446.691066178684, 15577.922110389447, 18532.629347413043, 20778.376648700527, 21310.89344553279, 21536.246231381905, 21635.891820540914, 21858.89070554649, 21935.122595096178, 22216.666749998723, 5769.423057694239, 5821.941780582193, 5956.16613674086, 6048.0, 6067.666278354689, 5917.437843404891, 5965.61223520472, 5973.372795856438, 5977.551683623724, 5981.312149102865, 5675.574331925122, 5933.0, 5979.701014949257, 6012.879742405149, 6072.72672729726, 5801.3328467225965, 5985.132155837401, 6028.0355143176885, 6033.09503574463, 6034.36639152887, 5823.67969761663, 5914.349421563625, 5915.349311575723, 5918.585699001072, 5941.584089113764, 22468.438289042904, 25128.0, 25828.612570811452, 26011.0, 26078.73921260782, 2293.598620241451, 2888.494513460157, 2994.625671791026, 3010.412969470954, 3096.9535456968165, 10021.599136034623, 12102.455389507493, 15099.0, 15622.843771562366, 15820.971636843567, 1852.8332450079422, 1870.962580748391, 1893.346795355614, 1895.2229585869866, 1904.4096330137702, 115666.42166789196, 138385.0, 139657.0, 139863.99999999898, 142390.2880485591, 11427.60003399877, 11626.128040397005, 11722.824157637642, 11817.409129543488, 11957.820632690517, 15460.304286307142, 15491.690166196613, 15511.146886921102, 15517.146556939251, 15547.689046219013, 8317.148294195033, 8706.085860984633, 8721.694740684172, 8805.69180078694, 8831.779205519913, 8270.37972152079, 8405.789855253544, 8461.11158328379, 8589.742307730778, 8592.8711069334, 4665.790039448233, 4683.110209060268, 4683.203855344532, 4706.3646407735205, 4809.350737650442, 1958.177565422526, 2001.9899900500695, 2004.5489764803094, 2011.718359429681, 2018.9192432302543, 791.600241877848, 828.5318794880873, 830.4850992384639, 840.4495055738502, 851.9616617252238, 21375.67936480954, 22114.557708845732, 22255.0, 22276.443088922908, 22414.215502457337, 836.3894357119337, 852.861429990969, 858.9742307730778, 868.4268382867277, 872.7861673889811, 9842.852357214648, 10485.947570262253, 11189.944050279859, 11522.884771152349, 11585.189036767346, 670370.0, 721154.0, 734473.3276333691, 739745.3012734902, 741016.0, 157991.0, 159192.0, 162514.18742906448, 167093.0, 167239.0, 883.5847151838625, 971.3055165556661, 984.7193549838262, 1009.2884516415909, 1009.9446078847621, 16110.275037623102, 17040.57398565046, 18084.101169377027, 19275.4217373479, 19562.315318963767, 59043.0, 59303.0, 59456.0, 59645.403545965724, 60458.697706511186, 84123.0, 88121.0, 89527.55236223777, 90231.54884225538, 91047.0, 7845.3723702104835, 8089.150639182801, 8199.22107399796, 8239.423240373117, 8394.24451799341, 12040.819387709016, 12905.870941290466, 13083.869161308647, 13401.86598134006, 13414.195148291134, 11055.889441105486, 11224.214304998572, 11361.318320900773, 11432.628084629898, 11507.597234096766, 9372.625094996123, 11295.661130166109, 11319.320840749582, 11340.716482087684, 11459.0, 9999.600015999278, 10553.947230263799, 11051.005409512862, 11644.825327620261, 11654.592089276835, 8146.185381462044, 8227.300679442315, 8234.547099909323, 8304.750857474286, 8506.445030607723, 8744.606492707717, 10130.79738405213, 10361.4301213434, 11269.098472122374, 11300.886991130312], "value": 9953.53548748192, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.3659275251496, 1419.453510398497, 1432.7779194224893, 1444.162385816227, 1444.1840360196488, 7414.777556673302, 7767.106782719989, 7944.96027519863, 8254.133316001824, 8264.297534709542, 7929.682812687485, 8153.184681531859, 8163.714270000552, 8167.346612271019, 8217.21936416041, 2055.290924631, 2254.579614842647, 2297.3797074789795, 2310.3992961829877, 2371.4427109629223, 2128.8296936245065, 2260.9095636174534, 2325.197806756666, 2333.9066437342485, 2338.3920180752957, 2276.9203077892275, 2281.1445707859552, 2300.3559003478967, 2302.1251924268754, 2302.4934514406805, 2285.4514916420108, 2306.6770652108717, 2308.35366097493, 2309.8383113182085, 2311.5954707926044, 2251.8311126665567, 2313.2944451942153, 2316.3630001749484, 2317.733460652013, 2323.0591610397814, 8132.430729848913, 8192.016957965023, 8226.326443905695, 8257.710980115668, 8280.958595207047, 4470.8435204767675, 4965.801367945278, 5133.614978876599, 5134.64057515974, 5136.332276803992, 3007.8496075196153, 3348.4809854472596, 3492.3015396920464, 3550.3254381667657, 3558.395072837624, 1203.3201241298725, 1211.4669545399995, 1226.564569577796, 1287.3949243855373, 1290.870912908711, 10352.637657681946, 10514.369137851676, 10563.577456901714, 10564.313319634277, 10602.893971060268, 4400.559944005638, 4565.703229290086, 4651.558101980307, 4660.161170989187, 4667.929981050287, 9342.90657093434, 9357.26890525255, 9361.578728957213, 9362.859557106513, 9390.389624674512, 5201.973990130025, 5205.531502164823, 5208.869778255574, 5211.1922651989, 5212.687238765686, 4554.134714404318, 4565.771711414416, 4711.175544279738, 4734.384530011076, 4764.95235047652, 3481.373352796521, 3784.905377365533, 3812.2375524895133, 3846.1730727893637, 3893.980530097331, 1263.9620811375673, 1289.9226046437243, 1293.4373547506864, 1308.483149156078, 1314.835645544307, 840.9243168114899, 900.4777229207044, 907.7322189953848, 929.3076657889916, 944.9412885743485, 5353.785848566014, 5455.908068948289, 5472.2338872557875, 5486.423925487846, 5594.580406469531, 550.3313474128953, 551.1127085392515, 557.1948534367881, 572.7365411910484, 576.0955300178763, 3198.3603279344225, 3444.689977901951, 3555.9644403555635, 3593.6406359363855, 3607.6933460655614, 9305.209057230211, 9733.318667693193, 9739.707808765748, 9760.609575617038, 9814.75463113428, 9674.03259674041, 9704.75738106553, 9705.369151005161, 9719.65981190655, 9735.65925192629, 7570.091589009354, 7691.46159768822, 7702.460827742117, 7702.691892324245, 7752.069751629728, 4393.472783266028, 4630.86107416778, 5522.447755224445, 5607.635503692247, 5679.886402271849, 8838.734837954615, 8861.867071993796, 8869.556522173994, 8895.777605559913, 9099.545022748704, 8957.641694332153, 9108.544572771467, 9123.0, 9202.769930751785, 9230.215431688113, 6594.142761440982, 6822.590644561342, 6848.931510685029, 7000.964995174992, 7193.892091618734, 7824.491408058458, 7913.841723165619, 8000.279974802295, 8002.719904803537, 8004.318553284715, 7047.929520704933, 7091.82270443243, 7103.431725462054, 7136.536125151849, 7149.106361704787, 6888.655567221719, 6964.408025317702, 6971.0, 6988.79033628992, 7009.684564194726, 6248.812535623939, 6345.238571371465, 6379.393957574023, 6384.712687928962, 6387.616742995435, 4770.0936822003705, 4885.291632713308, 4946.604271658331, 4977.129002424634, 4988.201887697951, 4835.758212089452, 4923.0, 5026.070177017264, 5029.823956161663, 5117.43708192106], "value": 4302.249144689772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.55999360920276, 353.9716822654188, 370.7330721880246, 374.6253746253746, 375.4368447329006, 4363.454568178978, 5010.398752149738, 5203.401608814987, 5265.289185959894, 5332.760025798839, 4431.645468362532, 5131.358580177478, 5139.640225184238, 5176.249443830642, 5268.9473105269035, 3524.4537096750046, 4179.582041795811, 4184.372344148372, 4185.623293903548, 4197.139586384799, 3598.9280214395826, 4013.5585085640555, 4199.1391764688315, 4200.348945913388, 4206.411102445645, 3349.3133907548895, 3538.7345949053924, 4121.896952576195, 4138.965258685329, 4147.8963025924295, 4135.421041054255, 4136.786819051823, 4141.337386018237, 4144.689148313858, 4156.314208155651, 3121.5161649944293, 3677.17263615687, 3683.28176005679, 3685.9126557665554, 3705.5923848376656, 4302.41917341158, 4461.486929003174, 5113.642045056848, 5147.819826306099, 5205.219217117463, 3939.428782826503, 4004.9199016019807, 4301.827926882921, 4728.69263497875, 4821.566059054702, 4961.957988822352, 5390.0, 5562.833115006556, 5589.217509548666, 5598.86002849932, 3865.260632715261, 4041.353383458662, 4103.528094269167, 4107.322291821846, 4114.086949568436, 5098.745062746885, 5804.390538993387, 5825.825225243207, 5836.941630583639, 5853.912191317134, 3797.8290976906105, 3844.6539811416546, 3846.807659617008, 4110.856120035784, 4137.358709400048, 3920.5883382244456, 4067.8372865085657, 4161.708680392343, 4260.783108752872, 4267.253230684618, 4229.746215227096, 4606.723596584216, 4744.461712983537, 4758.381410416693, 4800.831970881002, 4578.214496346428, 5145.61407894402, 5611.8597035073635, 5612.520237452723, 5657.464084946908, 1179.988200118005, 1265.8987281017496, 1282.762688902556, 1299.382793173235, 1331.5406184866206, 963.6530848894392, 977.9340518834505, 989.5101924547251, 993.6869885985891, 997.9471657401366, 4730.858074257778, 4976.308055261228, 4993.700377977333, 5002.623753717028, 5031.52200540948, 4754.36449461772, 4798.113528042297, 4914.950850491521, 5343.251944727741, 5438.972805136028, 4664.813407463731, 4938.777755001033, 4966.751662416865, 4978.854863381443, 4984.825531106394, 2460.6678098456478, 2575.0343621142074, 2697.2063577720596, 2841.3891013432212, 2862.266535458632, 5005.3242812220615, 5138.715321169708, 5226.425093239741, 5261.5264626183825, 5538.363088244903, 5207.4271830099415, 5509.729959281509, 5559.77760889568, 5566.888662226814, 5578.330600327987, 4625.468071171858, 5066.873328166752, 5227.503387178288, 5499.395066542757, 5612.494875461228, 5209.505097015777, 5420.972895135499, 5444.455554444524, 5451.4276001019325, 5463.552158678033, 4550.361869726962, 5077.949220507748, 5347.652402593819, 5366.604682782427, 5368.677879327252, 4918.483559226373, 5497.821655541897, 5587.916181257203, 5589.5248903842, 5636.0, 3951.0, 3952.7430717003303, 3955.5055618047745, 3956.6043395660204, 3957.5053118360206, 4392.538783427822, 4554.88612784683, 4738.607003463229, 4892.730899800546, 4917.28699338601, 4463.419755431773, 5408.648437851527, 5454.29094217765, 5517.393086760373, 5610.326760788732, 4716.599089077329, 4940.702573303288, 5554.777808887761, 5569.331680198402, 5574.296466213802, 4974.154393753143, 5095.185050127594, 5416.45835416471, 5428.891422171456, 5440.510354067995, 4798.416522547481, 5233.685978841282, 5510.504054635102, 5521.585881058856, 5654.802081927113, 5272.945410917755, 5305.912287980877, 5499.560035197094, 5500.147477141049, 5501.367342755634], "value": 4037.5513588784406, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.9474744646704, 652.259685257233, 762.5500954436884, 827.5489858027374, 839.2195258409677, 15047.623809404771, 16114.952528085667, 16135.112568808725, 16141.11223882686, 16175.757363639525, 13492.66268343292, 14644.341004654789, 14689.265536723175, 14757.409703611842, 14786.40854365824, 877.4428238068808, 878.6090189865513, 894.66897248018, 896.2740180453853, 896.668232753882, 889.6219106879582, 891.3849443883732, 892.687559354224, 893.7631527645179, 895.9462432254053, 937.7608709778997, 938.4885237545554, 939.1125386509751, 939.783849714566, 939.9859002114973, 939.8073394954052, 939.8543225800012, 940.5720397219249, 940.7695114696909, 940.8635747816596, 701.5369855895136, 704.3132945378267, 704.5807744392079, 705.28765946394, 707.0419581467141, 13832.585022449342, 14038.227897465635, 14956.775648365283, 15382.923085384502, 15581.90926635136, 13824.516141934984, 13904.513342033082, 13906.860931390758, 13948.442062317496, 14098.224597647126, 9864.90135098647, 11518.884811151866, 11652.184347095706, 11665.0, 12180.330081845495, 3633.854645814164, 3699.6855267302312, 3728.869489567879, 3734.5705243897028, 3776.754510956779, 81152.59423702903, 102940.94118117596, 104768.95231047591, 104946.95053049448, 106048.46975765149, 10546.472676366151, 10657.360558366523, 10825.0, 11110.61112861046, 11157.218994670457, 21303.25438609641, 21602.56794864094, 21625.0, 22034.8982550875, 22049.33851984443, 6281.811545653638, 7263.455240856957, 7345.669444874993, 7357.456140350899, 7477.214892436325, 4255.638270746959, 4296.892577685583, 4333.090051089306, 4348.369486424451, 4436.977815110904, 3527.8588856445967, 3590.174259920233, 3590.4434812604086, 3615.6022837487853, 3630.7821530708243, 500.61452681435094, 500.68206688752906, 503.5442924153647, 504.100181176598, 509.10143596552115, 634.194572892424, 663.271114542959, 677.576514678326, 686.4679873098389, 688.2243810967691, 9080.22818060459, 9379.296552758571, 9683.983181765954, 9772.413655180711, 9811.803763924825, 599.4724642314823, 621.254494606474, 647.8574713563008, 650.5316172355896, 651.8826611209932, 4678.672492925462, 4832.8791780205775, 4836.733979631154, 4886.144924638245, 4908.8772780680765, 101806.49096754617, 104921.0, 106055.0, 106349.93650063401, 107222.92777072129, 63151.0, 65378.0, 85016.57491712502, 87318.12681873227, 87956.56021719979, 75346.94448888152, 78378.0, 78565.21434785693, 80046.0, 80610.0, 9698.660546880825, 9985.201183905416, 10100.898991009995, 10183.439910804977, 10351.482425878827, 38579.42130867983, 47415.52584474111, 48385.27422088601, 48614.0, 49191.754041229564, 66057.0, 67028.0, 67193.66403167952, 68630.0, 69533.65233173802, 11524.82712759326, 11715.297082175097, 11871.940640297089, 12187.268763874195, 12308.876911230773, 13693.515240052255, 13785.51750688721, 14062.929685351508, 14170.0, 14326.283685815875, 12666.556670516487, 12943.935280323538, 13008.739825203254, 13078.607641770763, 13509.729805404033, 12788.232706037668, 12811.615651530468, 12887.29119898377, 12941.805872912102, 13313.800292995806, 12295.69260768488, 12594.496220151457, 12826.166299190523, 12967.610971670865, 12968.870311296767, 10246.0, 10534.94732526363, 10716.464176790972, 10745.570177192825, 10836.783264334827, 10066.144377727682, 10305.330153539995, 10314.071733544017, 10343.689689309333, 10363.222758293008], "value": 7348.343811728895, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 115.0, 115.0, 113.0, 114.0, 113.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 106.0, 145.0, 114.0, 110.0, 109.0, 111.0, 131.0, 117.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 184.0, 184.0, 182.0, 185.0, 182.0, 181.0, 181.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 57.0, 83.0, 47.0, 74.0, 77.0, 80.0, 78.0, 59.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 87.0, 69.0, 69.0, 86.0, 87.0, 69.0, 69.0, 83.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 90.0, 89.0, 85.0, 129.0, 123.0, 91.0, 86.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 98.0, 97.0, 99.0, 99.0, 111.0, 157.0, 93.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 87.0, 86.0, 84.0, 85.0, 88.0, 86.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 55.0, 53.0, 55.0, 54.0, 53.0, 54.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 69.0, 75.0, 70.0, 69.0, 69.0, 71.0, 69.0, 71.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 86.0, 87.0, 85.0, 85.0, 87.0, 90.0, 87.0, 97.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 140.0, 137.0, 143.0, 158.0, 139.0, 131.0, 187.0, 175.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 174.0, 172.0, 176.0, 176.0, 173.0, 172.0, 172.0, 172.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 64.0], "value": 67.0, "unit": "score"}]}]} 10:43:26 INFO - Return code: 0 10:43:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:43:26 ERROR - # TBPL SUCCESS # 10:43:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:43:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:43:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:43:26 INFO - 2017-01-29 10:43:26.137 screenresolution[1661:10825] starting screenresolution argv=screenresolution get 10:43:26 INFO - 2017-01-29 10:43:26.140 screenresolution[1661:10825] Display 0: 1600x1200x32@60 10:43:26 INFO - 2017-01-29 10:43:26.146 screenresolution[1662:10828] starting screenresolution argv=screenresolution list 10:43:26 INFO - Available Modes on Display 0 10:43:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:43:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:43:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:43:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:43:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:43:26 INFO - Intel Iris: 10:43:26 INFO - Chipset Model: Intel Iris 10:43:26 INFO - Type: GPU 10:43:26 INFO - Bus: Built-In 10:43:26 INFO - VRAM (Dynamic, Max): 1536 MB 10:43:26 INFO - Vendor: Intel (0x8086) 10:43:26 INFO - Device ID: 0x0a2e 10:43:26 INFO - Revision ID: 0x0009 10:43:26 INFO - Displays: 10:43:26 INFO - Display: 10:43:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:43:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:43:26 INFO - Display Serial Number: 1600x1200 60 10:43:26 INFO - Main Display: Yes 10:43:26 INFO - Mirror: Off 10:43:26 INFO - Online: Yes 10:43:26 INFO - Rotation: Supported 10:43:26 INFO - Return code: 0 10:43:26 INFO - Running post-action listener: _resource_record_post_action 10:43:26 INFO - [mozharness: 2017-01-29 18:43:26.387936Z] Finished run-tests step (success) 10:43:26 INFO - Running post-run listener: _resource_record_post_run 10:43:26 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 33500160; Write bytes: 554817024; Read time: 311; Write time: 1820 10:43:26 INFO - TinderboxPrint: CPU usage
54.2% 10:43:26 INFO - TinderboxPrint: I/O read bytes / time
33,500,160 / 311 10:43:26 INFO - TinderboxPrint: I/O write bytes / time
554,817,024 / 1,820 10:43:26 INFO - TinderboxPrint: CPU idle
2,197.9 (45.8%) 10:43:26 INFO - TinderboxPrint: CPU system
966.5 (20.1%) 10:43:26 INFO - TinderboxPrint: CPU user
1,637.3 (34.1%) 10:43:26 INFO - TinderboxPrint: Swap in / out
281,763,840 / 0 10:43:26 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250739200; Write bytes: 245093376; Read time: 17940; Write time: 501 10:43:26 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29137920; Write bytes: 298426880; Read time: 280; Write time: 1283 10:43:26 INFO - Running post-run listener: _upload_blobber_files 10:43:26 INFO - Blob upload gear active. 10:43:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:43:26 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 10:43:26 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'] 10:43:26 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 10:43:27 INFO - (blobuploader) - INFO - Open directory for files ... 10:43:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:43:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:43:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:27 INFO - (blobuploader) - INFO - Done attempting. 10:43:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:43:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:28 INFO - (blobuploader) - INFO - Done attempting. 10:43:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:43:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:29 INFO - (blobuploader) - INFO - Done attempting. 10:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:32 INFO - (blobuploader) - INFO - Done attempting. 10:43:32 INFO - (blobuploader) - INFO - Iteration through files over. 10:43:32 INFO - Return code: 0 10:43:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:43:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:43:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82a86aa88367953f6c4314901369ed2d0664149ca5b1c03a3321816b981e12af4430990c42507f0decfb34981139cf50bd05ad02253f4e20d9e379a0a04d400c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d1791d33db876498cc7cf6d5a74bcf940af8006e6aea43891c5f7d7b0b09be7670a0fded9e639ac53f67b839c1f83d18b4694eaa071474e4e5e2f587ffd1b92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d568bd67c4c26d42bb40eee9fedaa169968a3eab95e31e88f0f4ed99b35e19d07ef758a2bb45f956d68f4daca7b3afe13d05cff7189ec2b467eff4e606775d6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a17d24de649c54f833cba54b90baba525eb53dbed84d815818a72b9710d71274dbcbb745cb69d104ff8f4a56a4227661a27f2a3e8ad875982764977fe0bd28a"} 10:43:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:43:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:43:32 INFO - Contents: 10:43:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82a86aa88367953f6c4314901369ed2d0664149ca5b1c03a3321816b981e12af4430990c42507f0decfb34981139cf50bd05ad02253f4e20d9e379a0a04d400c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d1791d33db876498cc7cf6d5a74bcf940af8006e6aea43891c5f7d7b0b09be7670a0fded9e639ac53f67b839c1f83d18b4694eaa071474e4e5e2f587ffd1b92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d568bd67c4c26d42bb40eee9fedaa169968a3eab95e31e88f0f4ed99b35e19d07ef758a2bb45f956d68f4daca7b3afe13d05cff7189ec2b467eff4e606775d6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a17d24de649c54f833cba54b90baba525eb53dbed84d815818a72b9710d71274dbcbb745cb69d104ff8f4a56a4227661a27f2a3e8ad875982764977fe0bd28a"} 10:43:32 INFO - Running post-run listener: copy_logs_to_upload_dir 10:43:32 INFO - Copying logs to upload dir... 10:43:32 INFO - mkdir: /builds/slave/test/build/upload/logs 10:43:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.704435 ========= master_lag: 1.35 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-29 10:43:33.477046) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:33.481513) ========= 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.zeI3ngHb3k/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.95SvTOklM0/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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/82a86aa88367953f6c4314901369ed2d0664149ca5b1c03a3321816b981e12af4430990c42507f0decfb34981139cf50bd05ad02253f4e20d9e379a0a04d400c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d1791d33db876498cc7cf6d5a74bcf940af8006e6aea43891c5f7d7b0b09be7670a0fded9e639ac53f67b839c1f83d18b4694eaa071474e4e5e2f587ffd1b92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d568bd67c4c26d42bb40eee9fedaa169968a3eab95e31e88f0f4ed99b35e19d07ef758a2bb45f956d68f4daca7b3afe13d05cff7189ec2b467eff4e606775d6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a17d24de649c54f833cba54b90baba525eb53dbed84d815818a72b9710d71274dbcbb745cb69d104ff8f4a56a4227661a27f2a3e8ad875982764977fe0bd28a"} build_url:https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011039 build_url: 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82a86aa88367953f6c4314901369ed2d0664149ca5b1c03a3321816b981e12af4430990c42507f0decfb34981139cf50bd05ad02253f4e20d9e379a0a04d400c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d1791d33db876498cc7cf6d5a74bcf940af8006e6aea43891c5f7d7b0b09be7670a0fded9e639ac53f67b839c1f83d18b4694eaa071474e4e5e2f587ffd1b92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d568bd67c4c26d42bb40eee9fedaa169968a3eab95e31e88f0f4ed99b35e19d07ef758a2bb45f956d68f4daca7b3afe13d05cff7189ec2b467eff4e606775d6e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a17d24de649c54f833cba54b90baba525eb53dbed84d815818a72b9710d71274dbcbb745cb69d104ff8f4a56a4227661a27f2a3e8ad875982764977fe0bd28a"}' ========= master_lag: 0.88 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:34.376773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:34.377152) ========= 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.zeI3ngHb3k/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.95SvTOklM0/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005261 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:34.564189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 10:43:34.564516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 10:43:34.564818) ========= ========= Total master_lag: 2.52 =========