builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0360 starttime: 1489060551.91 results: success (0) buildid: 20170309023023 builduid: 9f54d0e428e049e685099077a69cbbff revision: c40ca7a1bdd93632c6bdc5e23bd33d984d508b19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:51.915311) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:51.916263) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:51.916924) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:51.947048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:51.947653) ========= 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.PjflpyOA79/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.ImDQUqf3HQ/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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-03-09 03:55:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.4M=0.001s 2017-03-09 03:55:52 (17.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092695 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:52.069538) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:52.070265) ========= 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.PjflpyOA79/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.ImDQUqf3HQ/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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.025749 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:52.122493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 03:55:52.123232) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c40ca7a1bdd93632c6bdc5e23bd33d984d508b19 --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 c40ca7a1bdd93632c6bdc5e23bd33d984d508b19 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PjflpyOA79/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.ImDQUqf3HQ/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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-03-09 03:55:52,230 truncating revision to first 12 chars 2017-03-09 03:55:52,231 Setting DEBUG logging. 2017-03-09 03:55:52,231 attempt 1/10 2017-03-09 03:55:52,231 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c40ca7a1bdd9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-09 03:55:53,484 unpacking tar archive at: mozilla-central-c40ca7a1bdd9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.519822 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 03:55:53.668501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:53.668917) ========= script_repo_revision: c40ca7a1bdd93632c6bdc5e23bd33d984d508b19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:53.669377) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:53.669716) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 03:55:53.689663) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-09 03:55:53.690019) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.PjflpyOA79/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.ImDQUqf3HQ/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:55:53 INFO - MultiFileLogger online at 20170309 03:55:53 in /builds/slave/test 03:55:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:55:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:55:53 INFO - {'append_to_log': False, 03:55:53 INFO - 'base_work_dir': '/builds/slave/test', 03:55:53 INFO - 'blob_upload_branch': 'Firefox', 03:55:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:55:53 INFO - 'branch': 'Firefox', 03:55:53 INFO - 'buildbot_json_path': 'buildprops.json', 03:55:53 INFO - 'config_files': ('talos/mac_config.py',), 03:55:53 INFO - 'default_actions': ('clobber', 03:55:53 INFO - 'read-buildbot-config', 03:55:53 INFO - 'download-and-extract', 03:55:53 INFO - 'populate-webroot', 03:55:53 INFO - 'create-virtualenv', 03:55:53 INFO - 'install', 03:55:53 INFO - 'run-tests'), 03:55:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:55:53 INFO - 'download_minidump_stackwalk': True, 03:55:53 INFO - 'download_symbols': 'ondemand', 03:55:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:55:53 INFO - 'tooltool.py': '/tools/tooltool.py', 03:55:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:55:53 INFO - '/tools/misc-python/virtualenv.py')}, 03:55:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:55:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:55:53 INFO - 'gecko_profile': False, 03:55:53 INFO - 'gecko_profile_interval': 0, 03:55:53 INFO - 'installer_path': 'installer.exe', 03:55:53 INFO - 'log_level': 'info', 03:55:53 INFO - 'log_name': 'talos', 03:55:53 INFO - 'log_to_console': True, 03:55:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:55:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:55:53 INFO - 'opt_config_files': (), 03:55:53 INFO - 'pip_index': False, 03:55:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:55:53 INFO - 'cmd': ('bash', 03:55:53 INFO - '-c', 03:55:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:55:53 INFO - 'enabled': True, 03:55:53 INFO - 'halt_on_failure': False, 03:55:53 INFO - 'name': 'check_screen_resolution'},), 03:55:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:55:53 INFO - 'cmd': ('bash', 03:55:53 INFO - '-c', 03:55:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:55:53 INFO - 'enabled': True, 03:55:53 INFO - 'halt_on_failure': False, 03:55:53 INFO - 'name': 'check_screen_resolution'},), 03:55:53 INFO - 'run_cmd_checks_enabled': True, 03:55:53 INFO - 'suite': 'dromaeojs-e10s', 03:55:53 INFO - 'system_bits': '32', 03:55:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:55:53 INFO - 'title': 't-yosemite-r7-0360', 03:55:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:55:53 INFO - 'use_talos_json': True, 03:55:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:55:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:55:53 INFO - 'work_dir': 'build'} 03:55:53 INFO - [mozharness: 2017-03-09 11:55:53.901340Z] Running clobber step. 03:55:53 INFO - Running pre-action listener: _resource_record_pre_action 03:55:53 INFO - Running main action method: clobber 03:55:53 INFO - rmtree: /builds/slave/test/build 03:55:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:55:56 INFO - Running post-action listener: _resource_record_post_action 03:55:56 INFO - [mozharness: 2017-03-09 11:55:56.348202Z] Finished clobber step (success) 03:55:56 INFO - [mozharness: 2017-03-09 11:55:56.348419Z] Running read-buildbot-config step. 03:55:56 INFO - Running pre-action listener: _resource_record_pre_action 03:55:56 INFO - Running main action method: read_buildbot_config 03:55:56 INFO - Using buildbot properties: 03:55:56 INFO - { 03:55:56 INFO - "project": "", 03:55:56 INFO - "product": "firefox", 03:55:56 INFO - "script_repo_revision": "production", 03:55:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:55:56 INFO - "repository": "", 03:55:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:55:56 INFO - "buildid": "20170309023023", 03:55:56 INFO - "pgo_build": "False", 03:55:56 INFO - "basedir": "/builds/slave/test", 03:55:56 INFO - "buildnumber": 105, 03:55:56 INFO - "slavename": "t-yosemite-r7-0360", 03:55:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:55:56 INFO - "platform": "yosemite_r7", 03:55:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:55:56 INFO - "branch": "mozilla-central", 03:55:56 INFO - "repo_path": "mozilla-central", 03:55:56 INFO - "revision": "c40ca7a1bdd93632c6bdc5e23bd33d984d508b19", 03:55:56 INFO - "stage_platform": "macosx64", 03:55:56 INFO - "builduid": "9f54d0e428e049e685099077a69cbbff", 03:55:56 INFO - "slavebuilddir": "test" 03:55:56 INFO - } 03:55:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 03:55:56 INFO - Running post-action listener: _resource_record_post_action 03:55:56 INFO - [mozharness: 2017-03-09 11:55:56.354149Z] Finished read-buildbot-config step (success) 03:55:56 INFO - [mozharness: 2017-03-09 11:55:56.354372Z] Running download-and-extract step. 03:55:56 INFO - Running pre-action listener: _resource_record_pre_action 03:55:56 INFO - Running main action method: download_and_extract 03:55:56 INFO - mkdir: /builds/slave/test/build/tests 03:55: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')]} 03:55:56 INFO - trying https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:55:56 INFO - Downloading https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:55:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 03:55:57 INFO - Downloaded 1199 bytes. 03:55:57 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:55:57 INFO - Using the following test package requirements: 03:55:57 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:55:57 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:55:57 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:55:57 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'jsshell-mac.zip'], 03:55:57 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:55:57 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:55:57 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:55:57 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:55:57 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:55:57 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:55:57 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:55:57 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:55:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:55:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:55:57 INFO - Fetch https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:55:59 INFO - Expected file size: 51784907 03:55:59 INFO - Obtained file size: 51784907 03:56:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:56:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:56:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:56:14 INFO - Fetch https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:56:16 INFO - Expected file size: 51784907 03:56:16 INFO - Obtained file size: 51784907 03:56:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:56:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:56:31 INFO - Fetch https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:56:32 INFO - Expected file size: 14404424 03:56:32 INFO - Obtained file size: 14404424 03:56: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')]} 03:56:32 INFO - trying https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:56:32 INFO - Downloading https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:56:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 03:56:35 INFO - Downloaded 74929859 bytes. 03:56:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:56:35 INFO - mkdir: /builds/slave/test/properties 03:56:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:56:35 INFO - Writing to file /builds/slave/test/properties/build_url 03:56:35 INFO - Contents: 03:56:35 INFO - build_url:https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:56:36 INFO - Running post-action listener: _resource_record_post_action 03:56:36 INFO - Running post-action listener: set_extra_try_arguments 03:56:36 INFO - [mozharness: 2017-03-09 11:56:36.132529Z] Finished download-and-extract step (success) 03:56:36 INFO - [mozharness: 2017-03-09 11:56:36.132649Z] Running populate-webroot step. 03:56:36 INFO - Running pre-action listener: _resource_record_pre_action 03:56:36 INFO - Running main action method: populate_webroot 03:56:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:36 INFO - '--tppagecycles', 03:56:36 INFO - '7'], 03:56:36 INFO - 'tests': ['tsvgm']}}, 03:56:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:56:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:56:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:56:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['damp', 'tps']}, 03:56:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'tests': ['damp', 'tps']}, 03:56:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['dromaeo_dom']}, 03:56:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['basic_compositor_video']}, 03:56:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:56:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other-e10s': {'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['a11yr', 03:56:36 INFO - 'ts_paint', 03:56:36 INFO - 'tpaint', 03:56:36 INFO - 'sessionrestore', 03:56:36 INFO - 'sessionrestore_no_auto_restore', 03:56:36 INFO - 'tabpaint']}, 03:56:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tsvgx', 03:56:36 INFO - 'tsvgr_opacity', 03:56:36 INFO - 'tart', 03:56:36 INFO - 'tscrollx', 03:56:36 INFO - 'cart', 03:56:36 INFO - 'tsvg_static']}, 03:56:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:56:36 INFO - 'tsvgr_opacity', 03:56:36 INFO - 'tart', 03:56:36 INFO - 'tscrollx', 03:56:36 INFO - 'cart', 03:56:36 INFO - 'tsvg_static']}, 03:56:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s'], 03:56:36 INFO - 'tests': ['tp5o']}, 03:56:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:56:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'talos_options': ['--disable-e10s', 03:56:36 INFO - '--xperf_path', 03:56:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:36 INFO - 'tests': ['tp5n']}, 03:56:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:56:36 INFO - 'talos_options': ['--xperf_path', 03:56:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:36 INFO - 'tests': ['tp5n']}}, 03:56:36 INFO - 'talos.zip': {'path': '', 03:56:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:36 INFO - Running post-action listener: _resource_record_post_action 03:56:36 INFO - [mozharness: 2017-03-09 11:56:36.150948Z] Finished populate-webroot step (success) 03:56:36 INFO - [mozharness: 2017-03-09 11:56:36.151056Z] Running create-virtualenv step. 03:56:36 INFO - Running pre-action listener: _resource_record_pre_action 03:56:36 INFO - Running main action method: create_virtualenv 03:56:36 INFO - Creating virtualenv /builds/slave/test/build/venv 03:56:36 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 03:56:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:56:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:36 INFO - Using real prefix '/tools/python27' 03:56:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:56:37 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:39 INFO - Installing pip.................done. 03:56:39 INFO - Return code: 0 03:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:56:40 INFO - Reading from file tmpfile_stdout 03:56:40 INFO - Output received: 03:56:40 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) 03:56:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:56:40 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')]} 03:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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')]} 03:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:40 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 03:56:40 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 03:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:40 INFO - 'HOME': '/Users/cltbld', 03:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:40 INFO - 'LOGNAME': 'cltbld', 03:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:40 INFO - 'MOZ_NO_REMOTE': '1', 03:56:40 INFO - 'NO_EM_RESTART': '1', 03:56:40 INFO - 'PAGER': '/bin/cat', 03:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:40 INFO - 'PWD': '/builds/slave/test', 03:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:40 INFO - 'SHELL': '/bin/bash', 03:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:40 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:40 INFO - 'USER': 'cltbld', 03:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:40 INFO - 'XPC_FLAGS': '0x0', 03:56:40 INFO - 'XPC_SERVICE_NAME': '0', 03:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:40 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 03:56:40 INFO - Cleaning up... 03:56:40 INFO - Return code: 0 03:56:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:56:40 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')]} 03:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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')]} 03:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:40 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:40 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 03:56:40 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 03:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:40 INFO - 'HOME': '/Users/cltbld', 03:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:40 INFO - 'LOGNAME': 'cltbld', 03:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:40 INFO - 'MOZ_NO_REMOTE': '1', 03:56:40 INFO - 'NO_EM_RESTART': '1', 03:56:40 INFO - 'PAGER': '/bin/cat', 03:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:40 INFO - 'PWD': '/builds/slave/test', 03:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:40 INFO - 'SHELL': '/bin/bash', 03:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:40 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:40 INFO - 'USER': 'cltbld', 03:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:40 INFO - 'XPC_FLAGS': '0x0', 03:56:40 INFO - 'XPC_SERVICE_NAME': '0', 03:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:40 INFO - Downloading/unpacking psutil>=3.1.1 03:56:40 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 03:56:40 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 03:56:40 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 03:56:40 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 03:56:40 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 03:56:40 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 03:56:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:44 INFO - Installing collected packages: psutil 03:56:44 INFO - Running setup.py install for psutil 03:56:44 INFO - building 'psutil._psutil_osx' extension 03:56:44 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 03:56:44 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 03:56:44 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 03:56:44 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 03:56:45 INFO - building 'psutil._psutil_posix' extension 03:56:45 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 03:56:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:56:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:56:45 INFO - ^ 03:56:45 INFO - 1 warning generated. 03:56:45 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 03:56:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:45 INFO - Successfully installed psutil 03:56:45 INFO - Cleaning up... 03:56:45 INFO - Return code: 0 03:56:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:45 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:45 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 03:56:45 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 03:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:45 INFO - 'HOME': '/Users/cltbld', 03:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:45 INFO - 'LOGNAME': 'cltbld', 03:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:45 INFO - 'MOZ_NO_REMOTE': '1', 03:56:45 INFO - 'NO_EM_RESTART': '1', 03:56:45 INFO - 'PAGER': '/bin/cat', 03:56:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:45 INFO - 'PWD': '/builds/slave/test', 03:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:45 INFO - 'SHELL': '/bin/bash', 03:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:45 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:45 INFO - 'USER': 'cltbld', 03:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:45 INFO - 'XPC_FLAGS': '0x0', 03:56:45 INFO - 'XPC_SERVICE_NAME': '0', 03:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:56:45 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 03:56:45 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 03:56:45 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 03:56:45 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 03:56:45 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 03:56:45 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 03:56:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:56:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:56:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:56:48 INFO - Installing collected packages: mozsystemmonitor 03:56:48 INFO - Running setup.py install for mozsystemmonitor 03:56:48 INFO - Successfully installed mozsystemmonitor 03:56:48 INFO - Cleaning up... 03:56:48 INFO - Return code: 0 03:56:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56: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')]} 03:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:49 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:49 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 03:56:49 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 03:56:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:49 INFO - 'HOME': '/Users/cltbld', 03:56:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:49 INFO - 'LOGNAME': 'cltbld', 03:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:49 INFO - 'MOZ_NO_REMOTE': '1', 03:56:49 INFO - 'NO_EM_RESTART': '1', 03:56:49 INFO - 'PAGER': '/bin/cat', 03:56:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:49 INFO - 'PWD': '/builds/slave/test', 03:56:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:49 INFO - 'SHELL': '/bin/bash', 03:56:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:49 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:49 INFO - 'USER': 'cltbld', 03:56:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:49 INFO - 'XPC_FLAGS': '0x0', 03:56:49 INFO - 'XPC_SERVICE_NAME': '0', 03:56:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:49 INFO - Downloading/unpacking jsonschema==2.5.1 03:56:49 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 03:56:49 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 03:56:49 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 03:56:49 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 03:56:49 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 03:56:49 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 03:56:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:56:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:56:52 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 03:56:52 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 03:56:52 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 03:56:52 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 03:56:52 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 03:56:52 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 03:56:52 INFO - Downloading functools32-3.2.3-2.tar.gz 03:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:56:53 INFO - warning: no files found matching '*.txt' 03:56:53 INFO - no previously-included directories found matching 'build' 03:56:53 INFO - no previously-included directories found matching 'dist' 03:56:53 INFO - no previously-included directories found matching '.git*' 03:56:53 INFO - Installing collected packages: jsonschema, functools32 03:56:53 INFO - Running setup.py install for functools32 03:56:53 INFO - warning: no files found matching '*.txt' 03:56:53 INFO - no previously-included directories found matching 'build' 03:56:53 INFO - no previously-included directories found matching 'dist' 03:56:53 INFO - no previously-included directories found matching '.git*' 03:56:53 INFO - Successfully installed jsonschema functools32 03:56:53 INFO - Cleaning up... 03:56:53 INFO - Return code: 0 03:56:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:53 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 03:56:53 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 03:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:53 INFO - 'HOME': '/Users/cltbld', 03:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:53 INFO - 'LOGNAME': 'cltbld', 03:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:53 INFO - 'MOZ_NO_REMOTE': '1', 03:56:53 INFO - 'NO_EM_RESTART': '1', 03:56:53 INFO - 'PAGER': '/bin/cat', 03:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:53 INFO - 'PWD': '/builds/slave/test', 03:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:53 INFO - 'SHELL': '/bin/bash', 03:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:53 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:53 INFO - 'USER': 'cltbld', 03:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:53 INFO - 'XPC_FLAGS': '0x0', 03:56:53 INFO - 'XPC_SERVICE_NAME': '0', 03:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:56:53 INFO - Cleaning up... 03:56:53 INFO - Return code: 0 03:56:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:53 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 03:56:53 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 03:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:53 INFO - 'HOME': '/Users/cltbld', 03:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:53 INFO - 'LOGNAME': 'cltbld', 03:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:53 INFO - 'MOZ_NO_REMOTE': '1', 03:56:53 INFO - 'NO_EM_RESTART': '1', 03:56:53 INFO - 'PAGER': '/bin/cat', 03:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:53 INFO - 'PWD': '/builds/slave/test', 03:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:53 INFO - 'SHELL': '/bin/bash', 03:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:53 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:53 INFO - 'USER': 'cltbld', 03:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:53 INFO - 'XPC_FLAGS': '0x0', 03:56:53 INFO - 'XPC_SERVICE_NAME': '0', 03:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:53 INFO - Downloading/unpacking blobuploader==1.2.4 03:56:53 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 03:56:53 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 03:56:53 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 03:56:53 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 03:56:53 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 03:56:53 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 03:56:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:56:56 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 03:56:56 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 03:56:56 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 03:56:56 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 03:56:56 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 03:56:56 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 03:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:56:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56: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 03:56:57 INFO - Downloading docopt-0.6.1.tar.gz 03:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:57 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:57 INFO - Running setup.py install for blobuploader 03:56:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:56:57 INFO - Running setup.py install for requests 03:56:58 INFO - Running setup.py install for docopt 03:56:58 INFO - Successfully installed blobuploader requests docopt 03:56:58 INFO - Cleaning up... 03:56:58 INFO - Return code: 0 03:56:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:58 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:56:58 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 03:56:58 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 03:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:58 INFO - 'HOME': '/Users/cltbld', 03:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:58 INFO - 'LOGNAME': 'cltbld', 03:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:58 INFO - 'MOZ_NO_REMOTE': '1', 03:56:58 INFO - 'NO_EM_RESTART': '1', 03:56:58 INFO - 'PAGER': '/bin/cat', 03:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:58 INFO - 'PWD': '/builds/slave/test', 03:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:58 INFO - 'SHELL': '/bin/bash', 03:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:56:58 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:58 INFO - 'USER': 'cltbld', 03:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:58 INFO - 'XPC_FLAGS': '0x0', 03:56:58 INFO - 'XPC_SERVICE_NAME': '0', 03:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:58 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-ADTmzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-GRAxol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-k1P0et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-eKo43U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-VH1vAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-pl0OdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-3ocyoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-ieUnzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-kQ0qvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-HqZsQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:59 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-hTDoFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:00 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-MijuxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:00 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-dADqDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:00 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-3U6Y8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:00 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-kSzghn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:00 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-ZoBF6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:00 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-hAdKfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:57:00 INFO - Running setup.py install for manifestparser 03:57:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:57:00 INFO - Running setup.py install for mozcrash 03:57:00 INFO - Running setup.py install for mozdebug 03:57:01 INFO - Running setup.py install for mozdevice 03:57:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Running setup.py install for mozfile 03:57:01 INFO - Running setup.py install for mozhttpd 03:57:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Running setup.py install for mozinfo 03:57:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Running setup.py install for mozInstall 03:57:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:57:01 INFO - Running setup.py install for mozleak 03:57:01 INFO - Running setup.py install for mozlog 03:57:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for moznetwork 03:57:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozprocess 03:57:02 INFO - Running setup.py install for mozprofile 03:57:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozrunner 03:57:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:57:02 INFO - Running setup.py install for mozscreenshot 03:57:02 INFO - Running setup.py install for moztest 03:57:03 INFO - Running setup.py install for mozversion 03:57:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:57:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:57:03 INFO - Cleaning up... 03:57:03 INFO - Return code: 0 03:57:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:03 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:57:03 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 03:57:03 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 03:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:03 INFO - 'HOME': '/Users/cltbld', 03:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:03 INFO - 'LOGNAME': 'cltbld', 03:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:03 INFO - 'MOZ_NO_REMOTE': '1', 03:57:03 INFO - 'NO_EM_RESTART': '1', 03:57:03 INFO - 'PAGER': '/bin/cat', 03:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:03 INFO - 'PWD': '/builds/slave/test', 03:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:03 INFO - 'SHELL': '/bin/bash', 03:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:57:03 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:03 INFO - 'USER': 'cltbld', 03:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:03 INFO - 'XPC_FLAGS': '0x0', 03:57:03 INFO - 'XPC_SERVICE_NAME': '0', 03:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:03 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-pcIauq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:03 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)) 03:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:03 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-A9Dywj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:03 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)) 03:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:03 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-fv13dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:03 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)) 03:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:03 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-h_zyQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:03 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)) 03:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:03 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-dIEAoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-oKvVBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-rXBy1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-PRtsMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-fhfegp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-2NTUrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-nIAIBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-tkTBkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-nTveg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:04 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)) 03:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:04 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-9pGVG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:05 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)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:05 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-SVBB3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:05 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)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:05 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-lqVYTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:05 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)) 03:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:05 INFO - Running setup.py (path:/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/pip-HoF3RQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:05 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)) 03:57:05 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)) 03:57:05 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)) 03:57:05 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)) 03:57:05 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)) 03:57:05 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)) 03:57:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57: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 03:57:07 INFO - Downloading blessings-1.6.tar.gz 03:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:57:08 INFO - Installing collected packages: blessings 03:57:08 INFO - Running setup.py install for blessings 03:57:08 INFO - Successfully installed blessings 03:57:08 INFO - Cleaning up... 03:57:08 INFO - Return code: 0 03:57:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:08 INFO - Reading from file tmpfile_stdout 03:57:08 INFO - Current package versions: 03:57:08 INFO - blessings == 1.6 03:57:08 INFO - blobuploader == 1.2.4 03:57:08 INFO - docopt == 0.6.1 03:57:08 INFO - functools32 == 3.2.3-2 03:57:08 INFO - jsonschema == 2.5.1 03:57:08 INFO - manifestparser == 1.1 03:57:08 INFO - mozInstall == 1.12 03:57:08 INFO - mozcrash == 1.0 03:57:08 INFO - mozdebug == 0.1 03:57:08 INFO - mozdevice == 0.49 03:57:08 INFO - mozfile == 1.2 03:57:08 INFO - mozhttpd == 0.7 03:57:08 INFO - mozinfo == 0.9 03:57:08 INFO - mozleak == 0.1 03:57:08 INFO - mozlog == 3.4 03:57:08 INFO - moznetwork == 0.27 03:57:08 INFO - mozprocess == 0.25 03:57:08 INFO - mozprofile == 0.28 03:57:08 INFO - mozrunner == 6.13 03:57:08 INFO - mozscreenshot == 0.1 03:57:08 INFO - mozsystemmonitor == 0.3 03:57:08 INFO - moztest == 0.8 03:57:08 INFO - mozversion == 1.4 03:57:08 INFO - psutil == 3.1.1 03:57:08 INFO - requests == 1.2.3 03:57:08 INFO - wsgiref == 0.1.2 03:57:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:08 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 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:57:08 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 03:57:08 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 03:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:08 INFO - 'HOME': '/Users/cltbld', 03:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:08 INFO - 'LOGNAME': 'cltbld', 03:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:08 INFO - 'MOZ_NO_REMOTE': '1', 03:57:08 INFO - 'NO_EM_RESTART': '1', 03:57:08 INFO - 'PAGER': '/bin/cat', 03:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:08 INFO - 'PWD': '/builds/slave/test', 03:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:08 INFO - 'SHELL': '/bin/bash', 03:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:57:08 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:08 INFO - 'USER': 'cltbld', 03:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:08 INFO - 'XPC_FLAGS': '0x0', 03:57:08 INFO - 'XPC_SERVICE_NAME': '0', 03:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 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)) 03:57:08 INFO - Cleaning up... 03:57:08 INFO - Return code: 0 03:57:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a884b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7e257d640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, '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.PjflpyOA79/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.ImDQUqf3HQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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 03:57:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:08 INFO - 'HOME': '/Users/cltbld', 03:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:08 INFO - 'LOGNAME': 'cltbld', 03:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:08 INFO - 'MOZ_NO_REMOTE': '1', 03:57:08 INFO - 'NO_EM_RESTART': '1', 03:57:08 INFO - 'PAGER': '/bin/cat', 03:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:08 INFO - 'PWD': '/builds/slave/test', 03:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:08 INFO - 'SHELL': '/bin/bash', 03:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:57:08 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:08 INFO - 'USER': 'cltbld', 03:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:08 INFO - 'XPC_FLAGS': '0x0', 03:57:08 INFO - 'XPC_SERVICE_NAME': '0', 03:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:57:09 INFO - Cleaning up... 03:57:09 INFO - Return code: 0 03:57:09 INFO - Running post-action listener: _resource_record_post_action 03:57:09 INFO - Running post-action listener: _start_resource_monitoring 03:57:09 INFO - Starting resource monitoring. 03:57:09 INFO - [mozharness: 2017-03-09 11:57:09.248333Z] Finished create-virtualenv step (success) 03:57:09 INFO - [mozharness: 2017-03-09 11:57:09.249277Z] Running install step. 03:57:09 INFO - Running pre-action listener: _resource_record_pre_action 03:57:09 INFO - Running main action method: install 03:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:09 INFO - Reading from file tmpfile_stdout 03:57:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:57:09 INFO - Reading from file tmpfile_stdout 03:57:09 INFO - Output received: 03:57:09 INFO - Usage: mozinstall [options] installer 03:57:09 INFO - Options: 03:57:09 INFO - -h, --help show this help message and exit 03:57:09 INFO - -d DEST, --destination=DEST 03:57:09 INFO - Directory to install application into. [default: 03:57:09 INFO - "/builds/slave/test"] 03:57:09 INFO - --app=APP Application being installed. [default: firefox] 03:57:09 INFO - mkdir: /builds/slave/test/build/application 03:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:57:29 INFO - Reading from file tmpfile_stdout 03:57:29 INFO - Output received: 03:57:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:57:29 INFO - Running post-action listener: _resource_record_post_action 03:57:29 INFO - [mozharness: 2017-03-09 11:57:29.787104Z] Finished install step (success) 03:57:29 INFO - [mozharness: 2017-03-09 11:57:29.787258Z] Running run-tests step. 03:57:29 INFO - Running pre-action listener: _resource_record_pre_action 03:57:29 INFO - Running main action method: run_tests 03:57:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:57:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:57:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:57:29 INFO - 2017-03-09 03:57:29.801 screenresolution[1620:7235] starting screenresolution argv=screenresolution get 03:57:29 INFO - 2017-03-09 03:57:29.804 screenresolution[1620:7235] Display 0: 1600x1200x32@60 03:57:29 INFO - 2017-03-09 03:57:29.809 screenresolution[1621:7238] starting screenresolution argv=screenresolution list 03:57:29 INFO - Available Modes on Display 0 03:57:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:57:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:57:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:57:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:57:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:57:30 INFO - Intel Iris: 03:57:30 INFO - Chipset Model: Intel Iris 03:57:30 INFO - Type: GPU 03:57:30 INFO - Bus: Built-In 03:57:30 INFO - VRAM (Dynamic, Max): 1536 MB 03:57:30 INFO - Vendor: Intel (0x8086) 03:57:30 INFO - Device ID: 0x0a2e 03:57:30 INFO - Revision ID: 0x0009 03:57:30 INFO - Displays: 03:57:30 INFO - Display: 03:57:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:57:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:57:30 INFO - Display Serial Number: 1600x1200 60 03:57:30 INFO - Main Display: Yes 03:57:30 INFO - Mirror: Off 03:57:30 INFO - Online: Yes 03:57:30 INFO - Rotation: Supported 03:57:30 INFO - Return code: 0 03:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:57:30 INFO - Python 2.7.3 03:57:30 INFO - Return code: 0 03:57:30 INFO - grabbing minidump binary from tooltool 03:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:30 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 0x10a662d30>, '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 0x10a589db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a657be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:57:30 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 03:57:30 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 03:57:30 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 03:57:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:57:30 INFO - Return code: 0 03:57:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:57:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:57:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:57:30 INFO - ENV: RUST_BACKTRACE is now 1 03:57:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:57:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PjflpyOA79/Render', 03:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:30 INFO - 'HOME': '/Users/cltbld', 03:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:30 INFO - 'LOGNAME': 'cltbld', 03:57:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:30 INFO - 'MOZ_NO_REMOTE': '1', 03:57:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:30 INFO - 'NO_EM_RESTART': '1', 03:57:30 INFO - 'PAGER': '/bin/cat', 03:57:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:30 INFO - 'PWD': '/builds/slave/test', 03:57:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:30 INFO - 'RUST_BACKTRACE': '1', 03:57:30 INFO - 'SHELL': '/bin/bash', 03:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ImDQUqf3HQ/Listeners', 03:57:30 INFO - 'TMPDIR': '/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/', 03:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:30 INFO - 'USER': 'cltbld', 03:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:30 INFO - 'XPC_FLAGS': '0x0', 03:57:30 INFO - 'XPC_SERVICE_NAME': '0', 03:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:57:30 INFO - mozversion application_buildid: 20170309023023 03:57:30 INFO - mozversion application_changeset: c40ca7a1bdd93632c6bdc5e23bd33d984d508b19 03:57:30 INFO - mozversion application_display_name: Nightly 03:57:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:57:30 INFO - mozversion application_name: Firefox 03:57:30 INFO - mozversion application_remotingname: firefox 03:57:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:57:30 INFO - mozversion application_vendor: Mozilla 03:57:30 INFO - mozversion application_version: 55.0a1 03:57:30 INFO - mozversion platform_buildid: 20170309023023 03:57:30 INFO - mozversion platform_changeset: c40ca7a1bdd93632c6bdc5e23bd33d984d508b19 03:57:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:57:30 INFO - mozversion platform_version: 55.0a1 03:57:30 INFO - using testdate: 1489060650 03:57:30 INFO - actual date: 1489060650 03:57:30 INFO - starting webserver on 'localhost:49235' 03:57:30 INFO - SUITE-START | Running 2 tests 03:57:30 INFO - TEST-START | dromaeo_css 03:57:30 INFO - operating with platform_type : mac_ 03:57:30 INFO - Initialising browser for dromaeo_css test... 03:57:30 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpoqm9BX/profile http://localhost:49235/getInfo.html) 03:57:33 INFO - PROCESS | 1631 | 2017-03-09 03:57:33.128 plugin-container[1633:7413] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9253, name = 'com.apple.tsm.portname' 03:57:33 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:33 INFO - PROCESS | 1631 | 2017-03-09 03:57:33.131 plugin-container[1633:7413] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e1f, name = 'com.apple.CFPasteboardClient' 03:57:33 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:33 INFO - PROCESS | 1631 | 2017-03-09 03:57:33.131 plugin-container[1633:7413] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:57:33 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 03:57:33 INFO - PROCESS | 1631 | colorDepth:24 03:57:33 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 03:57:33 INFO - PROCESS | 1631 | __metrics 03:57:33 ERROR - PROCESS | 1631 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 03:57:34 INFO - TEST-INFO | 1631: exit 0 03:57:34 INFO - Browser initialized. 03:57:34 INFO - Running cycle 1/1 for dromaeo_css test... 03:57:34 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmpoqm9BX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:36 INFO - PROCESS | 1634 | 2017-03-09 03:57:36.647 plugin-container[1635:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 03:57:36 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:36 INFO - PROCESS | 1634 | 2017-03-09 03:57:36.647 plugin-container[1635:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 03:57:36 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:57:36 INFO - PROCESS | 1634 | 2017-03-09 03:57:36.647 plugin-container[1635:7547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:57:46 INFO - PROCESS | 1634 | RSS: Main: 227332096 03:57:46 INFO - PROCESS | 1634 | 04:00:50 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 04:00:52 INFO - PROCESS | 1634 | RSS: Main: 256897024 04:00:52 INFO - PROCESS | 1634 | 04:02:34 INFO - PROCESS | 1634 | ************************* 04:02:34 INFO - PROCESS | 1634 | A coding exception was thrown and uncaught in a Task. 04:02:34 INFO - PROCESS | 1634 | 04:02:34 ERROR - PROCESS | 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:02:34 INFO - PROCESS | 1634 | Full stack: 04:02:34 INFO - PROCESS | 1634 | ************************* 04:03:54 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 04:03:55 INFO - PROCESS | 1634 | RSS: Main: 260005888 04:03:55 INFO - PROCESS | 1634 | 04:06:57 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 04:06:57 INFO - PROCESS | 1634 | RSS: Main: 272052224 04:06:57 INFO - PROCESS | 1634 | 04:09:59 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 04:10:00 INFO - PROCESS | 1634 | RSS: Main: 264376320 04:10:00 INFO - PROCESS | 1634 | 04:13:03 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 04:13:03 INFO - PROCESS | 1634 | RSS: Main: 275451904 04:13:03 INFO - PROCESS | 1634 | 04:16:06 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 04:16:07 INFO - PROCESS | 1634 | RSS: Main: 269402112 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | __start_tp_report 04:16:07 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 04:16:07 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 04:16:07 INFO - PROCESS | 1634 | |i|pagename|runs| 04:16:07 INFO - PROCESS | 1634 | |0;dojo.html;3455.464403017532;3465.8440370183357;3472.3923313420146;3477.6000759912604;3514.648535146484;4569.771511424428;5750.568707346944;5904.970475147624;5930.703464826763;6001.759929602822;5826.4173582641715;5834.183214349994;5846.444587764156;5923.9407605923925;6074.331823499411;4986.927810520738;4990.725510096943;5241.711705856176;5412.9458705413035;5464.808731694391;5348.91976620351;5431.266778984826;5483.917741233885;5484.7531861066345;5499;5443.564514838803;5470.753816078286;5472.589555783312;5497.862553436176;5511.063119269734;5299.920501192485;5341.412444631087;5444;5461.5357694596005;5463.699496527689;5339.172428273585;5365.490278423583;5376.838694839161;5394.973025134849;5405.486478784509;4695.624350052022;5781.682007489587;5961.076033214858;6064.120702498157;6132;5137.5633071188995;5197.896042079137;6283.340249273806;6290.528210384239;6307.400796924285;5514.6139770216105;6416.582922110095;6438.098666186737;6473.676316184172;6511.28375878653;6127.846803829851;6147.569670123138;6176.413240742122;6188.87622247557;6217.129601855699;5271.499207575274;5454.236406903036;6396.744130234739;6475.643839588868;6524.543281970216;6121.571489995747;6244.063390491372;6312.747490100436;6316.9368306317265;6326.588771729823;6311.96844015777;6323.873522529524;6370.777022804273;6383.425491705769;6387.425131738166;6322.33615470369;6358.682065896779;6363.363663633692;6375.968120159462;6385.872282554323;6226.844328891723;6262.279837818618;6303.936960630335;6306.684665766693;6369.681515924185;5032.169692000852;5137.845864624067;5152.742362881841;5194.636375453682;5201.141811601119;4617.976910115428;4644.442666879996;4657.301404789241;4658.835291177205;4680.742559159279;5954.017587098167;6057.7879774207695;6192.845178870564;6205.720742566596;6208.627482351073;6295.937040629535;6308.337624549448;6339.651319177397;6387.010013447923;6495.382938620918;6128.9693551531955;6136.20229370179;6165.260168779775;6172.814815555541;6184.8763024739255;5669.744861481243;5678.602497825195;5692.7722891083895;5703.657780533182;5707.0583353747;6392.009238568029;6462.806115816533;6569.211694596679;6599.241087275025;6713.2279787824045;6426.4537514311805;6448.806535803933;6449.3550644937;6476.06097115925;6530.706118224596;6544.181977252843;6626.138601981712;6684.732610695518;6704.966475167593;6705.530612857248;6231.844203894939;6250.812475625738;6256.061590761422;6380.87238255223;6408.551401401857;6316.968415157895;6354.586951848115;6451.870962580629;6453.709583068679;6472.838179045561;6114.206128133634;6160.753569857335;6247.406496382836;6265.373462653881;6278.152449419453;5417.7832886685665;6137.315698286874;6248.312685604671;6260.217472815898;6261;6137.110119032626;6146.354632763674;6154.046122850965;6233.376662333731;6242.719077641426;6185.443310102112;6219.346968568416;6246.593971391845;6259.405356491126;6296.244450665949;6244.500439964882;6250.937490625036;6251.812445626639;6253.562250642593;6267.091271765477 04:16:07 INFO - PROCESS | 1634 | |1;ext.html;778.6223681514466;798.8082860113573;818.9181081891811;935.4200395754625;936.6066252174086;37922.81038594807;39763.80118099409;39769.20461590766;39819;39871.60128398723;38105.61894381062;38114.8094259529;38290.61709382905;38346.61653383472;38367.42448863269;7678;10927.508262128224;11176.441177941111;11302.491387887565;11330.09359251262;9335.953320233424;11143.275687080355;11156.442177891113;11170.273932194423;11186.944065279702;11091.944540277327;11126.38804865732;11136.832947505794;11192.664220073411;11207.551697932073;11110.833337499944;11129.998300152985;11166.330020198815;11194.66416007517;11199.216054876162;10923.508442120044;10931.508082136323;10984.340939543654;10988.835167472414;10990.725231869268;38300.425493617615;38500.229995400216;38521.614783852085;38541.42187867184;38649.64726234568;3053.3286859532896;3217.806931584089;3777.4522694209186;3821.197548514815;3949.2891279569667;23359.66098997297;24408;27491.587626185625;27719.307017324525;28414;3165.09794708507;3167.9683203167906;3196.1530194498478;3253.414385410625;3267.738580913545;258456;336309;346971;347755.2612236923;347954;49794;50054.9989000218;50143.498565013884;50223;50265.24602130971;53077.73461132747;53412;53486.930261394555;53546.73226633842;53560.732196338766;14651.487197948021;14730.26348682583;14733.336999835034;14871.884608654394;14899.404023839143;12045.939770301093;12107;12110.031197504355;12115.515379384902;12137.635870923887;12158.331291779037;13673.906087512974;13751;13786.931065344608;13789.241591712553;5916.585838991229;5946.4053594641055;5967.6419414835245;5978.5814992950945;5990.5507086968655;1005.4369553050316;1050.1598721023304;1130.4856290387854;1133.1274918312854;1181.9172657914035;19598.902005489883;24792;26126.477470450864;26318.47363052728;26430.339241519116;1233.8766123387588;1243.3348158735214;1249.5501619417003;1270.1553466944563;1284.9229046257256;11226.494807733672;13287.734245315232;13443.058985870895;13455.394507247198;13592.796108058386;342953;444930;445493;446952;447253;45565.08869822585;55799.72100139473;56266.15600765992;56444;56525.71737141288;1402.144691738039;1428.250168661461;1432.9856701433062;1443;1443.9061461005;27079.531877240035;28217.012404565743;29157.979470718423;29233.707662923098;29725.405491889607;44544.33183502315;48968.5103148964;51374.486255138465;51695.966080678925;53055.734721326146;470154;472746;476733;477133;477444.6127769339;33270.334593308486;33350.833245833615;33387.332253355286;33471.1632209187;33571.664283356855;42412.36381454219;42477.78761106298;42742.504012359415;42942.78528607337;43852;35038.49615038476;35096.64903350934;35325.82337088298;35895;36584.85420599933;31023.29371884568;33493.83253083719;33918.321633567684;36094.09764755902;36908.81545592255;26311.868440657676;35288.47067293942;35405.760798371935;35735.82132089323;35913.640863592074;29685.703142968294;30982.84508577443;31065.223369415944;31088;31126.53310200394;23366.766332336458;23518.312376141494;23549.529009420057;23578.29265122049;23979.76020239845 04:16:07 INFO - PROCESS | 1634 | |2;jquery.html;348.24604730757903;390.21371935549865;397.8508059477696;399.4746907816223;444.6020811373821;20727.85996770179;21188;21239.256626018094;21436.57126857462;21765.455863603416;19313.0343482826;20756.792432075676;21022;21081.789182108176;21234.45186577613;6004.669743164113;6757.391834734873;6801.28586498418;6811.25076241613;6834.179898412198;6492.837679058014;6619.205695316568;6660.933390666104;6670.866582668368;6723.394894459498;6743.123393958778;6755.189377274709;6757;6758.222804377486;6775.830604234884;6631.104800851872;6650.600963942179;6694.0962969999155;6915.51591388598;6927.653617319113;6629.370209830058;6640.203175618956;6705.826480365917;6728.192616885956;6731.461483081342;23345.182918597937;24396.26811195649;25959.35101622456;26450.86774566134;26917.865410673017;2677;2684.087410280514;2700.230434326227;2714.19931120318;2725.1960671601923;12155.756884862341;15164.725291206298;16282.674346513122;16371.59071023222;16723.57849582787;1807.4487281379174;2043.4993426610577;2103.4110449074283;2111.429913923232;2123.097683484513;130984;136580.31709841389;150534.7419788704;155673;159414;22456.32631021072;23320.55077589457;23434.296971090553;23807.642885356734;23882.880585596962;36793.136720643946;36862.631373686454;36880.631193688256;36942.076448089014;36961.26077478489;10464.52909619069;10643.787124257471;10805.405702686428;10929.344239345506;10943.616973405893;7580.431467639949;8643.697470588624;8646.913530864611;8664.696735614223;8818.823623527494;10817.459127043616;11080.5567777288;11175.552977880794;11251;11281.21031527785;3966.7024973126554;4435.423394958635;4506.639468842551;4522.924411400214;4570.65720070996;1011.0950699124243;1048.66442738323;1059.210887888528;1070.1533919773365;1074.3392813419691;22894.771052289263;23165.652515212285;23249.651255231183;23331;23350;934.2806039349663;1070.3898777696638;1080.3949788118678;1084.5986984815668;1104.1608377632897;11311;11885.346305953255;12009.63971080869;12061.577844775566;12112.576059837864;664980;692668;694087;715421;727076;102605;121294;144618.83071753933;145411;145553;1142.6914733022204;1158.8609366876028;1200.5557943560939;1207.1791181996327;1230.1880758699215;19806.90096549508;19810.90094549518;19893.10481028386;20128.892910889455;20169.59660806805;48970.510294896594;55641.72179139078;62013;62701.68649156725;62797;58276.4172358288;86579.56710216409;87587.56206218927;88559;89698;11857.762844742885;11901.880981190077;12099.516019359127;12172.452239649414;12204.755904881675;13440;13515.797263041259;13840.16958982424;13885.028048036944;13953.511627093003;8899.421537600036;10825.520419514009;11137.944310278397;11179.385133817394;11548.538058477567;10580.36517808934;11449.771004579696;11691.883081169079;11725.296482211095;11939.940300298791;9729.951350243487;10686.57253709843;11661.591844285409;11916.76166476683;12159.270443773403;11864.406779661154;11901.403185205356;12110.273383597012;12140.514379424723;12181.330026848254;11663.941680291544;11871.287722736663;11987.981021613261;12036.217645852992;12040.157188997036 04:16:07 INFO - PROCESS | 1634 | |3;mootools.html;1318.8285522882024;1512.6747749233912;1522.3682171898656;1549.9845001549982;1560.1263292556168;9375.39059961102;9602.135807777298;10536.578536858526;11166.330020198784;11189;10379.169666426667;10697;10705.46472676367;10706.839397409025;10727.785444291128;1735.696253155699;1871.9625607487842;2241.6774103279063;2278.2937289440283;2338.9298321050396;2255.458689914417;2294.8393612447135;2297.425643589103;2303.665968434576;2319.3853628788384;2283.3378320287097;2287.359539328982;2287.542491501698;2296.1159953417887;2303.884805759714;2217.922372716955;2243.2933625907785;2261.547690461914;2262.592733308004;2263.060829755658;2242.7420846602686;2298.9769552549124;2300.1259521381767;2303.366574192094;2307.3654744945106;9985.502174673784;10308.22688298373;10962.835557466644;11019.338839669645;11081.113510919107;5540.833774986757;5686.3744988051285;5691.4593113654555;5693.800716974927;5699.458551437607;2910.738033576967;3277.836108194581;3281.6226133994655;3489.5114683944266;3540.7344449166417;1039.9324043937195;1166.8891455311732;1227.9754404911941;1230.034422977958;1243.60204734485;14929.328180231916;15159.166245856473;15283.617909552238;15309.693806123814;15316;4352.717073390219;4531.909361812745;4592.586667199968;4832.081904438216;4834.6374021948495;10081.899181008095;12561.685957850945;12732;12740.490380384681;12747.808782868264;5677.602567820213;5715.9997000524745;5764;5773.076307790733;5793.044147715664;4918.5819205367225;5045.167547354646;5158.690478571298;5174.7930082796265;5191.2472691459525;3213.389456003352;3746.925061498809;3879.476270703419;3890.727649064595;3955.723099383016;1015.9441230732233;1017.9389236645825;1020.2093377632394;1028.7428142964259;1034.2863424237253;772.9961350193213;788.1409263902374;806.664969475519;811.7321283976273;818.2880893622587;6338.619682819046;6356.904646430307;6708.865822683519;6763.830904227434;6777.49168812341;884.6726711116928;899.4738078224312;951.5194826612517;983.4836710726926;994.4083270454213;4184.476940382452;4487;4491.887702807456;4619.330197121399;4630.398048253705;13425;13576.1854288743;13669.931650341883;13709.451621935012;13738.969577281743;10386.688399348031;13076.084674072723;13392.799108013192;13611.931940340235;13640.795388069188;9916.107550320507;9992.350497217658;10122.937091605423;10128.392296462236;10154;5768.365479797135;6607.273199947906;6699.564528305645;6810.659467026728;6838.487113466411;10123.24075694311;11949.581764638197;12072.3360215189;12142;12159.270443773048;11983.580574679845;12215.389230538616;12221;12229.388530573613;12239.510419583117;7363.374113200438;9320.906790932004;9323.254139668674;9327.486988215713;9333.579988900652;9433.716988490356;9750.951245243728;9950.900490994998;9971.45157016371;9980.550875210778;8472.322214222972;8712.78218044554;8746.956265218632;8802.867956980523;8820.867686984571;8544.658213671632;8554.315654747481;8605.096464871349;8637.265832404317;8678.218960293603;7553.5845528494265;7582.924170758442;7687;7690.423218258765;7703.768886933401;4691.906161876812;5641.492265696107;5764.538836893123;5805.593608447368;5851.473367396955;5723.6851973141875;5872.001759700775;5896.498797602252;5961.434205879963;6000.669963152069 04:16:07 INFO - PROCESS | 1634 | |4;prototype.html;365.93961996270616;368.2193749251586;375.90226541099304;388.98638547650836;390.2818813383375;4868.68353557019;5121.795128194877;5163.638545301826;5260.842174734759;5285.577153827694;4022.698297627675;4812.7968007998;5092.363454568179;5190.169572868342;5209.687418754877;3781.3004594150034;3831.5210598675167;4127.215828992489;4242.766647834368;4300.1829652366105;3515.4726790981304;3782.6406491383414;4135.917281654365;4300.892477688051;4306.267934451135;3620.0406892173596;3674.50394196784;3960.8811735647832;4244.896326954999;4307.913841723163;3679.2089700714473;3837.846486140579;4266.424032755584;4340.43744252069;4404.867853964371;3465.8960231193105;3886.4753258310334;3893.27974324748;3929.620526347339;3978.8408463661417;4430.016231739293;4975.7512124394;5004.724740139291;5061.506855477643;5066.442691303953;4718.764061796931;4786.664933454659;4854.2961270615915;4983.750812459362;5003.474635163292;4427.092446048552;5036.571891389236;5257.18627073659;5476.644018138848;5528.834134975958;3753.530808648919;3952.565217826037;4368.7815609219415;4417.89552611847;4440.333949907507;5136.460671629462;5168.4314725380555;5506.68719137963;5815.912761308584;5940.079287710412;3883.9319187223173;4416.734995900256;4441.933370999438;4496.685232033727;4534.569215924482;4310.52584215736;4602.470715867651;4610.40064791575;4659.767011649404;4754.809807607726;4009.3184158693093;4373.409589705413;4416.558344165558;4781.856544303677;4957.702537847741;5496.258005169331;5496.285482887279;5506.697131657717;5518.144687573433;5588.972055139699;1541.8303986561468;1556.5019193858084;1560.5552417560941;1562.4843901415609;1564.68566404221;947.4504787223394;1047.4814966591587;1168.6435637130587;1178.3106882473849;1182.6097387861985;4835.903281934412;4939.2838038484215;5123.8719032023755;5197.8440646780655;5232.2674825524455;5134.794608215704;5211.947880521222;5317.441668624816;5538.280023596906;5825.727966093227;4870.902581948412;4987.5760560352055;5183.45573714762;5274.868128296824;5287.07476191665;2621.3839747659285;2642.2825163643784;2734.1524127520534;2753.9724602753718;2759.482284743383;5160.142348754445;5245.957344740042;5942.8811423771285;6012.759489620454;6034.607750496204;4913.820683036117;5122.436531981479;5238.92141617876;5351.625386222927;5715;5398.946010539923;5562.47032066177;5736.823951090017;5924.289085309791;6024.849378765478;4738.573528382462;4796.040791841576;5335.413104558418;5774.307083150049;5795.855533452649;5130.871728206825;5454.563634909277;5552.7778888843995;5719.5138413235345;5740;5725.965238423642;5835.795747148999;5862.472377486047;6051.062085376773;6154.78458253959;3946.6053394660303;4292.785360731888;4495.415595972503;4576.908461830678;4614.353990441274;5049.8737531562;5209.322788037531;5672.659640421588;5794.1308803679785;5900.321463031634;5371.946280537301;5462.863428414322;5495.258140151029;5501.889962200814;5640;4627.154300105478;4919.680220785637;5343.118385466354;5555.555555555465;5569.805056822992;4346.978265108654;4632.837850675211;4822.324874517641;5433.429489903503;5455.236266922713;4650.256153942271;5445.455454454523;5456.699881506555;5470.562355011669;5544.3346798384455;4858.6356023297685;4995.551290125823;5136.768845401891;5272.945410917755;5413.83758487246 04:16:07 INFO - PROCESS | 1634 | |5;yui.html;696.2132789947359;727.8944553039813;763.8122719171689;815.9347252219821;822.5475988206487;13063.346832658364;14966;15880;16116.677666446663;16146.677066458678;15087;15242.466513672023;15255.466058687949;15271.236438178075;15279;631.3307893632741;903.5708038681606;924.8381533231691;956.2780101023717;957.5690939077408;667.7562689618303;922.1055576091181;952.347641865321;952.5761036338832;954.8949615542285;879.3888247667874;974.5187315280768;989.5695372512944;1004.9547770350351;1005.36661903001;844.252836239926;996.3772642098688;997.0278977996431;997.1175509673918;998.59057786308;670.8893032649607;683.3030309084755;739.693027393631;739.9973036535471;741.462439731196;15436.3825446982;15810.841891581053;15863.206839658087;16576.83423165765;16880.746788798177;12161.635150945485;14339.354729037219;14405;14411.495597654031;14863.331150098164;11679.474423650956;11690.941545292304;11751.118666100077;11934.641960741192;12157.69605759863;3650.3976843820737;3657.396529572644;3742.9064273393383;3758.1168425420005;3845.6346647068485;93716;94731;95168;95486.52256738741;96397;9361.157495825408;9853.802923941481;10973.83539246912;11046.944765276123;11130.554777808959;23540.940657670446;23730.881345593163;23768;23790.929408176675;23815.285541433437;7011.404030657452;7261.019762332123;7312.670929808171;7758.14660387357;7968.800779980431;4275.508316543575;4290.377895205178;4387.802548885308;4431.645468362523;4450.154470650566;3389.7288216942584;3428.6228514863346;3446.655334466533;3450.654934506529;3478.7912725236565;625.1185827982523;636.045931103345;637.8118455055769;641.6984017511855;643.6781609195402;595.5265563876642;616.7317217010615;664.8040264732646;668.9431398331099;678.9916973294653;9367.391119577205;9588.328816982883;9676.694095957648;9702.417854928726;9772.560234789451;620.602814198913;664.3301255395868;666.1573110015834;672.5677783407369;689.1007235557645;4452.844150454718;4505.805961420227;4572.862814115582;4780.211265141283;4788.1381351356395;97845.51077244568;97933;99493;100724;102335.48832255791;73069.6346518264;74412.5117497647;87266;87604.5619771897;87999;63401;67047.32381690497;71532.64233678902;72157.04309487266;72572;7736.497127686683;8710.69512567057;9035.954820225857;9105.27157827359;9246.260299176185;32118.715251389684;35850.92447226588;48264.27603585879;49626.503734962185;49835;62322.68838655778;63557;64173.35826641674;64181.679091604245;64339.3915152125;11228.606998755331;11358.829617555579;11448.198626096093;11756.23584467007;11852.780389289224;11825.82261266099;11922.880771192176;11960.64118076459;12032;12545.184563003375;11551.079651648255;11551.364674942623;11655.708607284885;11745.647630571097;11756.882431175578;9667.854982175133;11268.774624507629;11628.94185529067;11902.821457677968;12054.216475929037;11268.042216411697;11487.655370338904;11525.423728813357;11868.940655296668;12016.519339226683;9444.338896277195;9600.478528524953;9656.468894210886;9739.123478886933;9865.852012219966;7222;7525.774226773206;9671.226301895691;9679.612815487302;9708.854367184356 04:16:07 INFO - PROCESS | 1634 | __end_tp_report 04:16:07 INFO - PROCESS | 1634 | __start_cc_report 04:16:07 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,423 04:16:07 INFO - PROCESS | 1634 | __end_cc_report 04:16:07 INFO - PROCESS | 1634 | __startTimestamp1489061767092__endTimestamp 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | ------- Summary: start ------- 04:16:07 INFO - PROCESS | 1634 | Number of tests: 6 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5900.59 Median:6184.88 stddev:665.01 (10.8%) stddev-sans-first:638.79 04:16:07 INFO - PROCESS | 1634 | Values: 3455.5 3465.8 3472.4 3477.6 3514.6 4569.8 5750.6 5905.0 5930.7 6001.8 5826.4 5834.2 5846.4 5923.9 6074.3 4986.9 4990.7 5241.7 5412.9 5464.8 5348.9 5431.3 5483.9 5484.8 5499.0 5443.6 5470.8 5472.6 5497.9 5511.1 5299.9 5341.4 5444.0 5461.5 5463.7 5339.2 5365.5 5376.8 5395.0 5405.5 4695.6 5781.7 5961.1 6064.1 6132.0 5137.6 5197.9 6283.3 6290.5 6307.4 5514.6 6416.6 6438.1 6473.7 6511.3 6127.8 6147.6 6176.4 6188.9 6217.1 5271.5 5454.2 6396.7 6475.6 6524.5 6121.6 6244.1 6312.7 6316.9 6326.6 6312.0 6323.9 6370.8 6383.4 6387.4 6322.3 6358.7 6363.4 6376.0 6385.9 6226.8 6262.3 6303.9 6306.7 6369.7 5032.2 5137.8 5152.7 5194.6 5201.1 4618.0 4644.4 4657.3 4658.8 4680.7 5954.0 6057.8 6192.8 6205.7 6208.6 6295.9 6308.3 6339.7 6387.0 6495.4 6129.0 6136.2 6165.3 6172.8 6184.9 5669.7 5678.6 5692.8 5703.7 5707.1 6392.0 6462.8 6569.2 6599.2 6713.2 6426.5 6448.8 6449.4 6476.1 6530.7 6544.2 6626.1 6684.7 6705.0 6705.5 6231.8 6250.8 6256.1 6380.9 6408.6 6317.0 6354.6 6451.9 6453.7 6472.8 6114.2 6160.8 6247.4 6265.4 6278.2 5417.8 6137.3 6248.3 6260.2 6261.0 6137.1 6146.4 6154.0 6233.4 6242.7 6185.4 6219.3 6246.6 6259.4 6296.2 6244.5 6250.9 6251.8 6253.6 6267.1 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:55298.19 Median:3235.61 stddev:110041.94 (3401.0%) stddev-sans-first:110274.50 04:16:07 INFO - PROCESS | 1634 | Values: 778.6 798.8 818.9 935.4 936.6 37922.8 39763.8 39769.2 39819.0 39871.6 38105.6 38114.8 38290.6 38346.6 38367.4 7678.0 10927.5 11176.4 11302.5 11330.1 9336.0 11143.3 11156.4 11170.3 11186.9 11091.9 11126.4 11136.8 11192.7 11207.6 11110.8 11130.0 11166.3 11194.7 11199.2 10923.5 10931.5 10984.3 10988.8 10990.7 38300.4 38500.2 38521.6 38541.4 38649.6 3053.3 3217.8 3777.5 3821.2 3949.3 23359.7 24408.0 27491.6 27719.3 28414.0 3165.1 3168.0 3196.2 3253.4 3267.7 258456.0 336309.0 346971.0 347755.3 347954.0 49794.0 50055.0 50143.5 50223.0 50265.2 53077.7 53412.0 53486.9 53546.7 53560.7 14651.5 14730.3 14733.3 14871.9 14899.4 12045.9 12107.0 12110.0 12115.5 12137.6 12158.3 13673.9 13751.0 13786.9 13789.2 5916.6 5946.4 5967.6 5978.6 5990.6 1005.4 1050.2 1130.5 1133.1 1181.9 19598.9 24792.0 26126.5 26318.5 26430.3 1233.9 1243.3 1249.6 1270.2 1284.9 11226.5 13287.7 13443.1 13455.4 13592.8 342953.0 444930.0 445493.0 446952.0 447253.0 45565.1 55799.7 56266.2 56444.0 56525.7 1402.1 1428.3 1433.0 1443.0 1443.9 27079.5 28217.0 29158.0 29233.7 29725.4 44544.3 48968.5 51374.5 51696.0 53055.7 470154.0 472746.0 476733.0 477133.0 477444.6 33270.3 33350.8 33387.3 33471.2 33571.7 42412.4 42477.8 42742.5 42942.8 43852.0 35038.5 35096.6 35325.8 35895.0 36584.9 31023.3 33493.8 33918.3 36094.1 36908.8 26311.9 35288.5 35405.8 35735.8 35913.6 29685.7 30982.8 31065.2 31088.0 31126.5 23366.8 23518.3 23549.5 23578.3 23979.8 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:40993.86 Median:11649.71 stddev:116311.58 (998.4%) stddev-sans-first:116597.83 04:16:07 INFO - PROCESS | 1634 | Values: 348.2 390.2 397.9 399.5 444.6 20727.9 21188.0 21239.3 21436.6 21765.5 19313.0 20756.8 21022.0 21081.8 21234.5 6004.7 6757.4 6801.3 6811.3 6834.2 6492.8 6619.2 6660.9 6670.9 6723.4 6743.1 6755.2 6757.0 6758.2 6775.8 6631.1 6650.6 6694.1 6915.5 6927.7 6629.4 6640.2 6705.8 6728.2 6731.5 23345.2 24396.3 25959.4 26450.9 26917.9 2677.0 2684.1 2700.2 2714.2 2725.2 12155.8 15164.7 16282.7 16371.6 16723.6 1807.4 2043.5 2103.4 2111.4 2123.1 130984.0 136580.3 150534.7 155673.0 159414.0 22456.3 23320.6 23434.3 23807.6 23882.9 36793.1 36862.6 36880.6 36942.1 36961.3 10464.5 10643.8 10805.4 10929.3 10943.6 7580.4 8643.7 8646.9 8664.7 8818.8 10817.5 11080.6 11175.6 11251.0 11281.2 3966.7 4435.4 4506.6 4522.9 4570.7 1011.1 1048.7 1059.2 1070.2 1074.3 22894.8 23165.7 23249.7 23331.0 23350.0 934.3 1070.4 1080.4 1084.6 1104.2 11311.0 11885.3 12009.6 12061.6 12112.6 664980.0 692668.0 694087.0 715421.0 727076.0 102605.0 121294.0 144618.8 145411.0 145553.0 1142.7 1158.9 1200.6 1207.2 1230.2 19806.9 19810.9 19893.1 20128.9 20169.6 48970.5 55641.7 62013.0 62701.7 62797.0 58276.4 86579.6 87587.6 88559.0 89698.0 11857.8 11901.9 12099.5 12172.5 12204.8 13440.0 13515.8 13840.2 13885.0 13953.5 8899.4 10825.5 11137.9 11179.4 11548.5 10580.4 11449.8 11691.9 11725.3 11939.9 9730.0 10686.6 11661.6 11916.8 12159.3 11864.4 11901.4 12110.3 12140.5 12181.3 11663.9 11871.3 11988.0 12036.2 12040.2 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:6584.31 Median:3643.83 stddev:4161.68 (114.2%) stddev-sans-first:4154.54 04:16:07 INFO - PROCESS | 1634 | Values: 1318.8 1512.7 1522.4 1550.0 1560.1 9375.4 9602.1 10536.6 11166.3 11189.0 10379.2 10697.0 10705.5 10706.8 10727.8 1735.7 1872.0 2241.7 2278.3 2338.9 2255.5 2294.8 2297.4 2303.7 2319.4 2283.3 2287.4 2287.5 2296.1 2303.9 2217.9 2243.3 2261.5 2262.6 2263.1 2242.7 2299.0 2300.1 2303.4 2307.4 9985.5 10308.2 10962.8 11019.3 11081.1 5540.8 5686.4 5691.5 5693.8 5699.5 2910.7 3277.8 3281.6 3489.5 3540.7 1039.9 1166.9 1228.0 1230.0 1243.6 14929.3 15159.2 15283.6 15309.7 15316.0 4352.7 4531.9 4592.6 4832.1 4834.6 10081.9 12561.7 12732.0 12740.5 12747.8 5677.6 5716.0 5764.0 5773.1 5793.0 4918.6 5045.2 5158.7 5174.8 5191.2 3213.4 3746.9 3879.5 3890.7 3955.7 1015.9 1017.9 1020.2 1028.7 1034.3 773.0 788.1 806.7 811.7 818.3 6338.6 6356.9 6708.9 6763.8 6777.5 884.7 899.5 951.5 983.5 994.4 4184.5 4487.0 4491.9 4619.3 4630.4 13425.0 13576.2 13669.9 13709.5 13739.0 10386.7 13076.1 13392.8 13611.9 13640.8 9916.1 9992.4 10122.9 10128.4 10154.0 5768.4 6607.3 6699.6 6810.7 6838.5 10123.2 11949.6 12072.3 12142.0 12159.3 11983.6 12215.4 12221.0 12229.4 12239.5 7363.4 9320.9 9323.3 9327.5 9333.6 9433.7 9751.0 9950.9 9971.5 9980.6 8472.3 8712.8 8747.0 8802.9 8820.9 8544.7 8554.3 8605.1 8637.3 8678.2 7553.6 7582.9 7687.0 7690.4 7703.8 4691.9 5641.5 5764.5 5805.6 5851.5 5723.7 5872.0 5896.5 5961.4 6000.7 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4563.97 Median:4979.75 stddev:1295.49 (26.0%) stddev-sans-first:1260.23 04:16:07 INFO - PROCESS | 1634 | Values: 365.9 368.2 375.9 389.0 390.3 4868.7 5121.8 5163.6 5260.8 5285.6 4022.7 4812.8 5092.4 5190.2 5209.7 3781.3 3831.5 4127.2 4242.8 4300.2 3515.5 3782.6 4135.9 4300.9 4306.3 3620.0 3674.5 3960.9 4244.9 4307.9 3679.2 3837.8 4266.4 4340.4 4404.9 3465.9 3886.5 3893.3 3929.6 3978.8 4430.0 4975.8 5004.7 5061.5 5066.4 4718.8 4786.7 4854.3 4983.8 5003.5 4427.1 5036.6 5257.2 5476.6 5528.8 3753.5 3952.6 4368.8 4417.9 4440.3 5136.5 5168.4 5506.7 5815.9 5940.1 3883.9 4416.7 4441.9 4496.7 4534.6 4310.5 4602.5 4610.4 4659.8 4754.8 4009.3 4373.4 4416.6 4781.9 4957.7 5496.3 5496.3 5506.7 5518.1 5589.0 1541.8 1556.5 1560.6 1562.5 1564.7 947.5 1047.5 1168.6 1178.3 1182.6 4835.9 4939.3 5123.9 5197.8 5232.3 5134.8 5211.9 5317.4 5538.3 5825.7 4870.9 4987.6 5183.5 5274.9 5287.1 2621.4 2642.3 2734.2 2754.0 2759.5 5160.1 5246.0 5942.9 6012.8 6034.6 4913.8 5122.4 5238.9 5351.6 5715.0 5398.9 5562.5 5736.8 5924.3 6024.8 4738.6 4796.0 5335.4 5774.3 5795.9 5130.9 5454.6 5552.8 5719.5 5740.0 5726.0 5835.8 5862.5 6051.1 6154.8 3946.6 4292.8 4495.4 4576.9 4614.4 5049.9 5209.3 5672.7 5794.1 5900.3 5371.9 5462.9 5495.3 5501.9 5640.0 4627.2 4919.7 5343.1 5555.6 5569.8 4347.0 4632.8 4822.3 5433.4 5455.2 4650.3 5445.5 5456.7 5470.6 5544.3 4858.6 4995.6 5136.8 5272.9 5413.8 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:19039.51 Median:628.22 stddev:27153.89 (4322.3%) stddev-sans-first:27195.13 04:16:07 INFO - PROCESS | 1634 | Values: 696.2 727.9 763.8 815.9 822.5 13063.3 14966.0 15880.0 16116.7 16146.7 15087.0 15242.5 15255.5 15271.2 15279.0 631.3 903.6 924.8 956.3 957.6 667.8 922.1 952.3 952.6 954.9 879.4 974.5 989.6 1005.0 1005.4 844.3 996.4 997.0 997.1 998.6 670.9 683.3 739.7 740.0 741.5 15436.4 15810.8 15863.2 16576.8 16880.7 12161.6 14339.4 14405.0 14411.5 14863.3 11679.5 11690.9 11751.1 11934.6 12157.7 3650.4 3657.4 3742.9 3758.1 3845.6 93716.0 94731.0 95168.0 95486.5 96397.0 9361.2 9853.8 10973.8 11046.9 11130.6 23540.9 23730.9 23768.0 23790.9 23815.3 7011.4 7261.0 7312.7 7758.1 7968.8 4275.5 4290.4 4387.8 4431.6 4450.2 3389.7 3428.6 3446.7 3450.7 3478.8 625.1 636.0 637.8 641.7 643.7 595.5 616.7 664.8 668.9 679.0 9367.4 9588.3 9676.7 9702.4 9772.6 620.6 664.3 666.2 672.6 689.1 4452.8 4505.8 4572.9 4780.2 4788.1 97845.5 97933.0 99493.0 100724.0 102335.5 73069.6 74412.5 87266.0 87604.6 87999.0 63401.0 67047.3 71532.6 72157.0 72572.0 7736.5 8710.7 9036.0 9105.3 9246.3 32118.7 35850.9 48264.3 49626.5 49835.0 62322.7 63557.0 64173.4 64181.7 64339.4 11228.6 11358.8 11448.2 11756.2 11852.8 11825.8 11922.9 11960.6 12032.0 12545.2 11551.1 11551.4 11655.7 11745.6 11756.9 9667.9 11268.8 11628.9 11902.8 12054.2 11268.0 11487.7 11525.4 11868.9 12016.5 9444.3 9600.5 9656.5 9739.1 9865.9 7222.0 7525.8 9671.2 9679.6 9708.9 04:16:07 INFO - PROCESS | 1634 | -------- Summary: end -------- 04:16:07 INFO - PROCESS | 1634 | 04:16:07 INFO - TEST-INFO | 1634: exit 0 04:16:07 INFO - TEST-OK | dromaeo_css | took 1117506ms 04:16:07 INFO - TEST-START | kraken 04:16:07 INFO - operating with platform_type : mac_ 04:16:07 INFO - Initialising browser for kraken test... 04:16:07 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmptquvSD/profile http://localhost:49235/getInfo.html) 04:16:10 INFO - PROCESS | 1648 | 2017-03-09 04:16:10.679 plugin-container[1649:10091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 04:16:10 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:10 INFO - PROCESS | 1648 | 2017-03-09 04:16:10.682 plugin-container[1649:10091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 04:16:10 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:10 INFO - PROCESS | 1648 | 2017-03-09 04:16:10.682 plugin-container[1649:10091] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:10 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 04:16:10 INFO - PROCESS | 1648 | colorDepth:24 04:16:10 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 04:16:10 INFO - PROCESS | 1648 | __metrics 04:16:10 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 04:16:11 INFO - TEST-INFO | 1648: exit 0 04:16:11 INFO - Browser initialized. 04:16:11 INFO - Running cycle 1/1 for kraken test... 04:16:11 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/T/tmptquvSD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:13 INFO - PROCESS | 1650 | 2017-03-09 04:16:13.901 plugin-container[1651:10220] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:16:13 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:13 INFO - PROCESS | 1650 | 2017-03-09 04:16:13.902 plugin-container[1651:10220] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:16:13 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:13 INFO - PROCESS | 1650 | 2017-03-09 04:16:13.902 plugin-container[1651:10220] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:23 INFO - PROCESS | 1650 | RSS: Main: 228700160 04:16:23 INFO - PROCESS | 1650 | 04:16:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 04:16:27 INFO - PROCESS | 1650 | RSS: Main: 241614848 04:16:27 INFO - PROCESS | 1650 | 04:16:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 04:16:30 INFO - PROCESS | 1650 | RSS: Main: 250093568 04:16:30 INFO - PROCESS | 1650 | 04:16:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 04:16:34 INFO - PROCESS | 1650 | RSS: Main: 252497920 04:16:34 INFO - PROCESS | 1650 | 04:16:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 04:16:37 INFO - PROCESS | 1650 | RSS: Main: 253870080 04:16:37 INFO - PROCESS | 1650 | 04:16:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:39 INFO - PROCESS | 1650 | RSS: Main: 255631360 04:16:39 INFO - PROCESS | 1650 | 04:16:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:44 INFO - PROCESS | 1650 | RSS: Main: 258580480 04:16:44 INFO - PROCESS | 1650 | 04:16:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 04:16:48 INFO - PROCESS | 1650 | RSS: Main: 260161536 04:16:48 INFO - PROCESS | 1650 | 04:16:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 04:16:52 INFO - PROCESS | 1650 | RSS: Main: 260677632 04:16:52 INFO - PROCESS | 1650 | 04:16:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:16:54 INFO - PROCESS | 1650 | RSS: Main: 260415488 04:16:54 INFO - PROCESS | 1650 | 04:16:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:16:56 INFO - PROCESS | 1650 | RSS: Main: 260333568 04:16:56 INFO - PROCESS | 1650 | 04:16:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:16:59 INFO - PROCESS | 1650 | RSS: Main: 260497408 04:16:59 INFO - PROCESS | 1650 | 04:17:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:17:03 INFO - PROCESS | 1650 | RSS: Main: 260927488 04:17:03 INFO - PROCESS | 1650 | 04:17:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:17:07 INFO - PROCESS | 1650 | RSS: Main: 260685824 04:17:07 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 04:17:09 INFO - PROCESS | 1650 | RSS: Main: 264163328 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | __start_tp_report 04:17:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 04:17:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 04:17:09 INFO - PROCESS | 1650 | |i|pagename|runs| 04:17:09 INFO - PROCESS | 1650 | |0;ai-astar;109;121;118;118;119;118;119;119;123;120 04:17:09 INFO - PROCESS | 1650 | |1;audio-beat-detection;153;144;114;154;112;113;114;114;146;147 04:17:09 INFO - PROCESS | 1650 | |2;audio-dft;196;201;200;221;203;275;285;225;198;198 04:17:09 INFO - PROCESS | 1650 | |3;audio-fft;83;59;62;88;61;62;60;62;78;62 04:17:09 INFO - PROCESS | 1650 | |4;audio-oscillator;131;103;95;72;71;73;83;70;70;83 04:17:09 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;88;116;136;87;97;138;136;97;135;135 04:17:09 INFO - PROCESS | 1650 | |6;imaging-darkroom;120;132;111;156;120;97;97;97;96;97 04:17:09 INFO - PROCESS | 1650 | |7;imaging-desaturate;94;148;102;145;147;92;90;91;93;89 04:17:09 INFO - PROCESS | 1650 | |8;json-parse-financial;67;65;69;69;67;68;68;68;69;68 04:17:09 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;55;70;58;64;59;56;56;57;58;64 04:17:09 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;87;87;87;89;105;117;112;88;85;89 04:17:09 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;139;140;141;139;142;144;158;160;181;140 04:17:09 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;231;175;172;225;242;181;172;172;174;175 04:17:09 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;69;65;69;78;85;65;73;85;90;91 04:17:09 INFO - PROCESS | 1650 | __end_tp_report 04:17:09 INFO - PROCESS | 1650 | __start_cc_report 04:17:09 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,982 04:17:09 INFO - PROCESS | 1650 | __end_cc_report 04:17:09 INFO - PROCESS | 1650 | __startTimestamp1489061829342__endTimestamp 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | ------- Summary: start ------- 04:17:09 INFO - PROCESS | 1650 | Number of tests: 14 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:118.40 Median:119.00 stddev:3.66 (3.1%) stddev-sans-first:1.67 04:17:09 INFO - PROCESS | 1650 | Values: 109.0 121.0 118.0 118.0 119.0 118.0 119.0 119.0 123.0 120.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:145.00 stddev:18.90 (13.0%) stddev-sans-first:18.31 04:17:09 INFO - PROCESS | 1650 | Values: 153.0 144.0 114.0 154.0 112.0 113.0 114.0 114.0 146.0 147.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:220.20 Median:212.00 stddev:33.12 (15.6%) stddev-sans-first:33.95 04:17:09 INFO - PROCESS | 1650 | Values: 196.0 201.0 200.0 221.0 203.0 275.0 285.0 225.0 198.0 198.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:67.70 Median:62.00 stddev:10.86 (17.5%) stddev-sans-first:10.01 04:17:09 INFO - PROCESS | 1650 | Values: 83.0 59.0 62.0 88.0 61.0 62.0 60.0 62.0 78.0 62.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:72.50 stddev:19.74 (27.2%) stddev-sans-first:12.07 04:17:09 INFO - PROCESS | 1650 | Values: 131.0 103.0 95.0 72.0 71.0 73.0 83.0 70.0 70.0 83.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:112.50 stddev:21.99 (19.5%) stddev-sans-first:20.76 04:17:09 INFO - PROCESS | 1650 | Values: 88.0 116.0 136.0 87.0 97.0 138.0 136.0 97.0 135.0 135.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:112.30 Median:96.50 stddev:20.06 (20.8%) stddev-sans-first:21.08 04:17:09 INFO - PROCESS | 1650 | Values: 120.0 132.0 111.0 156.0 120.0 97.0 97.0 97.0 96.0 97.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:109.10 Median:90.50 stddev:26.17 (28.9%) stddev-sans-first:27.18 04:17:09 INFO - PROCESS | 1650 | Values: 94.0 148.0 102.0 145.0 147.0 92.0 90.0 91.0 93.0 89.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 04:17:09 INFO - PROCESS | 1650 | Values: 67.0 65.0 69.0 69.0 67.0 68.0 68.0 68.0 69.0 68.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:58.50 stddev:4.79 (8.2%) stddev-sans-first:4.76 04:17:09 INFO - PROCESS | 1650 | Values: 55.0 70.0 58.0 64.0 59.0 56.0 56.0 57.0 58.0 64.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:87.00 stddev:11.95 (13.7%) stddev-sans-first:12.35 04:17:09 INFO - PROCESS | 1650 | Values: 87.0 87.0 87.0 89.0 105.0 117.0 112.0 88.0 85.0 89.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:143.00 stddev:13.83 (9.7%) stddev-sans-first:14.25 04:17:09 INFO - PROCESS | 1650 | Values: 139.0 140.0 141.0 139.0 142.0 144.0 158.0 160.0 181.0 140.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.90 Median:178.00 stddev:28.54 (16.0%) stddev-sans-first:26.54 04:17:09 INFO - PROCESS | 1650 | Values: 231.0 175.0 172.0 225.0 242.0 181.0 172.0 172.0 174.0 175.0 04:17:09 INFO - PROCESS | 1650 | 04:17:09 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:81.50 stddev:10.14 (12.4%) stddev-sans-first:10.34 04:17:09 INFO - PROCESS | 1650 | Values: 69.0 65.0 69.0 78.0 85.0 65.0 73.0 85.0 90.0 91.0 04:17:09 INFO - PROCESS | 1650 | -------- Summary: end -------- 04:17:09 INFO - PROCESS | 1650 | 04:17:10 INFO - TEST-INFO | 1650: exit 0 04:17:10 INFO - TEST-OK | kraken | took 62298ms 04:17:10 INFO - SUITE-END | took 1179s 04:17:10 INFO - Completed test suite (00:19:40) 04:17:10 INFO - javascript benchmark 04:17:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7626.044117419602, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.464403017532, 3465.8440370183357, 3472.3923313420146, 3477.6000759912604, 3514.648535146484, 4569.771511424428, 5750.568707346944, 5904.970475147624, 5930.703464826763, 6001.759929602822, 5826.4173582641715, 5834.183214349994, 5846.444587764156, 5923.9407605923925, 6074.331823499411, 4986.927810520738, 4990.725510096943, 5241.711705856176, 5412.9458705413035, 5464.808731694391, 5348.91976620351, 5431.266778984826, 5483.917741233885, 5484.7531861066345, 5499.0, 5443.564514838803, 5470.753816078286, 5472.589555783312, 5497.862553436176, 5511.063119269734, 5299.920501192485, 5341.412444631087, 5444.0, 5461.5357694596005, 5463.699496527689, 5339.172428273585, 5365.490278423583, 5376.838694839161, 5394.973025134849, 5405.486478784509, 4695.624350052022, 5781.682007489587, 5961.076033214858, 6064.120702498157, 6132.0, 5137.5633071188995, 5197.896042079137, 6283.340249273806, 6290.528210384239, 6307.400796924285, 5514.6139770216105, 6416.582922110095, 6438.098666186737, 6473.676316184172, 6511.28375878653, 6127.846803829851, 6147.569670123138, 6176.413240742122, 6188.87622247557, 6217.129601855699, 5271.499207575274, 5454.236406903036, 6396.744130234739, 6475.643839588868, 6524.543281970216, 6121.571489995747, 6244.063390491372, 6312.747490100436, 6316.9368306317265, 6326.588771729823, 6311.96844015777, 6323.873522529524, 6370.777022804273, 6383.425491705769, 6387.425131738166, 6322.33615470369, 6358.682065896779, 6363.363663633692, 6375.968120159462, 6385.872282554323, 6226.844328891723, 6262.279837818618, 6303.936960630335, 6306.684665766693, 6369.681515924185, 5032.169692000852, 5137.845864624067, 5152.742362881841, 5194.636375453682, 5201.141811601119, 4617.976910115428, 4644.442666879996, 4657.301404789241, 4658.835291177205, 4680.742559159279, 5954.017587098167, 6057.7879774207695, 6192.845178870564, 6205.720742566596, 6208.627482351073, 6295.937040629535, 6308.337624549448, 6339.651319177397, 6387.010013447923, 6495.382938620918, 6128.9693551531955, 6136.20229370179, 6165.260168779775, 6172.814815555541, 6184.8763024739255, 5669.744861481243, 5678.602497825195, 5692.7722891083895, 5703.657780533182, 5707.0583353747, 6392.009238568029, 6462.806115816533, 6569.211694596679, 6599.241087275025, 6713.2279787824045, 6426.4537514311805, 6448.806535803933, 6449.3550644937, 6476.06097115925, 6530.706118224596, 6544.181977252843, 6626.138601981712, 6684.732610695518, 6704.966475167593, 6705.530612857248, 6231.844203894939, 6250.812475625738, 6256.061590761422, 6380.87238255223, 6408.551401401857, 6316.968415157895, 6354.586951848115, 6451.870962580629, 6453.709583068679, 6472.838179045561, 6114.206128133634, 6160.753569857335, 6247.406496382836, 6265.373462653881, 6278.152449419453, 5417.7832886685665, 6137.315698286874, 6248.312685604671, 6260.217472815898, 6261.0, 6137.110119032626, 6146.354632763674, 6154.046122850965, 6233.376662333731, 6242.719077641426, 6185.443310102112, 6219.346968568416, 6246.593971391845, 6259.405356491126, 6296.244450665949, 6244.500439964882, 6250.937490625036, 6251.812445626639, 6253.562250642593, 6267.091271765477], "value": 5860.820434754624, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.6223681514466, 798.8082860113573, 818.9181081891811, 935.4200395754625, 936.6066252174086, 37922.81038594807, 39763.80118099409, 39769.20461590766, 39819.0, 39871.60128398723, 38105.61894381062, 38114.8094259529, 38290.61709382905, 38346.61653383472, 38367.42448863269, 7678.0, 10927.508262128224, 11176.441177941111, 11302.491387887565, 11330.09359251262, 9335.953320233424, 11143.275687080355, 11156.442177891113, 11170.273932194423, 11186.944065279702, 11091.944540277327, 11126.38804865732, 11136.832947505794, 11192.664220073411, 11207.551697932073, 11110.833337499944, 11129.998300152985, 11166.330020198815, 11194.66416007517, 11199.216054876162, 10923.508442120044, 10931.508082136323, 10984.340939543654, 10988.835167472414, 10990.725231869268, 38300.425493617615, 38500.229995400216, 38521.614783852085, 38541.42187867184, 38649.64726234568, 3053.3286859532896, 3217.806931584089, 3777.4522694209186, 3821.197548514815, 3949.2891279569667, 23359.66098997297, 24408.0, 27491.587626185625, 27719.307017324525, 28414.0, 3165.09794708507, 3167.9683203167906, 3196.1530194498478, 3253.414385410625, 3267.738580913545, 258456.0, 336309.0, 346971.0, 347755.2612236923, 347954.0, 49794.0, 50054.9989000218, 50143.498565013884, 50223.0, 50265.24602130971, 53077.73461132747, 53412.0, 53486.930261394555, 53546.73226633842, 53560.732196338766, 14651.487197948021, 14730.26348682583, 14733.336999835034, 14871.884608654394, 14899.404023839143, 12045.939770301093, 12107.0, 12110.031197504355, 12115.515379384902, 12137.635870923887, 12158.331291779037, 13673.906087512974, 13751.0, 13786.931065344608, 13789.241591712553, 5916.585838991229, 5946.4053594641055, 5967.6419414835245, 5978.5814992950945, 5990.5507086968655, 1005.4369553050316, 1050.1598721023304, 1130.4856290387854, 1133.1274918312854, 1181.9172657914035, 19598.902005489883, 24792.0, 26126.477470450864, 26318.47363052728, 26430.339241519116, 1233.8766123387588, 1243.3348158735214, 1249.5501619417003, 1270.1553466944563, 1284.9229046257256, 11226.494807733672, 13287.734245315232, 13443.058985870895, 13455.394507247198, 13592.796108058386, 342953.0, 444930.0, 445493.0, 446952.0, 447253.0, 45565.08869822585, 55799.72100139473, 56266.15600765992, 56444.0, 56525.71737141288, 1402.144691738039, 1428.250168661461, 1432.9856701433062, 1443.0, 1443.9061461005, 27079.531877240035, 28217.012404565743, 29157.979470718423, 29233.707662923098, 29725.405491889607, 44544.33183502315, 48968.5103148964, 51374.486255138465, 51695.966080678925, 53055.734721326146, 470154.0, 472746.0, 476733.0, 477133.0, 477444.6127769339, 33270.334593308486, 33350.833245833615, 33387.332253355286, 33471.1632209187, 33571.664283356855, 42412.36381454219, 42477.78761106298, 42742.504012359415, 42942.78528607337, 43852.0, 35038.49615038476, 35096.64903350934, 35325.82337088298, 35895.0, 36584.85420599933, 31023.29371884568, 33493.83253083719, 33918.321633567684, 36094.09764755902, 36908.81545592255, 26311.868440657676, 35288.47067293942, 35405.760798371935, 35735.82132089323, 35913.640863592074, 29685.703142968294, 30982.84508577443, 31065.223369415944, 31088.0, 31126.53310200394, 23366.766332336458, 23518.312376141494, 23549.529009420057, 23578.29265122049, 23979.76020239845], "value": 19067.668379806484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.24604730757903, 390.21371935549865, 397.8508059477696, 399.4746907816223, 444.6020811373821, 20727.85996770179, 21188.0, 21239.256626018094, 21436.57126857462, 21765.455863603416, 19313.0343482826, 20756.792432075676, 21022.0, 21081.789182108176, 21234.45186577613, 6004.669743164113, 6757.391834734873, 6801.28586498418, 6811.25076241613, 6834.179898412198, 6492.837679058014, 6619.205695316568, 6660.933390666104, 6670.866582668368, 6723.394894459498, 6743.123393958778, 6755.189377274709, 6757.0, 6758.222804377486, 6775.830604234884, 6631.104800851872, 6650.600963942179, 6694.0962969999155, 6915.51591388598, 6927.653617319113, 6629.370209830058, 6640.203175618956, 6705.826480365917, 6728.192616885956, 6731.461483081342, 23345.182918597937, 24396.26811195649, 25959.35101622456, 26450.86774566134, 26917.865410673017, 2677.0, 2684.087410280514, 2700.230434326227, 2714.19931120318, 2725.1960671601923, 12155.756884862341, 15164.725291206298, 16282.674346513122, 16371.59071023222, 16723.57849582787, 1807.4487281379174, 2043.4993426610577, 2103.4110449074283, 2111.429913923232, 2123.097683484513, 130984.0, 136580.31709841389, 150534.7419788704, 155673.0, 159414.0, 22456.32631021072, 23320.55077589457, 23434.296971090553, 23807.642885356734, 23882.880585596962, 36793.136720643946, 36862.631373686454, 36880.631193688256, 36942.076448089014, 36961.26077478489, 10464.52909619069, 10643.787124257471, 10805.405702686428, 10929.344239345506, 10943.616973405893, 7580.431467639949, 8643.697470588624, 8646.913530864611, 8664.696735614223, 8818.823623527494, 10817.459127043616, 11080.5567777288, 11175.552977880794, 11251.0, 11281.21031527785, 3966.7024973126554, 4435.423394958635, 4506.639468842551, 4522.924411400214, 4570.65720070996, 1011.0950699124243, 1048.66442738323, 1059.210887888528, 1070.1533919773365, 1074.3392813419691, 22894.771052289263, 23165.652515212285, 23249.651255231183, 23331.0, 23350.0, 934.2806039349663, 1070.3898777696638, 1080.3949788118678, 1084.5986984815668, 1104.1608377632897, 11311.0, 11885.346305953255, 12009.63971080869, 12061.577844775566, 12112.576059837864, 664980.0, 692668.0, 694087.0, 715421.0, 727076.0, 102605.0, 121294.0, 144618.83071753933, 145411.0, 145553.0, 1142.6914733022204, 1158.8609366876028, 1200.5557943560939, 1207.1791181996327, 1230.1880758699215, 19806.90096549508, 19810.90094549518, 19893.10481028386, 20128.892910889455, 20169.59660806805, 48970.510294896594, 55641.72179139078, 62013.0, 62701.68649156725, 62797.0, 58276.4172358288, 86579.56710216409, 87587.56206218927, 88559.0, 89698.0, 11857.762844742885, 11901.880981190077, 12099.516019359127, 12172.452239649414, 12204.755904881675, 13440.0, 13515.797263041259, 13840.16958982424, 13885.028048036944, 13953.511627093003, 8899.421537600036, 10825.520419514009, 11137.944310278397, 11179.385133817394, 11548.538058477567, 10580.36517808934, 11449.771004579696, 11691.883081169079, 11725.296482211095, 11939.940300298791, 9729.951350243487, 10686.57253709843, 11661.591844285409, 11916.76166476683, 12159.270443773403, 11864.406779661154, 11901.403185205356, 12110.273383597012, 12140.514379424723, 12181.330026848254, 11663.941680291544, 11871.287722736663, 11987.981021613261, 12036.217645852992, 12040.157188997036], "value": 11717.090243420662, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.8285522882024, 1512.6747749233912, 1522.3682171898656, 1549.9845001549982, 1560.1263292556168, 9375.39059961102, 9602.135807777298, 10536.578536858526, 11166.330020198784, 11189.0, 10379.169666426667, 10697.0, 10705.46472676367, 10706.839397409025, 10727.785444291128, 1735.696253155699, 1871.9625607487842, 2241.6774103279063, 2278.2937289440283, 2338.9298321050396, 2255.458689914417, 2294.8393612447135, 2297.425643589103, 2303.665968434576, 2319.3853628788384, 2283.3378320287097, 2287.359539328982, 2287.542491501698, 2296.1159953417887, 2303.884805759714, 2217.922372716955, 2243.2933625907785, 2261.547690461914, 2262.592733308004, 2263.060829755658, 2242.7420846602686, 2298.9769552549124, 2300.1259521381767, 2303.366574192094, 2307.3654744945106, 9985.502174673784, 10308.22688298373, 10962.835557466644, 11019.338839669645, 11081.113510919107, 5540.833774986757, 5686.3744988051285, 5691.4593113654555, 5693.800716974927, 5699.458551437607, 2910.738033576967, 3277.836108194581, 3281.6226133994655, 3489.5114683944266, 3540.7344449166417, 1039.9324043937195, 1166.8891455311732, 1227.9754404911941, 1230.034422977958, 1243.60204734485, 14929.328180231916, 15159.166245856473, 15283.617909552238, 15309.693806123814, 15316.0, 4352.717073390219, 4531.909361812745, 4592.586667199968, 4832.081904438216, 4834.6374021948495, 10081.899181008095, 12561.685957850945, 12732.0, 12740.490380384681, 12747.808782868264, 5677.602567820213, 5715.9997000524745, 5764.0, 5773.076307790733, 5793.044147715664, 4918.5819205367225, 5045.167547354646, 5158.690478571298, 5174.7930082796265, 5191.2472691459525, 3213.389456003352, 3746.925061498809, 3879.476270703419, 3890.727649064595, 3955.723099383016, 1015.9441230732233, 1017.9389236645825, 1020.2093377632394, 1028.7428142964259, 1034.2863424237253, 772.9961350193213, 788.1409263902374, 806.664969475519, 811.7321283976273, 818.2880893622587, 6338.619682819046, 6356.904646430307, 6708.865822683519, 6763.830904227434, 6777.49168812341, 884.6726711116928, 899.4738078224312, 951.5194826612517, 983.4836710726926, 994.4083270454213, 4184.476940382452, 4487.0, 4491.887702807456, 4619.330197121399, 4630.398048253705, 13425.0, 13576.1854288743, 13669.931650341883, 13709.451621935012, 13738.969577281743, 10386.688399348031, 13076.084674072723, 13392.799108013192, 13611.931940340235, 13640.795388069188, 9916.107550320507, 9992.350497217658, 10122.937091605423, 10128.392296462236, 10154.0, 5768.365479797135, 6607.273199947906, 6699.564528305645, 6810.659467026728, 6838.487113466411, 10123.24075694311, 11949.581764638197, 12072.3360215189, 12142.0, 12159.270443773048, 11983.580574679845, 12215.389230538616, 12221.0, 12229.388530573613, 12239.510419583117, 7363.374113200438, 9320.906790932004, 9323.254139668674, 9327.486988215713, 9333.579988900652, 9433.716988490356, 9750.951245243728, 9950.900490994998, 9971.45157016371, 9980.550875210778, 8472.322214222972, 8712.78218044554, 8746.956265218632, 8802.867956980523, 8820.867686984571, 8544.658213671632, 8554.315654747481, 8605.096464871349, 8637.265832404317, 8678.218960293603, 7553.5845528494265, 7582.924170758442, 7687.0, 7690.423218258765, 7703.768886933401, 4691.906161876812, 5641.492265696107, 5764.538836893123, 5805.593608447368, 5851.473367396955, 5723.6851973141875, 5872.001759700775, 5896.498797602252, 5961.434205879963, 6000.669963152069], "value": 4981.136250207551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.93961996270616, 368.2193749251586, 375.90226541099304, 388.98638547650836, 390.2818813383375, 4868.68353557019, 5121.795128194877, 5163.638545301826, 5260.842174734759, 5285.577153827694, 4022.698297627675, 4812.7968007998, 5092.363454568179, 5190.169572868342, 5209.687418754877, 3781.3004594150034, 3831.5210598675167, 4127.215828992489, 4242.766647834368, 4300.1829652366105, 3515.4726790981304, 3782.6406491383414, 4135.917281654365, 4300.892477688051, 4306.267934451135, 3620.0406892173596, 3674.50394196784, 3960.8811735647832, 4244.896326954999, 4307.913841723163, 3679.2089700714473, 3837.846486140579, 4266.424032755584, 4340.43744252069, 4404.867853964371, 3465.8960231193105, 3886.4753258310334, 3893.27974324748, 3929.620526347339, 3978.8408463661417, 4430.016231739293, 4975.7512124394, 5004.724740139291, 5061.506855477643, 5066.442691303953, 4718.764061796931, 4786.664933454659, 4854.2961270615915, 4983.750812459362, 5003.474635163292, 4427.092446048552, 5036.571891389236, 5257.18627073659, 5476.644018138848, 5528.834134975958, 3753.530808648919, 3952.565217826037, 4368.7815609219415, 4417.89552611847, 4440.333949907507, 5136.460671629462, 5168.4314725380555, 5506.68719137963, 5815.912761308584, 5940.079287710412, 3883.9319187223173, 4416.734995900256, 4441.933370999438, 4496.685232033727, 4534.569215924482, 4310.52584215736, 4602.470715867651, 4610.40064791575, 4659.767011649404, 4754.809807607726, 4009.3184158693093, 4373.409589705413, 4416.558344165558, 4781.856544303677, 4957.702537847741, 5496.258005169331, 5496.285482887279, 5506.697131657717, 5518.144687573433, 5588.972055139699, 1541.8303986561468, 1556.5019193858084, 1560.5552417560941, 1562.4843901415609, 1564.68566404221, 947.4504787223394, 1047.4814966591587, 1168.6435637130587, 1178.3106882473849, 1182.6097387861985, 4835.903281934412, 4939.2838038484215, 5123.8719032023755, 5197.8440646780655, 5232.2674825524455, 5134.794608215704, 5211.947880521222, 5317.441668624816, 5538.280023596906, 5825.727966093227, 4870.902581948412, 4987.5760560352055, 5183.45573714762, 5274.868128296824, 5287.07476191665, 2621.3839747659285, 2642.2825163643784, 2734.1524127520534, 2753.9724602753718, 2759.482284743383, 5160.142348754445, 5245.957344740042, 5942.8811423771285, 6012.759489620454, 6034.607750496204, 4913.820683036117, 5122.436531981479, 5238.92141617876, 5351.625386222927, 5715.0, 5398.946010539923, 5562.47032066177, 5736.823951090017, 5924.289085309791, 6024.849378765478, 4738.573528382462, 4796.040791841576, 5335.413104558418, 5774.307083150049, 5795.855533452649, 5130.871728206825, 5454.563634909277, 5552.7778888843995, 5719.5138413235345, 5740.0, 5725.965238423642, 5835.795747148999, 5862.472377486047, 6051.062085376773, 6154.78458253959, 3946.6053394660303, 4292.785360731888, 4495.415595972503, 4576.908461830678, 4614.353990441274, 5049.8737531562, 5209.322788037531, 5672.659640421588, 5794.1308803679785, 5900.321463031634, 5371.946280537301, 5462.863428414322, 5495.258140151029, 5501.889962200814, 5640.0, 4627.154300105478, 4919.680220785637, 5343.118385466354, 5555.555555555465, 5569.805056822992, 4346.978265108654, 4632.837850675211, 4822.324874517641, 5433.429489903503, 5455.236266922713, 4650.256153942271, 5445.455454454523, 5456.699881506555, 5470.562355011669, 5544.3346798384455, 4858.6356023297685, 4995.551290125823, 5136.768845401891, 5272.945410917755, 5413.83758487246], "value": 4186.901364038826, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.2132789947359, 727.8944553039813, 763.8122719171689, 815.9347252219821, 822.5475988206487, 13063.346832658364, 14966.0, 15880.0, 16116.677666446663, 16146.677066458678, 15087.0, 15242.466513672023, 15255.466058687949, 15271.236438178075, 15279.0, 631.3307893632741, 903.5708038681606, 924.8381533231691, 956.2780101023717, 957.5690939077408, 667.7562689618303, 922.1055576091181, 952.347641865321, 952.5761036338832, 954.8949615542285, 879.3888247667874, 974.5187315280768, 989.5695372512944, 1004.9547770350351, 1005.36661903001, 844.252836239926, 996.3772642098688, 997.0278977996431, 997.1175509673918, 998.59057786308, 670.8893032649607, 683.3030309084755, 739.693027393631, 739.9973036535471, 741.462439731196, 15436.3825446982, 15810.841891581053, 15863.206839658087, 16576.83423165765, 16880.746788798177, 12161.635150945485, 14339.354729037219, 14405.0, 14411.495597654031, 14863.331150098164, 11679.474423650956, 11690.941545292304, 11751.118666100077, 11934.641960741192, 12157.69605759863, 3650.3976843820737, 3657.396529572644, 3742.9064273393383, 3758.1168425420005, 3845.6346647068485, 93716.0, 94731.0, 95168.0, 95486.52256738741, 96397.0, 9361.157495825408, 9853.802923941481, 10973.83539246912, 11046.944765276123, 11130.554777808959, 23540.940657670446, 23730.881345593163, 23768.0, 23790.929408176675, 23815.285541433437, 7011.404030657452, 7261.019762332123, 7312.670929808171, 7758.14660387357, 7968.800779980431, 4275.508316543575, 4290.377895205178, 4387.802548885308, 4431.645468362523, 4450.154470650566, 3389.7288216942584, 3428.6228514863346, 3446.655334466533, 3450.654934506529, 3478.7912725236565, 625.1185827982523, 636.045931103345, 637.8118455055769, 641.6984017511855, 643.6781609195402, 595.5265563876642, 616.7317217010615, 664.8040264732646, 668.9431398331099, 678.9916973294653, 9367.391119577205, 9588.328816982883, 9676.694095957648, 9702.417854928726, 9772.560234789451, 620.602814198913, 664.3301255395868, 666.1573110015834, 672.5677783407369, 689.1007235557645, 4452.844150454718, 4505.805961420227, 4572.862814115582, 4780.211265141283, 4788.1381351356395, 97845.51077244568, 97933.0, 99493.0, 100724.0, 102335.48832255791, 73069.6346518264, 74412.5117497647, 87266.0, 87604.5619771897, 87999.0, 63401.0, 67047.32381690497, 71532.64233678902, 72157.04309487266, 72572.0, 7736.497127686683, 8710.69512567057, 9035.954820225857, 9105.27157827359, 9246.260299176185, 32118.715251389684, 35850.92447226588, 48264.27603585879, 49626.503734962185, 49835.0, 62322.68838655778, 63557.0, 64173.35826641674, 64181.679091604245, 64339.3915152125, 11228.606998755331, 11358.829617555579, 11448.198626096093, 11756.23584467007, 11852.780389289224, 11825.82261266099, 11922.880771192176, 11960.64118076459, 12032.0, 12545.184563003375, 11551.079651648255, 11551.364674942623, 11655.708607284885, 11745.647630571097, 11756.882431175578, 9667.854982175133, 11268.774624507629, 11628.94185529067, 11902.821457677968, 12054.216475929037, 11268.042216411697, 11487.655370338904, 11525.423728813357, 11868.940655296668, 12016.519339226683, 9444.338896277195, 9600.478528524953, 9656.468894210886, 9739.123478886933, 9865.852012219966, 7222.0, 7525.774226773206, 9671.226301895691, 9679.612815487302, 9708.854367184356], "value": 7202.039417941386, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 118.0, 118.0, 119.0, 118.0, 119.0, 119.0, 123.0, 120.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 144.0, 114.0, 154.0, 112.0, 113.0, 114.0, 114.0, 146.0, 147.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 200.0, 221.0, 203.0, 275.0, 285.0, 225.0, 198.0, 198.0], "value": 220.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 62.0, 88.0, 61.0, 62.0, 60.0, 62.0, 78.0, 62.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 103.0, 95.0, 72.0, 71.0, 73.0, 83.0, 70.0, 70.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 116.0, 136.0, 87.0, 97.0, 138.0, 136.0, 97.0, 135.0, 135.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 132.0, 111.0, 156.0, 120.0, 97.0, 97.0, 97.0, 96.0, 97.0], "value": 112.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 148.0, 102.0, 145.0, 147.0, 92.0, 90.0, 91.0, 93.0, 89.0], "value": 109.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 69.0, 69.0, 67.0, 68.0, 68.0, 68.0, 69.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 70.0, 58.0, 64.0, 59.0, 56.0, 56.0, 57.0, 58.0, 64.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 89.0, 105.0, 117.0, 112.0, 88.0, 85.0, 89.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 141.0, 139.0, 142.0, 144.0, 158.0, 160.0, 181.0, 140.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [231.0, 175.0, 172.0, 225.0, 242.0, 181.0, 172.0, 172.0, 174.0, 175.0], "value": 191.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 69.0, 78.0, 85.0, 65.0, 73.0, 85.0, 90.0, 91.0], "value": 77.0, "unit": "score"}]}]} 04:17:10 INFO - Return code: 0 04:17:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:17:10 ERROR - # TBPL SUCCESS # 04:17:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:17:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:17:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:17:10 INFO - 2017-03-09 04:17:10.662 screenresolution[1655:10617] starting screenresolution argv=screenresolution get 04:17:10 INFO - 2017-03-09 04:17:10.664 screenresolution[1655:10617] Display 0: 1600x1200x32@60 04:17:10 INFO - 2017-03-09 04:17:10.670 screenresolution[1656:10620] starting screenresolution argv=screenresolution list 04:17:10 INFO - Available Modes on Display 0 04:17:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:17:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:17:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:17:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:17:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:17:10 INFO - Intel Iris: 04:17:10 INFO - Chipset Model: Intel Iris 04:17:10 INFO - Type: GPU 04:17:10 INFO - Bus: Built-In 04:17:10 INFO - VRAM (Dynamic, Max): 1536 MB 04:17:10 INFO - Vendor: Intel (0x8086) 04:17:10 INFO - Device ID: 0x0a2e 04:17:10 INFO - Revision ID: 0x0009 04:17:10 INFO - Displays: 04:17:10 INFO - Display: 04:17:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:17:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:17:10 INFO - Display Serial Number: 1600x1200 60 04:17:10 INFO - Main Display: Yes 04:17:10 INFO - Mirror: Off 04:17:10 INFO - Online: Yes 04:17:10 INFO - Rotation: Supported 04:17:10 INFO - Return code: 0 04:17:10 INFO - Running post-action listener: _resource_record_post_action 04:17:10 INFO - [mozharness: 2017-03-09 12:17:10.904783Z] Finished run-tests step (success) 04:17:10 INFO - Running post-run listener: _resource_record_post_run 04:17:10 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 37833216; Write bytes: 516315648; Read time: 252; Write time: 1636 04:17:10 INFO - TinderboxPrint: CPU usage
54.6% 04:17:10 INFO - TinderboxPrint: I/O read bytes / time
37,833,216 / 252 04:17:10 INFO - TinderboxPrint: I/O write bytes / time
516,315,648 / 1,636 04:17:10 INFO - TinderboxPrint: CPU idle
2,182.4 (45.4%) 04:17:10 INFO - TinderboxPrint: CPU system
964.1 (20.1%) 04:17:10 INFO - TinderboxPrint: CPU user
1,659.2 (34.5%) 04:17:10 INFO - TinderboxPrint: Swap in / out
247,840,768 / 0 04:17:10 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205421056; Write bytes: 203458048; Read time: 15792; Write time: 435 04:17:10 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35904000; Write bytes: 302089216; Read time: 227; Write time: 1165 04:17:11 INFO - Running post-run listener: _upload_blobber_files 04:17:11 INFO - Blob upload gear active. 04:17:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:17:11 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 04:17:11 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'] 04:17:11 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 04:17:11 INFO - (blobuploader) - INFO - Open directory for files ... 04:17:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:17:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:13 INFO - (blobuploader) - INFO - Done attempting. 04:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:17:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:14 INFO - (blobuploader) - INFO - Done attempting. 04:17:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:17:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:17:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:15 INFO - (blobuploader) - INFO - Done attempting. 04:17:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:17:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:17:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:17:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:17:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:17:18 INFO - (blobuploader) - INFO - Done attempting. 04:17:18 INFO - (blobuploader) - INFO - Iteration through files over. 04:17:18 INFO - Return code: 0 04:17:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:17:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:17:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7fa98fe7676d34eee15d5fe50b8e9c176a8df0b2f7cbd401562176cfdaf7538685a80e546ff1382dd8ffe545a447ef04543fe2c4677287597ecdbdf05b93d6c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6928e03304e6b38d7a587fcf3219cacc39671b71a289dde3b2cd6bccfefe4ec9895da6bac3fa0a3c90ba313443241919e90a6b679ac970e3fe2dda5c305cc648", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbae9d2e5fabdf415fd701e3d57f6964d0740841591ff46bc7db9a279e3aa9ad3259497d5774b98891f1a379edea095364d411def23ca3677e0a16da140ae41c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71302f016a72146d596b564b92bd1ddf58383f67456f4c9705a50f54a153d6cfc329db176d9e9fa35b5dc4218deae88af114b47d83d8f72f560aa0c2800869f7"} 04:17:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:17:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:17:18 INFO - Contents: 04:17:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7fa98fe7676d34eee15d5fe50b8e9c176a8df0b2f7cbd401562176cfdaf7538685a80e546ff1382dd8ffe545a447ef04543fe2c4677287597ecdbdf05b93d6c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6928e03304e6b38d7a587fcf3219cacc39671b71a289dde3b2cd6bccfefe4ec9895da6bac3fa0a3c90ba313443241919e90a6b679ac970e3fe2dda5c305cc648", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbae9d2e5fabdf415fd701e3d57f6964d0740841591ff46bc7db9a279e3aa9ad3259497d5774b98891f1a379edea095364d411def23ca3677e0a16da140ae41c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71302f016a72146d596b564b92bd1ddf58383f67456f4c9705a50f54a153d6cfc329db176d9e9fa35b5dc4218deae88af114b47d83d8f72f560aa0c2800869f7"} 04:17:18 INFO - Running post-run listener: copy_logs_to_upload_dir 04:17:18 INFO - Copying logs to upload dir... 04:17:18 INFO - mkdir: /builds/slave/test/build/upload/logs 04:17:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.748840 ========= master_lag: 1.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-09 04:17:19.519669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 04:17:19.524102) ========= 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.PjflpyOA79/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.ImDQUqf3HQ/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7fa98fe7676d34eee15d5fe50b8e9c176a8df0b2f7cbd401562176cfdaf7538685a80e546ff1382dd8ffe545a447ef04543fe2c4677287597ecdbdf05b93d6c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6928e03304e6b38d7a587fcf3219cacc39671b71a289dde3b2cd6bccfefe4ec9895da6bac3fa0a3c90ba313443241919e90a6b679ac970e3fe2dda5c305cc648", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbae9d2e5fabdf415fd701e3d57f6964d0740841591ff46bc7db9a279e3aa9ad3259497d5774b98891f1a379edea095364d411def23ca3677e0a16da140ae41c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71302f016a72146d596b564b92bd1ddf58383f67456f4c9705a50f54a153d6cfc329db176d9e9fa35b5dc4218deae88af114b47d83d8f72f560aa0c2800869f7"} build_url:https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/AqtPCVfzTnK_eKQwzPLQrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7fa98fe7676d34eee15d5fe50b8e9c176a8df0b2f7cbd401562176cfdaf7538685a80e546ff1382dd8ffe545a447ef04543fe2c4677287597ecdbdf05b93d6c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6928e03304e6b38d7a587fcf3219cacc39671b71a289dde3b2cd6bccfefe4ec9895da6bac3fa0a3c90ba313443241919e90a6b679ac970e3fe2dda5c305cc648", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbae9d2e5fabdf415fd701e3d57f6964d0740841591ff46bc7db9a279e3aa9ad3259497d5774b98891f1a379edea095364d411def23ca3677e0a16da140ae41c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71302f016a72146d596b564b92bd1ddf58383f67456f4c9705a50f54a153d6cfc329db176d9e9fa35b5dc4218deae88af114b47d83d8f72f560aa0c2800869f7"}' ========= master_lag: 0.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 04:17:19.712856) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 04:17:19.713185) ========= 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.PjflpyOA79/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.ImDQUqf3HQ/Listeners TMPDIR=/var/folders/v0/xt40qp0j7dbbsm7xhv27_ww000000w/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.005142 ========= master_lag: 0.46 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 04:17:20.181315) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 04:17:20.181616) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 04:17:20.183970) ========= ========= Total master_lag: 1.80 =========