builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0009 starttime: 1484858856.92 results: success (0) buildid: 20170119071129 builduid: c70f3fdf1f924acab3275675a44e0caf revision: a3978751f45108ff1ae002ecebdc0fa23fc52b84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:36.925015) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:36.925934) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:36.926614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:37.000505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:37.000944) ========= 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.exiWpEuYPH/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.b62SuuuxuF/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-19 12:47:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-01-19 12:47:37 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158169 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:37.187851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:37.188170) ========= 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.exiWpEuYPH/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.b62SuuuxuF/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.026936 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:37.238679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-19 12:47:37.239042) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3978751f45108ff1ae002ecebdc0fa23fc52b84 --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 a3978751f45108ff1ae002ecebdc0fa23fc52b84 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.exiWpEuYPH/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.b62SuuuxuF/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-19 12:47:37,345 truncating revision to first 12 chars 2017-01-19 12:47:37,345 Setting DEBUG logging. 2017-01-19 12:47:37,345 attempt 1/10 2017-01-19 12:47:37,345 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3978751f451?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-19 12:47:38,419 unpacking tar archive at: mozilla-central-a3978751f451/testing/mozharness/ program finished with exit code 0 elapsedTime=1.345058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-19 12:47:38.608644) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:38.609278) ========= script_repo_revision: a3978751f45108ff1ae002ecebdc0fa23fc52b84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:38.609725) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:38.610084) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 12:47:38.632803) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-19 12:47:38.633180) ========= /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.exiWpEuYPH/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.b62SuuuxuF/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:47:38 INFO - MultiFileLogger online at 20170119 12:47:38 in /builds/slave/test 12:47:38 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 12:47:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:47:38 INFO - {'append_to_log': False, 12:47:38 INFO - 'base_work_dir': '/builds/slave/test', 12:47:38 INFO - 'blob_upload_branch': 'Firefox', 12:47:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:47:38 INFO - 'branch': 'Firefox', 12:47:38 INFO - 'buildbot_json_path': 'buildprops.json', 12:47:38 INFO - 'config_files': ('talos/mac_config.py',), 12:47:38 INFO - 'default_actions': ('clobber', 12:47:38 INFO - 'read-buildbot-config', 12:47:38 INFO - 'download-and-extract', 12:47:38 INFO - 'populate-webroot', 12:47:38 INFO - 'create-virtualenv', 12:47:38 INFO - 'install', 12:47:38 INFO - 'run-tests'), 12:47:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:47:38 INFO - 'download_minidump_stackwalk': True, 12:47:38 INFO - 'download_symbols': 'ondemand', 12:47:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:47:38 INFO - 'tooltool.py': '/tools/tooltool.py', 12:47:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:47:38 INFO - '/tools/misc-python/virtualenv.py')}, 12:47:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:47:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:47:38 INFO - 'installer_path': 'installer.exe', 12:47:38 INFO - 'log_level': 'info', 12:47:38 INFO - 'log_name': 'talos', 12:47:38 INFO - 'log_to_console': True, 12:47:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:47:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:47:38 INFO - 'opt_config_files': (), 12:47:38 INFO - 'pip_index': False, 12:47:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:38 INFO - 'cmd': ('bash', 12:47:38 INFO - '-c', 12:47:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:38 INFO - 'enabled': True, 12:47:38 INFO - 'halt_on_failure': False, 12:47:38 INFO - 'name': 'check_screen_resolution'},), 12:47:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:38 INFO - 'cmd': ('bash', 12:47:38 INFO - '-c', 12:47:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:38 INFO - 'enabled': True, 12:47:38 INFO - 'halt_on_failure': False, 12:47:38 INFO - 'name': 'check_screen_resolution'},), 12:47:38 INFO - 'run_cmd_checks_enabled': True, 12:47:38 INFO - 'sps_profile': False, 12:47:38 INFO - 'sps_profile_interval': 0, 12:47:38 INFO - 'suite': 'dromaeojs-e10s', 12:47:38 INFO - 'system_bits': '32', 12:47:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:47:38 INFO - 'title': 't-yosemite-r7-0009', 12:47:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:47:38 INFO - 'use_talos_json': True, 12:47:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:47:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:47:38 INFO - 'work_dir': 'build'} 12:47:38 INFO - [mozharness: 2017-01-19 20:47:38.852557Z] Running clobber step. 12:47:38 INFO - Running pre-action listener: _resource_record_pre_action 12:47:38 INFO - Running main action method: clobber 12:47:38 INFO - rmtree: /builds/slave/test/build 12:47:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:47:41 INFO - Running post-action listener: _resource_record_post_action 12:47:41 INFO - [mozharness: 2017-01-19 20:47:41.443387Z] Finished clobber step (success) 12:47:41 INFO - [mozharness: 2017-01-19 20:47:41.443533Z] Running read-buildbot-config step. 12:47:41 INFO - Running pre-action listener: _resource_record_pre_action 12:47:41 INFO - Running main action method: read_buildbot_config 12:47:41 INFO - Using buildbot properties: 12:47:41 INFO - { 12:47:41 INFO - "project": "", 12:47:41 INFO - "product": "firefox", 12:47:41 INFO - "script_repo_revision": "production", 12:47:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:47:41 INFO - "repository": "", 12:47:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:47:41 INFO - "buildid": "20170119071129", 12:47:41 INFO - "pgo_build": "False", 12:47:41 INFO - "basedir": "/builds/slave/test", 12:47:41 INFO - "buildnumber": 61, 12:47:41 INFO - "slavename": "t-yosemite-r7-0009", 12:47:41 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:47:41 INFO - "platform": "yosemite_r7", 12:47:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:47:41 INFO - "branch": "mozilla-central", 12:47:41 INFO - "repo_path": "mozilla-central", 12:47:41 INFO - "revision": "a3978751f45108ff1ae002ecebdc0fa23fc52b84", 12:47:41 INFO - "stage_platform": "macosx64", 12:47:41 INFO - "builduid": "c70f3fdf1f924acab3275675a44e0caf", 12:47:41 INFO - "slavebuilddir": "test" 12:47:41 INFO - } 12:47:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:47:41 INFO - Running post-action listener: _resource_record_post_action 12:47:41 INFO - [mozharness: 2017-01-19 20:47:41.446928Z] Finished read-buildbot-config step (success) 12:47:41 INFO - [mozharness: 2017-01-19 20:47:41.447049Z] Running download-and-extract step. 12:47:41 INFO - Running pre-action listener: _resource_record_pre_action 12:47:41 INFO - Running main action method: download_and_extract 12:47:41 INFO - mkdir: /builds/slave/test/build/tests 12:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:47:41 INFO - trying https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:47:41 INFO - Downloading https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:47:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 12:47:42 INFO - Downloaded 1199 bytes. 12:47:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:47:42 INFO - Using the following test package requirements: 12:47:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:47:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:47:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:47:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'jsshell-mac.zip'], 12:47:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:47:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:47:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:47:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:47:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:47:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:47:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:47:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:47:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:47:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:47:42 INFO - Fetch https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:47:45 INFO - Expected file size: 16340629 12:47:45 INFO - Obtained file size: 16340629 12:47:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:47:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:47:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:47:51 INFO - Fetch https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:47:54 INFO - Expected file size: 16340629 12:47:54 INFO - Obtained file size: 16340629 12:48:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:48:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:48:00 INFO - Fetch https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:48:02 INFO - Expected file size: 14403463 12:48:02 INFO - Obtained file size: 14403463 12:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:02 INFO - trying https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:48:02 INFO - Downloading https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:48:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 12:48:06 INFO - Downloaded 69915509 bytes. 12:48:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:48:06 INFO - mkdir: /builds/slave/test/properties 12:48:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:48:06 INFO - Writing to file /builds/slave/test/properties/build_url 12:48:06 INFO - Contents: 12:48:06 INFO - build_url:https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:48:08 INFO - Running post-action listener: _resource_record_post_action 12:48:08 INFO - Running post-action listener: set_extra_try_arguments 12:48:08 INFO - [mozharness: 2017-01-19 20:48:08.067841Z] Finished download-and-extract step (success) 12:48:08 INFO - [mozharness: 2017-01-19 20:48:08.067997Z] Running populate-webroot step. 12:48:08 INFO - Running pre-action listener: _resource_record_pre_action 12:48:08 INFO - Running main action method: populate_webroot 12:48:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:48:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:48:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:48:08 INFO - '--tppagecycles', 12:48:08 INFO - '7'], 12:48:08 INFO - 'tests': ['tsvgm']}}, 12:48:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:48:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:48:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:48:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:48:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:48:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:48:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['damp', 'tps']}, 12:48:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'tests': ['damp', 'tps']}, 12:48:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['dromaeo_dom']}, 12:48:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:48:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['basic_compositor_video']}, 12:48:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:48:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['a11yr', 12:48:08 INFO - 'ts_paint', 12:48:08 INFO - 'tpaint', 12:48:08 INFO - 'sessionrestore', 12:48:08 INFO - 'sessionrestore_no_auto_restore', 12:48:08 INFO - 'tabpaint']}, 12:48:08 INFO - 'other-e10s': {'tests': ['a11yr', 12:48:08 INFO - 'ts_paint', 12:48:08 INFO - 'tpaint', 12:48:08 INFO - 'sessionrestore', 12:48:08 INFO - 'sessionrestore_no_auto_restore', 12:48:08 INFO - 'tabpaint']}, 12:48:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:48:08 INFO - 'ts_paint', 12:48:08 INFO - 'tpaint', 12:48:08 INFO - 'sessionrestore', 12:48:08 INFO - 'sessionrestore_no_auto_restore', 12:48:08 INFO - 'tabpaint']}, 12:48:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:48:08 INFO - 'ts_paint', 12:48:08 INFO - 'tpaint', 12:48:08 INFO - 'sessionrestore', 12:48:08 INFO - 'sessionrestore_no_auto_restore', 12:48:08 INFO - 'tabpaint']}, 12:48:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['a11yr', 12:48:08 INFO - 'ts_paint', 12:48:08 INFO - 'tpaint', 12:48:08 INFO - 'sessionrestore', 12:48:08 INFO - 'sessionrestore_no_auto_restore', 12:48:08 INFO - 'tabpaint']}, 12:48:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['a11yr', 12:48:08 INFO - 'ts_paint', 12:48:08 INFO - 'tpaint', 12:48:08 INFO - 'sessionrestore', 12:48:08 INFO - 'sessionrestore_no_auto_restore', 12:48:08 INFO - 'tabpaint']}, 12:48:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['tsvgx', 12:48:08 INFO - 'tsvgr_opacity', 12:48:08 INFO - 'tart', 12:48:08 INFO - 'tscrollx', 12:48:08 INFO - 'cart', 12:48:08 INFO - 'tsvg_static']}, 12:48:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:48:08 INFO - 'tsvgr_opacity', 12:48:08 INFO - 'tart', 12:48:08 INFO - 'tscrollx', 12:48:08 INFO - 'cart', 12:48:08 INFO - 'tsvg_static']}, 12:48:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'talos_options': ['--disable-e10s'], 12:48:08 INFO - 'tests': ['tp5o']}, 12:48:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:48:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'talos_options': ['--disable-e10s', 12:48:08 INFO - '--xperf_path', 12:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:48:08 INFO - 'tests': ['tp5n']}, 12:48:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:48:08 INFO - 'talos_options': ['--xperf_path', 12:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:48:08 INFO - 'tests': ['tp5n']}}, 12:48:08 INFO - 'talos.zip': {'path': '', 12:48:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:48:08 INFO - Running post-action listener: _resource_record_post_action 12:48:08 INFO - [mozharness: 2017-01-19 20:48:08.085968Z] Finished populate-webroot step (success) 12:48:08 INFO - [mozharness: 2017-01-19 20:48:08.086081Z] Running create-virtualenv step. 12:48:08 INFO - Running pre-action listener: _resource_record_pre_action 12:48:08 INFO - Running main action method: create_virtualenv 12:48:08 INFO - Creating virtualenv /builds/slave/test/build/venv 12:48:08 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 12:48:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:48:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:48:08 INFO - Using real prefix '/tools/python27' 12:48:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:48:08 INFO - Installing distribute.............................................................................................................................................................................................done. 12:48:11 INFO - Installing pip.................done. 12:48:11 INFO - Return code: 0 12:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:48:12 INFO - Reading from file tmpfile_stdout 12:48:12 INFO - Output received: 12:48:12 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) 12:48:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:48:12 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')]} 12:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:12 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')]} 12:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:12 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:12 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 12:48:12 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 12:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:12 INFO - 'HOME': '/Users/cltbld', 12:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:12 INFO - 'LOGNAME': 'cltbld', 12:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:12 INFO - 'MOZ_NO_REMOTE': '1', 12:48:12 INFO - 'NO_EM_RESTART': '1', 12:48:12 INFO - 'PAGER': '/bin/cat', 12:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:12 INFO - 'PWD': '/builds/slave/test', 12:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:12 INFO - 'SHELL': '/bin/bash', 12:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:12 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:12 INFO - 'USER': 'cltbld', 12:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:12 INFO - 'XPC_FLAGS': '0x0', 12:48:12 INFO - 'XPC_SERVICE_NAME': '0', 12:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:13 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 12:48:13 INFO - Cleaning up... 12:48:13 INFO - Return code: 0 12:48:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:48:13 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')]} 12:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:13 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')]} 12:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:13 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:13 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 12:48:13 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 12:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:13 INFO - 'HOME': '/Users/cltbld', 12:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:13 INFO - 'LOGNAME': 'cltbld', 12:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:13 INFO - 'MOZ_NO_REMOTE': '1', 12:48:13 INFO - 'NO_EM_RESTART': '1', 12:48:13 INFO - 'PAGER': '/bin/cat', 12:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:13 INFO - 'PWD': '/builds/slave/test', 12:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:13 INFO - 'SHELL': '/bin/bash', 12:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:13 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:13 INFO - 'USER': 'cltbld', 12:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:13 INFO - 'XPC_FLAGS': '0x0', 12:48:13 INFO - 'XPC_SERVICE_NAME': '0', 12:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:13 INFO - Downloading/unpacking psutil>=3.1.1 12:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:48:17 INFO - Installing collected packages: psutil 12:48:17 INFO - Running setup.py install for psutil 12:48:17 INFO - building 'psutil._psutil_osx' extension 12:48:17 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 12:48:18 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 12:48:18 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 12:48:18 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 12:48:18 INFO - building 'psutil._psutil_posix' extension 12:48:18 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 12:48:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:48:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:48:18 INFO - ^ 12:48:18 INFO - 1 warning generated. 12:48:18 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 12:48:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:48:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:48:18 INFO - Successfully installed psutil 12:48:18 INFO - Cleaning up... 12:48:18 INFO - Return code: 0 12:48:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:18 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:18 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 12:48:18 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 12:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:18 INFO - 'HOME': '/Users/cltbld', 12:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:18 INFO - 'LOGNAME': 'cltbld', 12:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:18 INFO - 'MOZ_NO_REMOTE': '1', 12:48:18 INFO - 'NO_EM_RESTART': '1', 12:48:18 INFO - 'PAGER': '/bin/cat', 12:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:18 INFO - 'PWD': '/builds/slave/test', 12:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:18 INFO - 'SHELL': '/bin/bash', 12:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:18 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:18 INFO - 'USER': 'cltbld', 12:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:18 INFO - 'XPC_FLAGS': '0x0', 12:48:18 INFO - 'XPC_SERVICE_NAME': '0', 12:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:48:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:48:21 INFO - Installing collected packages: mozsystemmonitor 12:48:21 INFO - Running setup.py install for mozsystemmonitor 12:48:21 INFO - Successfully installed mozsystemmonitor 12:48:21 INFO - Cleaning up... 12:48:21 INFO - Return code: 0 12:48:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:21 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:21 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 12:48:21 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 12:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:21 INFO - 'HOME': '/Users/cltbld', 12:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:21 INFO - 'LOGNAME': 'cltbld', 12:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:21 INFO - 'MOZ_NO_REMOTE': '1', 12:48:21 INFO - 'NO_EM_RESTART': '1', 12:48:21 INFO - 'PAGER': '/bin/cat', 12:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:21 INFO - 'PWD': '/builds/slave/test', 12:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:21 INFO - 'SHELL': '/bin/bash', 12:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:21 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:21 INFO - 'USER': 'cltbld', 12:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:21 INFO - 'XPC_FLAGS': '0x0', 12:48:21 INFO - 'XPC_SERVICE_NAME': '0', 12:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:22 INFO - Downloading/unpacking jsonschema==2.5.1 12:48:22 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 12:48:22 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 12:48:22 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 12:48:22 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 12:48:22 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 12:48:22 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 12:48:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:48:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:48:24 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 12:48:24 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 12:48:24 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 12:48:24 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 12:48:24 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 12:48:24 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 12:48:25 INFO - Downloading functools32-3.2.3-2.tar.gz 12:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:48:25 INFO - warning: no files found matching '*.txt' 12:48:25 INFO - no previously-included directories found matching 'build' 12:48:25 INFO - no previously-included directories found matching 'dist' 12:48:25 INFO - no previously-included directories found matching '.git*' 12:48:25 INFO - Installing collected packages: jsonschema, functools32 12:48:25 INFO - Running setup.py install for functools32 12:48:25 INFO - warning: no files found matching '*.txt' 12:48:25 INFO - no previously-included directories found matching 'build' 12:48:25 INFO - no previously-included directories found matching 'dist' 12:48:25 INFO - no previously-included directories found matching '.git*' 12:48:25 INFO - Successfully installed jsonschema functools32 12:48:25 INFO - Cleaning up... 12:48:25 INFO - Return code: 0 12:48:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:25 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:25 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 12:48:25 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 12:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:25 INFO - 'HOME': '/Users/cltbld', 12:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:25 INFO - 'LOGNAME': 'cltbld', 12:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:25 INFO - 'MOZ_NO_REMOTE': '1', 12:48:25 INFO - 'NO_EM_RESTART': '1', 12:48:25 INFO - 'PAGER': '/bin/cat', 12:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:25 INFO - 'PWD': '/builds/slave/test', 12:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:25 INFO - 'SHELL': '/bin/bash', 12:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:25 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:25 INFO - 'USER': 'cltbld', 12:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:25 INFO - 'XPC_FLAGS': '0x0', 12:48:25 INFO - 'XPC_SERVICE_NAME': '0', 12:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:48:25 INFO - Cleaning up... 12:48:25 INFO - Return code: 0 12:48:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:25 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:25 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 12:48:25 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 12:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:25 INFO - 'HOME': '/Users/cltbld', 12:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:25 INFO - 'LOGNAME': 'cltbld', 12:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:25 INFO - 'MOZ_NO_REMOTE': '1', 12:48:25 INFO - 'NO_EM_RESTART': '1', 12:48:25 INFO - 'PAGER': '/bin/cat', 12:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:25 INFO - 'PWD': '/builds/slave/test', 12:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:25 INFO - 'SHELL': '/bin/bash', 12:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:25 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:25 INFO - 'USER': 'cltbld', 12:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:25 INFO - 'XPC_FLAGS': '0x0', 12:48:25 INFO - 'XPC_SERVICE_NAME': '0', 12:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:26 INFO - Downloading/unpacking blobuploader==1.2.4 12:48:26 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 12:48:26 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 12:48:26 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 12:48:26 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 12:48:26 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 12:48:26 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 12:48:28 INFO - Downloading blobuploader-1.2.4.tar.gz 12:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:48:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:48:28 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 12:48:28 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 12:48:28 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 12:48:28 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 12:48:28 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 12:48:28 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 12:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:48:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:48:29 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 12:48:29 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 12:48:29 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 12:48:29 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 12:48:29 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 12:48:29 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 12:48:29 INFO - Downloading docopt-0.6.1.tar.gz 12:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:48:30 INFO - Installing collected packages: blobuploader, requests, docopt 12:48:30 INFO - Running setup.py install for blobuploader 12:48:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:48:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:48:30 INFO - Running setup.py install for requests 12:48:30 INFO - Running setup.py install for docopt 12:48:30 INFO - Successfully installed blobuploader requests docopt 12:48:30 INFO - Cleaning up... 12:48:30 INFO - Return code: 0 12:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48: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')]} 12:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48: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')]} 12:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:30 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:30 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 12:48:30 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 12:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:30 INFO - 'HOME': '/Users/cltbld', 12:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:30 INFO - 'LOGNAME': 'cltbld', 12:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:30 INFO - 'MOZ_NO_REMOTE': '1', 12:48:30 INFO - 'NO_EM_RESTART': '1', 12:48:30 INFO - 'PAGER': '/bin/cat', 12:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:30 INFO - 'PWD': '/builds/slave/test', 12:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:30 INFO - 'SHELL': '/bin/bash', 12:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:30 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:30 INFO - 'USER': 'cltbld', 12:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:30 INFO - 'XPC_FLAGS': '0x0', 12:48:30 INFO - 'XPC_SERVICE_NAME': '0', 12:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-sPZRbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Np8phA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-X8dJ4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-7ByBpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-UEf97q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-shqvyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-9hAve1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-euyz7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:48:31 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-_dkeOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-8yWoQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-fdaSBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-jh7ihY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-CCZLB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-rZ7zMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-5y1s2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-tgWGII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:48:32 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-shqrWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:48:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:48:32 INFO - Running setup.py install for manifestparser 12:48:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:48:33 INFO - Running setup.py install for mozcrash 12:48:33 INFO - Running setup.py install for mozdebug 12:48:33 INFO - Running setup.py install for mozdevice 12:48:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:48:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:48:33 INFO - Running setup.py install for mozfile 12:48:33 INFO - Running setup.py install for mozhttpd 12:48:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:48:33 INFO - Running setup.py install for mozinfo 12:48:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:48:33 INFO - Running setup.py install for mozInstall 12:48:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Running setup.py install for mozleak 12:48:34 INFO - Running setup.py install for mozlog 12:48:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Running setup.py install for moznetwork 12:48:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Running setup.py install for mozprocess 12:48:34 INFO - Running setup.py install for mozprofile 12:48:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:48:34 INFO - Running setup.py install for mozrunner 12:48:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:48:35 INFO - Running setup.py install for mozscreenshot 12:48:35 INFO - Running setup.py install for moztest 12:48:35 INFO - Running setup.py install for mozversion 12:48:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:48:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:48:35 INFO - Cleaning up... 12:48:35 INFO - Return code: 0 12:48:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:35 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:35 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 12:48:35 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 12:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:35 INFO - 'HOME': '/Users/cltbld', 12:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:35 INFO - 'LOGNAME': 'cltbld', 12:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:35 INFO - 'MOZ_NO_REMOTE': '1', 12:48:35 INFO - 'NO_EM_RESTART': '1', 12:48:35 INFO - 'PAGER': '/bin/cat', 12:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:35 INFO - 'PWD': '/builds/slave/test', 12:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:35 INFO - 'SHELL': '/bin/bash', 12:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:35 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:35 INFO - 'USER': 'cltbld', 12:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:35 INFO - 'XPC_FLAGS': '0x0', 12:48:35 INFO - 'XPC_SERVICE_NAME': '0', 12:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:48:35 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-D7s4vQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:48:35 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)) 12:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:48:35 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-h9ymCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-7jLNqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Elh1_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-hpoUGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KM7Lb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-HjdFcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-2gWZVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-MOI1ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-J44TSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-hhdErk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:48:36 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)) 12:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:48:36 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-2i3TPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:48:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Cy7htt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:48:37 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)) 12:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:48:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-8rFiSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:48:37 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)) 12:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-gCG4yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:37 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)) 12:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:48:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-1jYIxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:48:37 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)) 12:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:48:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-vJxr2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:48:37 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)) 12:48:37 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)) 12:48:37 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)) 12:48:37 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)) 12:48:37 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)) 12:48:37 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)) 12:48:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:48:37 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 12:48:37 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 12:48:37 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 12:48:37 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 12:48:37 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 12:48:37 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 12:48:40 INFO - Downloading blessings-1.6.tar.gz 12:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:48:40 INFO - Installing collected packages: blessings 12:48:40 INFO - Running setup.py install for blessings 12:48:40 INFO - Successfully installed blessings 12:48:40 INFO - Cleaning up... 12:48:40 INFO - Return code: 0 12:48:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:40 INFO - Reading from file tmpfile_stdout 12:48:40 INFO - Current package versions: 12:48:40 INFO - blessings == 1.6 12:48:40 INFO - blobuploader == 1.2.4 12:48:40 INFO - docopt == 0.6.1 12:48:40 INFO - functools32 == 3.2.3-2 12:48:40 INFO - jsonschema == 2.5.1 12:48:40 INFO - manifestparser == 1.1 12:48:40 INFO - mozInstall == 1.12 12:48:40 INFO - mozcrash == 1.0 12:48:40 INFO - mozdebug == 0.1 12:48:40 INFO - mozdevice == 0.49 12:48:40 INFO - mozfile == 1.2 12:48:40 INFO - mozhttpd == 0.7 12:48:40 INFO - mozinfo == 0.9 12:48:40 INFO - mozleak == 0.1 12:48:40 INFO - mozlog == 3.4 12:48:40 INFO - moznetwork == 0.27 12:48:40 INFO - mozprocess == 0.24 12:48:40 INFO - mozprofile == 0.28 12:48:40 INFO - mozrunner == 6.13 12:48:40 INFO - mozscreenshot == 0.1 12:48:40 INFO - mozsystemmonitor == 0.3 12:48:40 INFO - moztest == 0.8 12:48:40 INFO - mozversion == 1.4 12:48:40 INFO - psutil == 3.1.1 12:48:40 INFO - requests == 1.2.3 12:48:40 INFO - wsgiref == 0.1.2 12:48:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48: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')]} 12:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48: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')]} 12:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:40 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:40 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 12:48:40 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 12:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:40 INFO - 'HOME': '/Users/cltbld', 12:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:40 INFO - 'LOGNAME': 'cltbld', 12:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:40 INFO - 'MOZ_NO_REMOTE': '1', 12:48:40 INFO - 'NO_EM_RESTART': '1', 12:48:40 INFO - 'PAGER': '/bin/cat', 12:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:40 INFO - 'PWD': '/builds/slave/test', 12:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:40 INFO - 'SHELL': '/bin/bash', 12:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:40 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:40 INFO - 'USER': 'cltbld', 12:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:40 INFO - 'XPC_FLAGS': '0x0', 12:48:40 INFO - 'XPC_SERVICE_NAME': '0', 12:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 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)) 12:48:41 INFO - Cleaning up... 12:48:41 INFO - Return code: 0 12:48:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:41 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 0x10c80cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c80f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0535bf3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, '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.exiWpEuYPH/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.b62SuuuxuF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 12:48:41 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 12:48:41 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 12:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:41 INFO - 'HOME': '/Users/cltbld', 12:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:41 INFO - 'LOGNAME': 'cltbld', 12:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:41 INFO - 'MOZ_NO_REMOTE': '1', 12:48:41 INFO - 'NO_EM_RESTART': '1', 12:48:41 INFO - 'PAGER': '/bin/cat', 12:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:41 INFO - 'PWD': '/builds/slave/test', 12:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:41 INFO - 'SHELL': '/bin/bash', 12:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:48:41 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:41 INFO - 'USER': 'cltbld', 12:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:41 INFO - 'XPC_FLAGS': '0x0', 12:48:41 INFO - 'XPC_SERVICE_NAME': '0', 12:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:48:41 INFO - Cleaning up... 12:48:41 INFO - Return code: 0 12:48:41 INFO - Running post-action listener: _resource_record_post_action 12:48:41 INFO - Running post-action listener: _start_resource_monitoring 12:48:41 INFO - Starting resource monitoring. 12:48:41 INFO - [mozharness: 2017-01-19 20:48:41.397476Z] Finished create-virtualenv step (success) 12:48:41 INFO - [mozharness: 2017-01-19 20:48:41.398420Z] Running install step. 12:48:41 INFO - Running pre-action listener: _resource_record_pre_action 12:48:41 INFO - Running main action method: install 12:48:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:41 INFO - Reading from file tmpfile_stdout 12:48:41 INFO - Detecting whether we're running mozinstall >=1.0... 12:48:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:48:41 INFO - Reading from file tmpfile_stdout 12:48:41 INFO - Output received: 12:48:41 INFO - Usage: mozinstall [options] installer 12:48:41 INFO - Options: 12:48:41 INFO - -h, --help show this help message and exit 12:48:41 INFO - -d DEST, --destination=DEST 12:48:41 INFO - Directory to install application into. [default: 12:48:41 INFO - "/builds/slave/test"] 12:48:41 INFO - --app=APP Application being installed. [default: firefox] 12:48:41 INFO - mkdir: /builds/slave/test/build/application 12:48:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:49:03 INFO - Reading from file tmpfile_stdout 12:49:03 INFO - Output received: 12:49:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:49:03 INFO - Running post-action listener: _resource_record_post_action 12:49:03 INFO - [mozharness: 2017-01-19 20:49:03.720968Z] Finished install step (success) 12:49:03 INFO - [mozharness: 2017-01-19 20:49:03.721084Z] Running run-tests step. 12:49:03 INFO - Running pre-action listener: _resource_record_pre_action 12:49:03 INFO - Running main action method: run_tests 12:49:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:49:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:49:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:49:03 INFO - 2017-01-19 12:49:03.733 screenresolution[1629:7606] starting screenresolution argv=screenresolution get 12:49:03 INFO - 2017-01-19 12:49:03.735 screenresolution[1629:7606] Display 0: 1600x1200x32@60 12:49:03 INFO - 2017-01-19 12:49:03.740 screenresolution[1630:7608] starting screenresolution argv=screenresolution list 12:49:03 INFO - Available Modes on Display 0 12:49:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:49:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:49:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:49:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:49:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:49:03 INFO - Intel Iris: 12:49:03 INFO - Chipset Model: Intel Iris 12:49:03 INFO - Type: GPU 12:49:03 INFO - Bus: Built-In 12:49:03 INFO - VRAM (Dynamic, Max): 1536 MB 12:49:03 INFO - Vendor: Intel (0x8086) 12:49:03 INFO - Device ID: 0x0a2e 12:49:03 INFO - Revision ID: 0x0009 12:49:03 INFO - Displays: 12:49:03 INFO - Display: 12:49:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:49:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:49:03 INFO - Display Serial Number: 1600x1200 60 12:49:03 INFO - Main Display: Yes 12:49:03 INFO - Mirror: Off 12:49:03 INFO - Online: Yes 12:49:03 INFO - Rotation: Supported 12:49:03 INFO - Return code: 0 12:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:49:03 INFO - Python 2.7.3 12:49:03 INFO - Return code: 0 12:49:03 INFO - grabbing minidump binary from tooltool 12:49: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')]} 12:49:03 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 0x10c5ead30>, '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 0x10c511db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c5dfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:49:03 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 12:49:03 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 12:49:03 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 12:49:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:49:04 INFO - Return code: 0 12:49:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:49:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:49:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:49:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:49:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:49:04 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/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 12:49:04 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/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --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 12:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.exiWpEuYPH/Render', 12:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:04 INFO - 'HOME': '/Users/cltbld', 12:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:04 INFO - 'LOGNAME': 'cltbld', 12:49:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:49:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:04 INFO - 'MOZ_NO_REMOTE': '1', 12:49:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:49:04 INFO - 'NO_EM_RESTART': '1', 12:49:04 INFO - 'PAGER': '/bin/cat', 12:49:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:04 INFO - 'PWD': '/builds/slave/test', 12:49:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:04 INFO - 'SHELL': '/bin/bash', 12:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b62SuuuxuF/Listeners', 12:49:04 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 12:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:04 INFO - 'USER': 'cltbld', 12:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:04 INFO - 'XPC_FLAGS': '0x0', 12:49:04 INFO - 'XPC_SERVICE_NAME': '0', 12:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:04 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/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 12:49:04 INFO - mozversion application_buildid: 20170119071129 12:49:04 INFO - mozversion application_changeset: a3978751f45108ff1ae002ecebdc0fa23fc52b84 12:49:04 INFO - mozversion application_display_name: Nightly 12:49:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:49:04 INFO - mozversion application_name: Firefox 12:49:04 INFO - mozversion application_remotingname: firefox 12:49:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:49:04 INFO - mozversion application_vendor: Mozilla 12:49:04 INFO - mozversion application_version: 53.0a1 12:49:04 INFO - mozversion platform_buildid: 20170119071129 12:49:04 INFO - mozversion platform_changeset: a3978751f45108ff1ae002ecebdc0fa23fc52b84 12:49:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:49:04 INFO - mozversion platform_version: 53.0a1 12:49:04 INFO - using testdate: 1484858944 12:49:04 INFO - actual date: 1484858944 12:49:04 INFO - starting webserver on 'localhost:49254' 12:49:04 INFO - SUITE-START | Running 2 tests 12:49:04 INFO - TEST-START | dromaeo_css 12:49:04 INFO - operating with platform_type : mac_ 12:49:04 INFO - Initialising browser for dromaeo_css test... 12:49:04 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpxqyKd1/profile http://localhost:49254/getInfo.html) 12:49:07 INFO - PROCESS | 1640 | 2017-01-19 12:49:07.220 plugin-container[1642:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 12:49:07 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:49:07 INFO - PROCESS | 1640 | 2017-01-19 12:49:07.223 plugin-container[1642:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9317, name = 'com.apple.CFPasteboardClient' 12:49:07 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:49:07 INFO - PROCESS | 1640 | 2017-01-19 12:49:07.223 plugin-container[1642:7751] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:49:07 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 12:49:07 INFO - PROCESS | 1640 | colorDepth:24 12:49:07 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 12:49:07 INFO - PROCESS | 1640 | __metrics 12:49:07 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 12:49:08 INFO - TEST-INFO | 1640: exit 0 12:49:08 INFO - Browser initialized. 12:49:08 INFO - Running cycle 1/1 for dromaeo_css test... 12:49:08 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpxqyKd1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:10 INFO - PROCESS | 1643 | 2017-01-19 12:49:10.658 plugin-container[1644:7869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.tsm.portname' 12:49:10 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:49:10 INFO - PROCESS | 1643 | 2017-01-19 12:49:10.658 plugin-container[1644:7869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 12:49:10 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:49:10 INFO - PROCESS | 1643 | 2017-01-19 12:49:10.658 plugin-container[1644:7869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:49:20 INFO - PROCESS | 1643 | RSS: Main: 232341504 12:49:20 INFO - PROCESS | 1643 | 12:52:24 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49254/tests/dromaeo/cssquery-ext.html) 12:52:25 INFO - PROCESS | 1643 | RSS: Main: 261136384 12:52:25 INFO - PROCESS | 1643 | 12:54:08 INFO - PROCESS | 1643 | ************************* 12:54:08 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 12:54:08 INFO - PROCESS | 1643 | 12:54:08 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:54:08 INFO - PROCESS | 1643 | Full stack: 12:54:08 INFO - PROCESS | 1643 | ************************* 12:55:27 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-ext.html (next: http://localhost:49254/tests/dromaeo/cssquery-jquery.html) 12:55:28 INFO - PROCESS | 1643 | RSS: Main: 262033408 12:55:28 INFO - PROCESS | 1643 | 12:58:30 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49254/tests/dromaeo/cssquery-mootools.html) 12:58:31 INFO - PROCESS | 1643 | RSS: Main: 274739200 12:58:31 INFO - PROCESS | 1643 | 13:01:32 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49254/tests/dromaeo/cssquery-prototype.html) 13:01:33 INFO - PROCESS | 1643 | RSS: Main: 270934016 13:01:33 INFO - PROCESS | 1643 | 13:04:36 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49254/tests/dromaeo/cssquery-yui.html) 13:04:37 INFO - PROCESS | 1643 | RSS: Main: 275701760 13:04:37 INFO - PROCESS | 1643 | 13:07:39 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-yui.html (next: http://localhost:49254/tests/dromaeo/cssquery-dojo.html) 13:07:40 INFO - PROCESS | 1643 | RSS: Main: 278052864 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | __start_tp_report 13:07:40 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 13:07:40 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 13:07:40 INFO - PROCESS | 1643 | |i|pagename|runs| 13:07:40 INFO - PROCESS | 1643 | |0;dojo.html;3258.3646188993125;3288.440965035944;3317.5189597508347;3331.217163966469;3341.028793012122;5436.184572314145;5457.699826509541;5476.835694929148;5498.835034948953;5556.361018482876;5480.342358916925;5497.752601132948;5534.667919924807;5554.972225138868;5603.859903502414;4256.318988961767;4409.258342954531;5033.169527028036;5035.974820125894;5171.482851714836;4969.652124351297;5172.560332371734;5183.922241166386;5198.064348417283;5200.6099542534275;5050.949490505084;5135.35808023997;5144.536991670749;5185.870353241162;5190.351206099238;3858.247641709868;5014.623903207274;5050.217216331474;5070.847874563769;5090.516400941905;4463.4420697412825;5064.392272927273;5090.414602320743;5115.258287548322;5118.692878427269;4441.800118994653;5433.157860531624;5531;5550.888982220374;5573.554115670737;5957.434043765836;5980.760769569212;6072.119542666289;6115.724792384309;6128.938710612882;5085.542301192911;6081.1790408294755;6106.642687876637;6136.815895523141;6138.07928810682;5679.630823996468;5680.602357834994;5702.486776190163;5713.228714123623;5732.914006289909;6124.35694252106;6221.688915554204;6227.097070924781;6265.154204182468;6282.528810339243;5284.9735751320995;5828.795992140254;5843.503302219359;5880.588358814842;6028.517718582503;5745.913811292834;6014.188084608521;6043.939560604425;6068.393160683896;6105.26736791579;5950.642961422329;5987.43119403656;6015.578909476382;6038.849028774316;6073.787417440457;6113.932424433873;6115.327313995457;6131.407250743203;6132.722655450988;6142.191841058483;4191.140816132685;4874.488178741252;4885.145099607555;4893.143699852582;4920.237363208708;4263.552327005682;4275;4276.230278549829;4294.098239369705;4324.286492728665;4723;5381.4349493303425;5642.26650535436;5650.689212093374;5696.914546281809;6149.7847575334645;6150.630962142196;6161.599496032743;6191.164192834;6208.627482351073;5532.6680399176175;5557.249771280906;5567.832965011056;5571.303587051619;5627.971860140673;5272.499112584298;5309.681419114865;5310.495502927216;5315.28243687105;5380.515753582196;6240.812775616739;6260.499160067184;6264.968675156595;6282.026285925689;6308.684565771693;6004.0693692477735;6128;6140.938590614037;6149.20060392164;6172.814815555541;6172.753089876354;6173.59871608344;6175.228096487939;6201.193844800147;6231.750729970931;6088.543359247986;6095.329513753572;6102.4507794298725;6105.389461053859;6108.205933228652;5825.70871456417;6107.84730381744;6125.387461253839;6146.323904370605;6189.752409903554;5784.2769653793275;5853.560982926212;5878.470937615635;5902.203202567598;6005.549583781321;5855.151003104611;5859.150423188699;5874.17761513397;5953.821385358447;5963.880722385441;4989.55094041538;5709.65742055478;5754.309482862083;5770.105633626795;5817.738201780845;5906.143609176731;6015.39846015395;6035.39646035393;6038.818835434944;6071.817845464643;5648.604597678123;5697.743601537858;5712.0860662293835;5793.710314484343;5848.766049357978 13:07:40 INFO - PROCESS | 1643 | |1;ext.html;465.2253997094837;482.7079616831817;494.9158643030684;512.4312013664834;518.1605798606258;40944.18111637765;42838;42967.57032429675;42986.355204671956;42995;43406.565934340644;44251.33622995658;44296.55703442964;44305.556944430544;44405.11189776203;8424.705135320264;8634;9185.173334399919;9533.14201721845;9540.475273859935;11271.041961433288;11537.192396532246;11572.826407603892;11705.239159454652;11758.353290569015;11509.884901151006;11558.942205288962;11584.47869845859;11589.36258505782;11601.245919015279;11404.54381824726;11472.082233421348;11495.655130346104;11504.539818407253;11532.942335288353;11212.04697600705;11219.495122719498;11242.437878106144;11302.773944521146;11305.943470282597;41380;41405;41479.37780933289;41495.37756933648;41619.95950101242;3768.8869333920024;4046.5548789633117;4074.8370065197355;4077.1030373318117;4149.190907772985;29009.56485652717;29684.40631187364;29689.851550742325;29709.70290297091;29722.81108755647;2875.7555607773365;3150.4486714824816;3293.6541663125504;3324.983375083133;3355.0438125134465;394386;481171;489892;492904;493148;56307.43692563104;56564.717176413855;56610.71694641501;56695;56757.432425675215;59782;59980.40019599835;60321.396786032456;60424.697876510334;60538.78922421527;14181.858181418054;14363.563643635553;15545.689086218212;15756.527304180892;15769.842301576837;10953.726156846142;11174.66476005721;11927.58253461142;12081.697957550956;12201.938990304992;17190.484285471455;17262.050587217822;17323.56691082733;17419.99670037962;17550.29798808059;7547.50941188821;7681.615919204018;7707.730229441942;7709.922900771033;7714.845703085907;754.3324158120104;856.3406177243488;856.6016802187013;866.9769671787226;873.4890089297705;30503;30605.846970765004;31086.844565777028;31525.369492610018;31684.841575791972;878.692457639821;1069.983950240747;1102.3000394749392;1102.404701461202;1247.9766591394969;15490.767638485431;16007.439739609057;16011.119388433748;16013.439529616408;16358.509244722678;230357;231619;231836;232756;232906;62585.68707156526;63292;63342.36657633457;63453.365466344745;63578;1279.4242590834162;1414.3988804757937;1438.4102517967583;1452.157748505864;1464.8901332400112;28734;29499.557506637848;29545;30286.091417257518;30514.26872770564;53647.463525365805;53647.73176134095;53965.190522142984;54112;54116;458655;538395;539654.3017284889;540593;542749;25318.113866015337;26054.478910421305;26921.326966825985;27346;27371;42252;42831.35752963646;43173.92065198395;43402;43442.78278608587;36697.44953825748;36790;37669;37739.47126344856;38154.80922595369;30589.847050764605;34797.30405391929;36438.63561364458;36510;37834.62165378311;36104.458433123;36307.27385452329;36355.63644363522;36449.45325820168;37111.81444092762;31901.521477178325;33007.04052926361;33058;33174.83412582921;33626.82306119274;21177.89411052935;23210.839458026694;24619.753802461746;24814;25243.49513009693 13:07:40 INFO - PROCESS | 1643 | |2;jquery.html;380.5870630366051;382.1058722589142;390.2468236303933;392.9586595521869;415.44745488500297;15193.316300766464;15311.387544498235;15476.458323958665;20559.486012849688;20870.478238044056;17834.910825445855;21325.68011479829;21499.46251343717;21668.133274668995;21723.67414488784;5579.776808927638;5670.489655930955;5795.1886735857015;5875.383084776101;5914.4381283777975;5694.288213973254;5840.445157710011;5848.561357898154;5849.2980842298775;5852.356240813507;5655.236543066674;5948.375420580842;5973.044312909935;6070.757169713206;6075.149479072933;5346.117890548055;5784.045632470637;5959.314678811948;5963.970180149093;6040.818775436722;5874.353821079678;5879.235699359098;5881.294244690622;5886.8822623547285;5901.262342207224;13515;17770.9337439754;18080.83030036391;24271.271861844172;26420.47159056827;2212.513247085639;2613.4642398308492;2658.86705664716;2675;2688.5429476988957;9960;9965.40207587547;10091.949540252324;10406.843897341547;10414.895851041545;1584.8415158484174;1779.661864245789;1849.4821449994024;1862.3016368861677;1866.5333666583354;108469;135121;139202.30398848042;139804.60195398016;140475;10572.15422766177;11309.321440713584;11831.17181797266;12036.63890083299;12196;13477.730445391036;15547.067175969478;15685.843141568439;15689.843101569066;15707.528774136794;8047.436679432501;8528.701495447754;8572.742817715478;8687.565621718888;8816.603252853636;8361.498310101413;8372.162783721578;8390.79023024417;8514.148585141436;8547.658093676308;3951.6838652908273;4793.304970779287;4793.880152996133;4796.784144713496;4889;1663.609051872809;1794.2105473591578;1987.3739771971782;2045.9181632734524;2047.2015913793632;890.844102282098;984.3601658921758;1020.6631811502186;1030.175859312562;1062.2670357453333;15932;20472.69290960637;20552.944705529328;20634.896825515778;23102.191423300104;959.9808003840024;962.821877952581;983.6508039645822;991.2466525440717;1003.9397636141855;6164.845878853064;6178.762749689154;6243.188385509855;6272.529560282997;6377.808665740035;707213;707954;710248.4487577529;746071;750422;112676.43661781639;135938;140803;144459;145039;1042.9426381548935;1049.8687664041995;1073.3774410841693;1073.3881687438086;1078.3368228539548;11315.98156165949;11412.429378531206;11512.079033677119;11534.423278836193;11594.76810463803;52827.73586132045;54047.18929215986;54882.90234195214;55045.724771375884;55434.722826387224;81500;81666.18333816585;81685.59157204175;81835.7724634143;82063;7921.287084162453;8150.614395552889;8318.792030199293;8382.664693412195;8545.871811922701;9243.537823108683;10526.263161578616;11979;12656.557020504239;12911.35443227816;10553.577856885639;10890.128789696964;10904.964028417287;11107.777844442904;11368.658940231806;6860.416864566568;9230.261579073793;11036.668899933014;11069.944650276697;11412.657620271406;10828.187885908746;11376.715582110513;11474.713132171762;11650.359230242419;11692.883071169179;7822.06135263772;7975.960120199362;7997.400194985516;8065.83868322642;8177.468464550023;10822.458877056273;11482.655520334403;11502.194846360675;11581.652550423501;11600.825987610024 13:07:40 INFO - PROCESS | 1643 | |3;mootools.html;1333.4666133546575;1454.134789800069;1469.1111877314218;1490.3814916809524;1492.8357880633127;7543.698252069924;7783.338416234613;7980.241877021681;8382.916170838298;8481.533515656643;7985.560794156319;8271.751847444571;8291.502509849399;8404.03353614333;8441.873371899412;2261.1633695532637;2287.6797248385237;2309.503456756797;2317.617593097137;2326.895289711967;2298.528801595669;2319.1419174905222;2322.129201549419;2337.333859849943;2341.098677009347;2276.6698828669832;2277.9088836446604;2304.8962796674105;2310.0297874892503;2316.3745788637057;1904.3430016644236;1944.2028768204968;1950.8536859735505;2275.8520696154696;2296.3007374542885;2266.172846910882;2317.7102862142233;2326.674265602817;2328.068772491017;2331.778481044298;8334.708285209988;8339.916600834036;8432.114627964036;8435.578221088921;8443.1134730853;4603.401557797465;5032.094223039888;5069.059945231794;5190.532852043297;5191.273221748958;3264.8041117533025;3342.4317865962844;3465.861365545375;3524.18943642961;3616.4213725803747;1213.9332336721477;1215.4166000319897;1225.4669218889812;1248.5505218121468;1268.7439434959324;10694;10802.72993175169;10805.02754752076;10809.67570972872;10867.347959122479;4397.494288156839;4653.767311634405;4707;4724.448797649231;4745.549172828576;7936.642851071716;9454.810903782023;9455.101765332283;9477.668281610111;9602.183814375716;5204.9219261711105;5223.347081614798;5233.215017747369;5237.9476205238225;5245.029669511153;4180.482769968717;4624.768761561909;4701.670883038223;4755.500672429414;4810.7113573185725;3740.981295093507;3821.522309711286;3865.7100717446306;3876.8643097492018;3900.6294402031763;1273.0268084469035;1282.3139620303093;1284.3963337231487;1287.3691890973544;1294.766941950458;791.9643616037176;828.4118276024091;898.9640414383482;903.823754367892;915.2312057871287;5155.510226528474;5299.49654782795;5421.484958928895;5659.971700141556;5841.153032810219;748.6780684305692;924.930630202724;989.2975987049275;991.8214721350082;998.236349192868;3112.4242015227255;3651.2697460508007;3707.888763337104;3779.622037796253;3838.9040273993373;8302.958485207535;9323;9695.51522423876;9992.950035249778;10059.094681478702;8847.557622118868;9426.491761318157;9458.57436415363;9845.50772461374;9943.303968722264;6645.235797883209;7419.962900185573;7947.403944704055;7965.322947549524;7971.760847174594;4692.765361731968;4761.976190119027;5659.151127330933;5700.429957004267;5959.91060134107;8874.600642970952;8962.058983806866;9094.727158185266;9107.043760405062;9338.859917101114;9117.817643647222;9290.72127836166;9305.720828375159;9318.161365476868;9452.763680908032;6149.938500614937;6529.085927969993;6600.768973085919;7110.715571377087;7244.963775181091;7807.921920780719;7908.84182316362;7924.049114106344;7927.207279272026;7935.722249721232;6996.265392133752;7065.788026359218;7103.25415831351;7191.8561628768175;7244.348008679244;6880.380765731108;6955.756548520778;6978.965105174441;7056.188538317954;7082.929170708433;6349.936500635119;6354.205724284465;6438.195225596801;6456.644884531396;6485.805425837232;4301.419308393327;4911.877203069952;4961.7271050092595;4968.726720030289;5066.518680725325;4887.853364399074;4933.827316044065;4975.452700203047;4993.27597498368;5056.292119103255 13:07:40 INFO - PROCESS | 1643 | |4;prototype.html;288.92199106241316;299.580587177951;359.42312588295783;359.82008995502247;360.94044482660354;4490.057088011514;4590.357349971006;5001.274815151805;5369.624126311155;5483.972580137099;4419.646428285738;4816.590589799871;5325;5394.676319420838;5447.645903016301;3638.5633723953165;3833.8466461341513;3834.94247586285;4133.979330103361;4164.083901541656;4158.875233742978;4193.833655590025;4222.598853108965;4223.387608796723;4231.4710661167355;3567.1973805893726;4151.294279972412;4204.474440694913;4230.2174097792;4238.830446782125;3785.242951409729;4055.8174882130374;4071.633552980246;4214.515330736958;4223.36649502574;2816.8309901405983;3136.5765621641012;3370.427027405347;3501.404761190578;3531.5762108546883;4086.346184610448;4405.493368262627;4871.756412179377;4898.681585696918;5137.306463627438;4068.410080538335;4272.807723652443;4502.977485112586;4558.9772051139535;4668.533146685339;4658.697184682985;5018.723970181639;5446.91829622556;5649.435056494359;5679.586175875236;3865.5361356637104;3882.301185786557;3894.591067937854;3897.4153876918404;3899.4345819856244;5636.464535869045;6002.339742628307;6014.879702405971;6029.849253768647;6063.9090413643835;3714.795686237283;3920.4903362562686;3948.368261078214;3966.643002129822;3972.3445631470486;3579.910502237413;3810.942835857464;3993.5407428145554;4045;4052.6555242804693;4310.900720316362;4376.562343765661;4449.775112443778;4684.906301873943;4691.601213896789;5486.890262194734;5682.573806964494;5744.253247077853;5763.798267060716;5803.100519419497;1221.4808706299787;1294.4627979388636;1295.838020247469;1297.8183054372396;1298.7597294500547;769.5498133591911;783.4995982693192;868.8479516084786;877.7410663854179;883.5008220355479;4874.926876096862;5126.8974620508125;5249.766304918341;5271.78912843482;5310.468953104736;4614.861554153381;5080.314157588752;5109.540141387293;5132.486751324912;5845.298564172326;4499.257622492318;4675.509071547439;4755.0489902019735;4910.656254062182;5286.9471305287225;2534.657821194152;2567.8202525823012;2969.5248760198697;2975.702429757007;3001.864916078782;5717.283241325923;5785.768569257267;5936.881262374728;5950.67271300074;5974.3726908674835;4490.550944905483;5398.64908780928;5458.344998600193;5648.717564121777;5867.501262392659;4929.482404347492;5099.847004589868;5220.608454365938;5928.4960778334325;6004.937481884271;5245.9475405246985;5592.665684094859;5614.887702246014;5623.578231632562;5640.630935007389;5289.68261904287;5348.946510535001;5522.978249023943;5764.19301297827;5838.883222335615;4979;5655.830325090254;5747.166660834072;6155.722992465439;6179.907301390393;3655.2872189923773;3681.8895433135976;3957.228340473695;3981.601839816111;3999.560048394616;4758.547937945889;5825;5859.677717725566;5868.501177399798;5898.911516327173;4821.421429428491;4977.631151433385;5354.064762399556;5461.726913654381;5729.28383952006;4624.953750462587;4976.502349765139;5255.948810238044;5480;5640.830775076754;5324.334458192726;5409.323834520685;5545.140503222007;5566.944330556643;5570.384588469298;5423.83728488146;5462.535684466865;5626.606137570331;5698.544116470755;5722.740996980658;5498.477644623755;5534.08687566547;5537.390887002346;5537.846615038721;5565.55475561946 13:07:40 INFO - PROCESS | 1643 | |5;yui.html;731.7804658602417;776.3013288040762;811.2212276214833;845.2772879188294;848.6944699908034;15243.161626110574;15838;15839.84160158401;15875.920620396912;15954.68090638188;14485.130892146479;14567.635809104777;14579.854201457982;14616.853831461682;14682.63293417665;676.1818199978032;691.495208497797;844.4933040175875;844.6452489954203;856.7386946981168;870.7344260000729;885.1590988560861;886.3219636977685;886.4902680958443;886.8935727712651;919.1497864475367;929.5816882402912;931.4271722890442;933.5005771912029;934.1405906565942;923.0400383601046;923.0769230769231;923.7967647117657;926.3839546701436;928.0533855467386;687.6252442418894;688.8243497908052;690.3234829866759;690.6443181761397;691.3017851969497;13105.069083034361;13636.181829090187;15260.542183734506;15327.846721532753;15815.367385304575;14465.493707720285;14576.708465830625;14763.040402373812;14787.778183327258;14798.40806367744;11839.230450020807;11888.227265227819;11901.464434100486;12002.21985570935;12012.279263244145;3668.0646435158865;3693.316736403774;3721.3301605710963;3721.7394782364972;3853;101833.4908325461;104089.42748015284;104460;105736.47131764292;105980.47009764978;11336.206465547497;11381.317120972768;11476.368799716096;11476.4835582399;11594.362310072858;21475.248366307103;22142.45720684715;23817;23914.923828427764;23982.52034959326;7845.882311765328;7923.16806735296;7973.72091976778;8098.109207987117;8211.137830527829;3584.4802503636824;4217.98768295613;4369.934450983238;4413.16149931512;4485.663575231871;3529.735269854771;3699.112213068844;3732.8880133596035;3753.774773513598;3758.1732018955786;663.598522893652;678.1760161403918;681.431005110738;689.5931400473745;691.7717153339386;665.215046245435;692.2281655953575;699.6816448515913;703.5040296590994;706.4842664854685;10867.78264434707;11011.889881101246;11026.173037022256;11193.49629266685;11403;570.5777724483852;579.8027074091987;611.4527497889453;614.5114633866087;714.2678754276908;4389.956100439019;4816;4986.975065124724;5032.823851165192;5066.113430149709;80160.78552930264;80307;91623;93220.53389733144;95504;70346;73330;81472.77790833142;83105.16894831095;85441.14558854332;65033;65687.67156164188;66895;74516.88224676634;76086;10243.897561024294;11049.83425248638;11180.273282236629;11262.068452011681;11419.143564232882;45819;47138.76430617825;47161;47245.527544724115;48762.756186218845;61972.69013654903;65563.67218163879;66751.66624166848;67180.6640966792;68296.6585167071;10083.848742269018;12345.234703415545;12804.871951280367;13142.540011099565;13562.457501699822;13196;14896.584824441661;14950.551483455514;15227.847721522643;15351;11372.461282223514;14040.859591404364;14301.785473218119;14404.42382304696;14821.481248156262;14041.859581404055;14060.156390616596;14308.496013197857;14407.279636018367;14426.350814213176;13800.654983625489;13824.861751382357;13999.510017149758;14188.077774944595;14265.144091354552;10134.733158355206;11071.225014249247;11137.434873521694;11255.380954047607;11526;9472.857907131536;9751;11235.831462528233;11311;11365.31808091517 13:07:40 INFO - PROCESS | 1643 | __end_tp_report 13:07:40 INFO - PROCESS | 1643 | __start_cc_report 13:07:40 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,386 13:07:40 INFO - PROCESS | 1643 | __end_cc_report 13:07:40 INFO - PROCESS | 1643 | __startTimestamp1484860060102__endTimestamp 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | ------- Summary: start ------- 13:07:40 INFO - PROCESS | 1643 | Number of tests: 6 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5567.02 Median:5745.91 stddev:671.73 (11.7%) stddev-sans-first:648.91 13:07:40 INFO - PROCESS | 1643 | Values: 3258.4 3288.4 3317.5 3331.2 3341.0 5436.2 5457.7 5476.8 5498.8 5556.4 5480.3 5497.8 5534.7 5555.0 5603.9 4256.3 4409.3 5033.2 5036.0 5171.5 4969.7 5172.6 5183.9 5198.1 5200.6 5050.9 5135.4 5144.5 5185.9 5190.4 3858.2 5014.6 5050.2 5070.8 5090.5 4463.4 5064.4 5090.4 5115.3 5118.7 4441.8 5433.2 5531.0 5550.9 5573.6 5957.4 5980.8 6072.1 6115.7 6128.9 5085.5 6081.2 6106.6 6136.8 6138.1 5679.6 5680.6 5702.5 5713.2 5732.9 6124.4 6221.7 6227.1 6265.2 6282.5 5285.0 5828.8 5843.5 5880.6 6028.5 5745.9 6014.2 6043.9 6068.4 6105.3 5950.6 5987.4 6015.6 6038.8 6073.8 6113.9 6115.3 6131.4 6132.7 6142.2 4191.1 4874.5 4885.1 4893.1 4920.2 4263.6 4275.0 4276.2 4294.1 4324.3 4723.0 5381.4 5642.3 5650.7 5696.9 6149.8 6150.6 6161.6 6191.2 6208.6 5532.7 5557.2 5567.8 5571.3 5628.0 5272.5 5309.7 5310.5 5315.3 5380.5 6240.8 6260.5 6265.0 6282.0 6308.7 6004.1 6128.0 6140.9 6149.2 6172.8 6172.8 6173.6 6175.2 6201.2 6231.8 6088.5 6095.3 6102.5 6105.4 6108.2 5825.7 6107.8 6125.4 6146.3 6189.8 5784.3 5853.6 5878.5 5902.2 6005.5 5855.2 5859.2 5874.2 5953.8 5963.9 4989.6 5709.7 5754.3 5770.1 5817.7 5906.1 6015.4 6035.4 6038.8 6071.8 5648.6 5697.7 5712.1 5793.7 5848.8 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:56887.28 Median:35450.88 stddev:114252.45 (322.3%) stddev-sans-first:114494.42 13:07:40 INFO - PROCESS | 1643 | Values: 465.2 482.7 494.9 512.4 518.2 40944.2 42838.0 42967.6 42986.4 42995.0 43406.6 44251.3 44296.6 44305.6 44405.1 8424.7 8634.0 9185.2 9533.1 9540.5 11271.0 11537.2 11572.8 11705.2 11758.4 11509.9 11558.9 11584.5 11589.4 11601.2 11404.5 11472.1 11495.7 11504.5 11532.9 11212.0 11219.5 11242.4 11302.8 11305.9 41380.0 41405.0 41479.4 41495.4 41620.0 3768.9 4046.6 4074.8 4077.1 4149.2 29009.6 29684.4 29689.9 29709.7 29722.8 2875.8 3150.4 3293.7 3325.0 3355.0 394386.0 481171.0 489892.0 492904.0 493148.0 56307.4 56564.7 56610.7 56695.0 56757.4 59782.0 59980.4 60321.4 60424.7 60538.8 14181.9 14363.6 15545.7 15756.5 15769.8 10953.7 11174.7 11927.6 12081.7 12201.9 17190.5 17262.1 17323.6 17420.0 17550.3 7547.5 7681.6 7707.7 7709.9 7714.8 754.3 856.3 856.6 867.0 873.5 30503.0 30605.8 31086.8 31525.4 31684.8 878.7 1070.0 1102.3 1102.4 1248.0 15490.8 16007.4 16011.1 16013.4 16358.5 230357.0 231619.0 231836.0 232756.0 232906.0 62585.7 63292.0 63342.4 63453.4 63578.0 1279.4 1414.4 1438.4 1452.2 1464.9 28734.0 29499.6 29545.0 30286.1 30514.3 53647.5 53647.7 53965.2 54112.0 54116.0 458655.0 538395.0 539654.3 540593.0 542749.0 25318.1 26054.5 26921.3 27346.0 27371.0 42252.0 42831.4 43173.9 43402.0 43442.8 36697.4 36790.0 37669.0 37739.5 38154.8 30589.8 34797.3 36438.6 36510.0 37834.6 36104.5 36307.3 36355.6 36449.5 37111.8 31901.5 33007.0 33058.0 33174.8 33626.8 21177.9 23210.8 24619.8 24814.0 25243.5 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:38724.70 Median:14539.67 stddev:120568.36 (829.2%) stddev-sans-first:120872.21 13:07:40 INFO - PROCESS | 1643 | Values: 380.6 382.1 390.2 393.0 415.4 15193.3 15311.4 15476.5 20559.5 20870.5 17834.9 21325.7 21499.5 21668.1 21723.7 5579.8 5670.5 5795.2 5875.4 5914.4 5694.3 5840.4 5848.6 5849.3 5852.4 5655.2 5948.4 5973.0 6070.8 6075.1 5346.1 5784.0 5959.3 5964.0 6040.8 5874.4 5879.2 5881.3 5886.9 5901.3 13515.0 17770.9 18080.8 24271.3 26420.5 2212.5 2613.5 2658.9 2675.0 2688.5 9960.0 9965.4 10091.9 10406.8 10414.9 1584.8 1779.7 1849.5 1862.3 1866.5 108469.0 135121.0 139202.3 139804.6 140475.0 10572.2 11309.3 11831.2 12036.6 12196.0 13477.7 15547.1 15685.8 15689.8 15707.5 8047.4 8528.7 8572.7 8687.6 8816.6 8361.5 8372.2 8390.8 8514.1 8547.7 3951.7 4793.3 4793.9 4796.8 4889.0 1663.6 1794.2 1987.4 2045.9 2047.2 890.8 984.4 1020.7 1030.2 1062.3 15932.0 20472.7 20552.9 20634.9 23102.2 960.0 962.8 983.7 991.2 1003.9 6164.8 6178.8 6243.2 6272.5 6377.8 707213.0 707954.0 710248.4 746071.0 750422.0 112676.4 135938.0 140803.0 144459.0 145039.0 1042.9 1049.9 1073.4 1073.4 1078.3 11316.0 11412.4 11512.1 11534.4 11594.8 52827.7 54047.2 54882.9 55045.7 55434.7 81500.0 81666.2 81685.6 81835.8 82063.0 7921.3 8150.6 8318.8 8382.7 8545.9 9243.5 10526.3 11979.0 12656.6 12911.4 10553.6 10890.1 10905.0 11107.8 11368.7 6860.4 9230.3 11036.7 11069.9 11412.7 10828.2 11376.7 11474.7 11650.4 11692.9 7822.1 7976.0 7997.4 8065.8 8177.5 10822.5 11482.7 11502.2 11581.7 11600.8 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5358.71 Median:5228.28 stddev:2906.05 (55.6%) stddev-sans-first:2898.45 13:07:40 INFO - PROCESS | 1643 | Values: 1333.5 1454.1 1469.1 1490.4 1492.8 7543.7 7783.3 7980.2 8382.9 8481.5 7985.6 8271.8 8291.5 8404.0 8441.9 2261.2 2287.7 2309.5 2317.6 2326.9 2298.5 2319.1 2322.1 2337.3 2341.1 2276.7 2277.9 2304.9 2310.0 2316.4 1904.3 1944.2 1950.9 2275.9 2296.3 2266.2 2317.7 2326.7 2328.1 2331.8 8334.7 8339.9 8432.1 8435.6 8443.1 4603.4 5032.1 5069.1 5190.5 5191.3 3264.8 3342.4 3465.9 3524.2 3616.4 1213.9 1215.4 1225.5 1248.6 1268.7 10694.0 10802.7 10805.0 10809.7 10867.3 4397.5 4653.8 4707.0 4724.4 4745.5 7936.6 9454.8 9455.1 9477.7 9602.2 5204.9 5223.3 5233.2 5237.9 5245.0 4180.5 4624.8 4701.7 4755.5 4810.7 3741.0 3821.5 3865.7 3876.9 3900.6 1273.0 1282.3 1284.4 1287.4 1294.8 792.0 828.4 899.0 903.8 915.2 5155.5 5299.5 5421.5 5660.0 5841.2 748.7 924.9 989.3 991.8 998.2 3112.4 3651.3 3707.9 3779.6 3838.9 8303.0 9323.0 9695.5 9993.0 10059.1 8847.6 9426.5 9458.6 9845.5 9943.3 6645.2 7420.0 7947.4 7965.3 7971.8 4692.8 4762.0 5659.2 5700.4 5959.9 8874.6 8962.1 9094.7 9107.0 9338.9 9117.8 9290.7 9305.7 9318.2 9452.8 6149.9 6529.1 6600.8 7110.7 7245.0 7807.9 7908.8 7924.0 7927.2 7935.7 6996.3 7065.8 7103.3 7191.9 7244.3 6880.4 6955.8 6979.0 7056.2 7082.9 6349.9 6354.2 6438.2 6456.6 6485.8 4301.4 4911.9 4961.7 4968.7 5066.5 4887.9 4933.8 4975.5 4993.3 5056.3 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4509.35 Median:4977.07 stddev:1386.86 (27.9%) stddev-sans-first:1354.09 13:07:40 INFO - PROCESS | 1643 | Values: 288.9 299.6 359.4 359.8 360.9 4490.1 4590.4 5001.3 5369.6 5484.0 4419.6 4816.6 5325.0 5394.7 5447.6 3638.6 3833.8 3834.9 4134.0 4164.1 4158.9 4193.8 4222.6 4223.4 4231.5 3567.2 4151.3 4204.5 4230.2 4238.8 3785.2 4055.8 4071.6 4214.5 4223.4 2816.8 3136.6 3370.4 3501.4 3531.6 4086.3 4405.5 4871.8 4898.7 5137.3 4068.4 4272.8 4503.0 4559.0 4668.5 4658.7 5018.7 5446.9 5649.4 5679.6 3865.5 3882.3 3894.6 3897.4 3899.4 5636.5 6002.3 6014.9 6029.8 6063.9 3714.8 3920.5 3948.4 3966.6 3972.3 3579.9 3810.9 3993.5 4045.0 4052.7 4310.9 4376.6 4449.8 4684.9 4691.6 5486.9 5682.6 5744.3 5763.8 5803.1 1221.5 1294.5 1295.8 1297.8 1298.8 769.5 783.5 868.8 877.7 883.5 4874.9 5126.9 5249.8 5271.8 5310.5 4614.9 5080.3 5109.5 5132.5 5845.3 4499.3 4675.5 4755.0 4910.7 5286.9 2534.7 2567.8 2969.5 2975.7 3001.9 5717.3 5785.8 5936.9 5950.7 5974.4 4490.6 5398.6 5458.3 5648.7 5867.5 4929.5 5099.8 5220.6 5928.5 6004.9 5245.9 5592.7 5614.9 5623.6 5640.6 5289.7 5348.9 5523.0 5764.2 5838.9 4979.0 5655.8 5747.2 6155.7 6179.9 3655.3 3681.9 3957.2 3981.6 3999.6 4758.5 5825.0 5859.7 5868.5 5898.9 4821.4 4977.6 5354.1 5461.7 5729.3 4625.0 4976.5 5255.9 5480.0 5640.8 5324.3 5409.3 5545.1 5566.9 5570.4 5423.8 5462.5 5626.6 5698.5 5722.7 5498.5 5534.1 5537.4 5537.8 5565.6 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:19574.98 Median:3743.33 stddev:26897.47 (718.5%) stddev-sans-first:26935.71 13:07:40 INFO - PROCESS | 1643 | Values: 731.8 776.3 811.2 845.3 848.7 15243.2 15838.0 15839.8 15875.9 15954.7 14485.1 14567.6 14579.9 14616.9 14682.6 676.2 691.5 844.5 844.6 856.7 870.7 885.2 886.3 886.5 886.9 919.1 929.6 931.4 933.5 934.1 923.0 923.1 923.8 926.4 928.1 687.6 688.8 690.3 690.6 691.3 13105.1 13636.2 15260.5 15327.8 15815.4 14465.5 14576.7 14763.0 14787.8 14798.4 11839.2 11888.2 11901.5 12002.2 12012.3 3668.1 3693.3 3721.3 3721.7 3853.0 101833.5 104089.4 104460.0 105736.5 105980.5 11336.2 11381.3 11476.4 11476.5 11594.4 21475.2 22142.5 23817.0 23914.9 23982.5 7845.9 7923.2 7973.7 8098.1 8211.1 3584.5 4218.0 4369.9 4413.2 4485.7 3529.7 3699.1 3732.9 3753.8 3758.2 663.6 678.2 681.4 689.6 691.8 665.2 692.2 699.7 703.5 706.5 10867.8 11011.9 11026.2 11193.5 11403.0 570.6 579.8 611.5 614.5 714.3 4390.0 4816.0 4987.0 5032.8 5066.1 80160.8 80307.0 91623.0 93220.5 95504.0 70346.0 73330.0 81472.8 83105.2 85441.1 65033.0 65687.7 66895.0 74516.9 76086.0 10243.9 11049.8 11180.3 11262.1 11419.1 45819.0 47138.8 47161.0 47245.5 48762.8 61972.7 65563.7 66751.7 67180.7 68296.7 10083.8 12345.2 12804.9 13142.5 13562.5 13196.0 14896.6 14950.6 15227.8 15351.0 11372.5 14040.9 14301.8 14404.4 14821.5 14041.9 14060.2 14308.5 14407.3 14426.4 13800.7 13824.9 13999.5 14188.1 14265.1 10134.7 11071.2 11137.4 11255.4 11526.0 9472.9 9751.0 11235.8 11311.0 11365.3 13:07:40 INFO - PROCESS | 1643 | -------- Summary: end -------- 13:07:40 INFO - PROCESS | 1643 | 13:07:40 INFO - TEST-INFO | 1643: exit 0 13:07:40 INFO - TEST-OK | dromaeo_css | took 1116397ms 13:07:40 INFO - TEST-START | kraken 13:07:40 INFO - operating with platform_type : mac_ 13:07:40 INFO - Initialising browser for kraken test... 13:07:40 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp7Dbz7m/profile http://localhost:49254/getInfo.html) 13:07:43 INFO - PROCESS | 1652 | 2017-01-19 13:07:43.284 plugin-container[1653:10210] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 13:07:43 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:07:43 INFO - PROCESS | 1652 | 2017-01-19 13:07:43.288 plugin-container[1653:10210] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 13:07:43 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:07:43 INFO - PROCESS | 1652 | 2017-01-19 13:07:43.288 plugin-container[1653:10210] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:07:43 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 13:07:43 INFO - PROCESS | 1652 | colorDepth:24 13:07:43 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 13:07:43 INFO - PROCESS | 1652 | __metrics 13:07:43 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 13:07:44 INFO - TEST-INFO | 1652: exit 0 13:07:44 INFO - Browser initialized. 13:07:44 INFO - Running cycle 1/1 for kraken test... 13:07:44 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp7Dbz7m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:46 INFO - PROCESS | 1654 | 2017-01-19 13:07:46.664 plugin-container[1655:10329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 13:07:46 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:07:46 INFO - PROCESS | 1654 | 2017-01-19 13:07:46.665 plugin-container[1655:10329] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 13:07:46 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:07:46 INFO - PROCESS | 1654 | 2017-01-19 13:07:46.665 plugin-container[1655:10329] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:07:56 INFO - PROCESS | 1654 | RSS: Main: 232099840 13:07:56 INFO - PROCESS | 1654 | 13:07:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection) 13:08:00 INFO - PROCESS | 1654 | RSS: Main: 245956608 13:08:00 INFO - PROCESS | 1654 | 13:08:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-dft) 13:08:03 INFO - PROCESS | 1654 | RSS: Main: 253517824 13:08:03 INFO - PROCESS | 1654 | 13:08:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-fft) 13:08:07 INFO - PROCESS | 1654 | RSS: Main: 256143360 13:08:07 INFO - PROCESS | 1654 | 13:08:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator) 13:08:10 INFO - PROCESS | 1654 | RSS: Main: 257261568 13:08:10 INFO - PROCESS | 1654 | 13:08:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:08:12 INFO - PROCESS | 1654 | RSS: Main: 257687552 13:08:12 INFO - PROCESS | 1654 | 13:08:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom) 13:08:15 INFO - PROCESS | 1654 | RSS: Main: 263098368 13:08:15 INFO - PROCESS | 1654 | 13:08:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate) 13:08:19 INFO - PROCESS | 1654 | RSS: Main: 262299648 13:08:19 INFO - PROCESS | 1654 | 13:08:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial) 13:08:23 INFO - PROCESS | 1654 | RSS: Main: 262434816 13:08:23 INFO - PROCESS | 1654 | 13:08:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:08:25 INFO - PROCESS | 1654 | RSS: Main: 262905856 13:08:25 INFO - PROCESS | 1654 | 13:08:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:08:28 INFO - PROCESS | 1654 | RSS: Main: 262983680 13:08:28 INFO - PROCESS | 1654 | 13:08:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:08:31 INFO - PROCESS | 1654 | RSS: Main: 263081984 13:08:31 INFO - PROCESS | 1654 | 13:08:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:08:34 INFO - PROCESS | 1654 | RSS: Main: 263352320 13:08:34 INFO - PROCESS | 1654 | 13:08:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:08:38 INFO - PROCESS | 1654 | RSS: Main: 263462912 13:08:38 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49254/tests/kraken/driver.html?testName=ai-astar) 13:08:40 INFO - PROCESS | 1654 | RSS: Main: 265203712 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | __start_tp_report 13:08:40 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 13:08:40 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 13:08:40 INFO - PROCESS | 1654 | |i|pagename|runs| 13:08:40 INFO - PROCESS | 1654 | |0;ai-astar;115;117;117;167;173;162;117;114;114;118 13:08:40 INFO - PROCESS | 1654 | |1;audio-beat-detection;141;139;96;178;146;114;140;143;145;115 13:08:40 INFO - PROCESS | 1654 | |2;audio-dft;194;211;210;209;197;195;198;216;204;272 13:08:40 INFO - PROCESS | 1654 | |3;audio-fft;84;50;75;109;87;81;83;76;71;60 13:08:40 INFO - PROCESS | 1654 | |4;audio-oscillator;87;71;85;74;69;69;80;67;72;83 13:08:40 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;87;89;87;91;86;104;89;89;86 13:08:40 INFO - PROCESS | 1654 | |6;imaging-darkroom;100;101;99;134;120;172;171;99;101;120 13:08:40 INFO - PROCESS | 1654 | |7;imaging-desaturate;116;148;111;94;94;95;94;100;94;96 13:08:40 INFO - PROCESS | 1654 | |8;json-parse-financial;58;58;58;59;60;59;59;57;58;59 13:08:40 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;70;71;74;74;69;82;67;76;100;103 13:08:40 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;88;91;108;111;86;88;97;81;93;109 13:08:40 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;129;145;152;146;157;140;140;142;187;145 13:08:40 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;176;177;182;175;235;236;177;178;176;175 13:08:40 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;70;68;74;64;90;88;88;86;90 13:08:40 INFO - PROCESS | 1654 | __end_tp_report 13:08:40 INFO - PROCESS | 1654 | __start_cc_report 13:08:40 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,905 13:08:40 INFO - PROCESS | 1654 | __end_cc_report 13:08:40 INFO - PROCESS | 1654 | __startTimestamp1484860120528__endTimestamp 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | ------- Summary: start ------- 13:08:40 INFO - PROCESS | 1654 | Number of tests: 14 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:131.40 Median:117.50 stddev:24.97 (21.2%) stddev-sans-first:25.77 13:08:40 INFO - PROCESS | 1654 | Values: 115.0 117.0 117.0 167.0 173.0 162.0 117.0 114.0 114.0 118.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:144.00 stddev:22.53 (15.6%) stddev-sans-first:23.81 13:08:40 INFO - PROCESS | 1654 | Values: 141.0 139.0 96.0 178.0 146.0 114.0 140.0 143.0 145.0 115.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:210.60 Median:209.50 stddev:22.87 (10.9%) stddev-sans-first:23.46 13:08:40 INFO - PROCESS | 1654 | Values: 194.0 211.0 210.0 209.0 197.0 195.0 198.0 216.0 204.0 272.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:77.60 Median:78.50 stddev:15.92 (20.3%) stddev-sans-first:16.71 13:08:40 INFO - PROCESS | 1654 | Values: 84.0 50.0 75.0 109.0 87.0 81.0 83.0 76.0 71.0 60.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:77.00 stddev:7.38 (9.6%) stddev-sans-first:6.60 13:08:40 INFO - PROCESS | 1654 | Values: 87.0 71.0 85.0 74.0 69.0 69.0 80.0 67.0 72.0 83.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.00 stddev:5.27 (5.9%) stddev-sans-first:5.59 13:08:40 INFO - PROCESS | 1654 | Values: 90.0 87.0 89.0 87.0 91.0 86.0 104.0 89.0 89.0 86.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:121.70 Median:152.50 stddev:28.82 (18.9%) stddev-sans-first:29.48 13:08:40 INFO - PROCESS | 1654 | Values: 100.0 101.0 99.0 134.0 120.0 172.0 171.0 99.0 101.0 120.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:94.00 stddev:17.29 (18.4%) stddev-sans-first:17.80 13:08:40 INFO - PROCESS | 1654 | Values: 116.0 148.0 111.0 94.0 94.0 95.0 94.0 100.0 94.0 96.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:59.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 13:08:40 INFO - PROCESS | 1654 | Values: 58.0 58.0 58.0 59.0 60.0 59.0 59.0 57.0 58.0 59.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:78.60 Median:72.50 stddev:12.79 (17.6%) stddev-sans-first:13.18 13:08:40 INFO - PROCESS | 1654 | Values: 70.0 71.0 74.0 74.0 69.0 82.0 67.0 76.0 100.0 103.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:88.00 stddev:10.64 (12.1%) stddev-sans-first:10.97 13:08:40 INFO - PROCESS | 1654 | Values: 88.0 91.0 108.0 111.0 86.0 88.0 97.0 81.0 93.0 109.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:145.50 stddev:15.51 (10.7%) stddev-sans-first:14.79 13:08:40 INFO - PROCESS | 1654 | Values: 129.0 145.0 152.0 146.0 157.0 140.0 140.0 142.0 187.0 145.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:177.50 stddev:24.75 (13.9%) stddev-sans-first:25.82 13:08:40 INFO - PROCESS | 1654 | Values: 176.0 177.0 182.0 175.0 235.0 236.0 177.0 178.0 176.0 175.0 13:08:40 INFO - PROCESS | 1654 | 13:08:40 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:87.00 stddev:10.78 (12.4%) stddev-sans-first:10.60 13:08:40 INFO - PROCESS | 1654 | Values: 67.0 70.0 68.0 74.0 64.0 90.0 88.0 88.0 86.0 90.0 13:08:40 INFO - PROCESS | 1654 | -------- Summary: end -------- 13:08:40 INFO - PROCESS | 1654 | 13:08:41 INFO - TEST-INFO | 1654: exit 0 13:08:41 INFO - TEST-OK | kraken | took 60679ms 13:08:41 INFO - SUITE-END | took 1177s 13:08:41 INFO - Completed test suite (00:19:37) 13:08:41 INFO - javascript benchmark 13:08:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7167.809412470598, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3258.3646188993125, 3288.440965035944, 3317.5189597508347, 3331.217163966469, 3341.028793012122, 5436.184572314145, 5457.699826509541, 5476.835694929148, 5498.835034948953, 5556.361018482876, 5480.342358916925, 5497.752601132948, 5534.667919924807, 5554.972225138868, 5603.859903502414, 4256.318988961767, 4409.258342954531, 5033.169527028036, 5035.974820125894, 5171.482851714836, 4969.652124351297, 5172.560332371734, 5183.922241166386, 5198.064348417283, 5200.6099542534275, 5050.949490505084, 5135.35808023997, 5144.536991670749, 5185.870353241162, 5190.351206099238, 3858.247641709868, 5014.623903207274, 5050.217216331474, 5070.847874563769, 5090.516400941905, 4463.4420697412825, 5064.392272927273, 5090.414602320743, 5115.258287548322, 5118.692878427269, 4441.800118994653, 5433.157860531624, 5531.0, 5550.888982220374, 5573.554115670737, 5957.434043765836, 5980.760769569212, 6072.119542666289, 6115.724792384309, 6128.938710612882, 5085.542301192911, 6081.1790408294755, 6106.642687876637, 6136.815895523141, 6138.07928810682, 5679.630823996468, 5680.602357834994, 5702.486776190163, 5713.228714123623, 5732.914006289909, 6124.35694252106, 6221.688915554204, 6227.097070924781, 6265.154204182468, 6282.528810339243, 5284.9735751320995, 5828.795992140254, 5843.503302219359, 5880.588358814842, 6028.517718582503, 5745.913811292834, 6014.188084608521, 6043.939560604425, 6068.393160683896, 6105.26736791579, 5950.642961422329, 5987.43119403656, 6015.578909476382, 6038.849028774316, 6073.787417440457, 6113.932424433873, 6115.327313995457, 6131.407250743203, 6132.722655450988, 6142.191841058483, 4191.140816132685, 4874.488178741252, 4885.145099607555, 4893.143699852582, 4920.237363208708, 4263.552327005682, 4275.0, 4276.230278549829, 4294.098239369705, 4324.286492728665, 4723.0, 5381.4349493303425, 5642.26650535436, 5650.689212093374, 5696.914546281809, 6149.7847575334645, 6150.630962142196, 6161.599496032743, 6191.164192834, 6208.627482351073, 5532.6680399176175, 5557.249771280906, 5567.832965011056, 5571.303587051619, 5627.971860140673, 5272.499112584298, 5309.681419114865, 5310.495502927216, 5315.28243687105, 5380.515753582196, 6240.812775616739, 6260.499160067184, 6264.968675156595, 6282.026285925689, 6308.684565771693, 6004.0693692477735, 6128.0, 6140.938590614037, 6149.20060392164, 6172.814815555541, 6172.753089876354, 6173.59871608344, 6175.228096487939, 6201.193844800147, 6231.750729970931, 6088.543359247986, 6095.329513753572, 6102.4507794298725, 6105.389461053859, 6108.205933228652, 5825.70871456417, 6107.84730381744, 6125.387461253839, 6146.323904370605, 6189.752409903554, 5784.2769653793275, 5853.560982926212, 5878.470937615635, 5902.203202567598, 6005.549583781321, 5855.151003104611, 5859.150423188699, 5874.17761513397, 5953.821385358447, 5963.880722385441, 4989.55094041538, 5709.65742055478, 5754.309482862083, 5770.105633626795, 5817.738201780845, 5906.143609176731, 6015.39846015395, 6035.39646035393, 6038.818835434944, 6071.817845464643, 5648.604597678123, 5697.743601537858, 5712.0860662293835, 5793.710314484343, 5848.766049357978], "value": 5524.021690185713, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.2253997094837, 482.7079616831817, 494.9158643030684, 512.4312013664834, 518.1605798606258, 40944.18111637765, 42838.0, 42967.57032429675, 42986.355204671956, 42995.0, 43406.565934340644, 44251.33622995658, 44296.55703442964, 44305.556944430544, 44405.11189776203, 8424.705135320264, 8634.0, 9185.173334399919, 9533.14201721845, 9540.475273859935, 11271.041961433288, 11537.192396532246, 11572.826407603892, 11705.239159454652, 11758.353290569015, 11509.884901151006, 11558.942205288962, 11584.47869845859, 11589.36258505782, 11601.245919015279, 11404.54381824726, 11472.082233421348, 11495.655130346104, 11504.539818407253, 11532.942335288353, 11212.04697600705, 11219.495122719498, 11242.437878106144, 11302.773944521146, 11305.943470282597, 41380.0, 41405.0, 41479.37780933289, 41495.37756933648, 41619.95950101242, 3768.8869333920024, 4046.5548789633117, 4074.8370065197355, 4077.1030373318117, 4149.190907772985, 29009.56485652717, 29684.40631187364, 29689.851550742325, 29709.70290297091, 29722.81108755647, 2875.7555607773365, 3150.4486714824816, 3293.6541663125504, 3324.983375083133, 3355.0438125134465, 394386.0, 481171.0, 489892.0, 492904.0, 493148.0, 56307.43692563104, 56564.717176413855, 56610.71694641501, 56695.0, 56757.432425675215, 59782.0, 59980.40019599835, 60321.396786032456, 60424.697876510334, 60538.78922421527, 14181.858181418054, 14363.563643635553, 15545.689086218212, 15756.527304180892, 15769.842301576837, 10953.726156846142, 11174.66476005721, 11927.58253461142, 12081.697957550956, 12201.938990304992, 17190.484285471455, 17262.050587217822, 17323.56691082733, 17419.99670037962, 17550.29798808059, 7547.50941188821, 7681.615919204018, 7707.730229441942, 7709.922900771033, 7714.845703085907, 754.3324158120104, 856.3406177243488, 856.6016802187013, 866.9769671787226, 873.4890089297705, 30503.0, 30605.846970765004, 31086.844565777028, 31525.369492610018, 31684.841575791972, 878.692457639821, 1069.983950240747, 1102.3000394749392, 1102.404701461202, 1247.9766591394969, 15490.767638485431, 16007.439739609057, 16011.119388433748, 16013.439529616408, 16358.509244722678, 230357.0, 231619.0, 231836.0, 232756.0, 232906.0, 62585.68707156526, 63292.0, 63342.36657633457, 63453.365466344745, 63578.0, 1279.4242590834162, 1414.3988804757937, 1438.4102517967583, 1452.157748505864, 1464.8901332400112, 28734.0, 29499.557506637848, 29545.0, 30286.091417257518, 30514.26872770564, 53647.463525365805, 53647.73176134095, 53965.190522142984, 54112.0, 54116.0, 458655.0, 538395.0, 539654.3017284889, 540593.0, 542749.0, 25318.113866015337, 26054.478910421305, 26921.326966825985, 27346.0, 27371.0, 42252.0, 42831.35752963646, 43173.92065198395, 43402.0, 43442.78278608587, 36697.44953825748, 36790.0, 37669.0, 37739.47126344856, 38154.80922595369, 30589.847050764605, 34797.30405391929, 36438.63561364458, 36510.0, 37834.62165378311, 36104.458433123, 36307.27385452329, 36355.63644363522, 36449.45325820168, 37111.81444092762, 31901.521477178325, 33007.04052926361, 33058.0, 33174.83412582921, 33626.82306119274, 21177.89411052935, 23210.839458026694, 24619.753802461746, 24814.0, 25243.49513009693], "value": 19533.927327344885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5870630366051, 382.1058722589142, 390.2468236303933, 392.9586595521869, 415.44745488500297, 15193.316300766464, 15311.387544498235, 15476.458323958665, 20559.486012849688, 20870.478238044056, 17834.910825445855, 21325.68011479829, 21499.46251343717, 21668.133274668995, 21723.67414488784, 5579.776808927638, 5670.489655930955, 5795.1886735857015, 5875.383084776101, 5914.4381283777975, 5694.288213973254, 5840.445157710011, 5848.561357898154, 5849.2980842298775, 5852.356240813507, 5655.236543066674, 5948.375420580842, 5973.044312909935, 6070.757169713206, 6075.149479072933, 5346.117890548055, 5784.045632470637, 5959.314678811948, 5963.970180149093, 6040.818775436722, 5874.353821079678, 5879.235699359098, 5881.294244690622, 5886.8822623547285, 5901.262342207224, 13515.0, 17770.9337439754, 18080.83030036391, 24271.271861844172, 26420.47159056827, 2212.513247085639, 2613.4642398308492, 2658.86705664716, 2675.0, 2688.5429476988957, 9960.0, 9965.40207587547, 10091.949540252324, 10406.843897341547, 10414.895851041545, 1584.8415158484174, 1779.661864245789, 1849.4821449994024, 1862.3016368861677, 1866.5333666583354, 108469.0, 135121.0, 139202.30398848042, 139804.60195398016, 140475.0, 10572.15422766177, 11309.321440713584, 11831.17181797266, 12036.63890083299, 12196.0, 13477.730445391036, 15547.067175969478, 15685.843141568439, 15689.843101569066, 15707.528774136794, 8047.436679432501, 8528.701495447754, 8572.742817715478, 8687.565621718888, 8816.603252853636, 8361.498310101413, 8372.162783721578, 8390.79023024417, 8514.148585141436, 8547.658093676308, 3951.6838652908273, 4793.304970779287, 4793.880152996133, 4796.784144713496, 4889.0, 1663.609051872809, 1794.2105473591578, 1987.3739771971782, 2045.9181632734524, 2047.2015913793632, 890.844102282098, 984.3601658921758, 1020.6631811502186, 1030.175859312562, 1062.2670357453333, 15932.0, 20472.69290960637, 20552.944705529328, 20634.896825515778, 23102.191423300104, 959.9808003840024, 962.821877952581, 983.6508039645822, 991.2466525440717, 1003.9397636141855, 6164.845878853064, 6178.762749689154, 6243.188385509855, 6272.529560282997, 6377.808665740035, 707213.0, 707954.0, 710248.4487577529, 746071.0, 750422.0, 112676.43661781639, 135938.0, 140803.0, 144459.0, 145039.0, 1042.9426381548935, 1049.8687664041995, 1073.3774410841693, 1073.3881687438086, 1078.3368228539548, 11315.98156165949, 11412.429378531206, 11512.079033677119, 11534.423278836193, 11594.76810463803, 52827.73586132045, 54047.18929215986, 54882.90234195214, 55045.724771375884, 55434.722826387224, 81500.0, 81666.18333816585, 81685.59157204175, 81835.7724634143, 82063.0, 7921.287084162453, 8150.614395552889, 8318.792030199293, 8382.664693412195, 8545.871811922701, 9243.537823108683, 10526.263161578616, 11979.0, 12656.557020504239, 12911.35443227816, 10553.577856885639, 10890.128789696964, 10904.964028417287, 11107.777844442904, 11368.658940231806, 6860.416864566568, 9230.261579073793, 11036.668899933014, 11069.944650276697, 11412.657620271406, 10828.187885908746, 11376.715582110513, 11474.713132171762, 11650.359230242419, 11692.883071169179, 7822.06135263772, 7975.960120199362, 7997.400194985516, 8065.83868322642, 8177.468464550023, 10822.458877056273, 11482.655520334403, 11502.194846360675, 11581.652550423501, 11600.825987610024], "value": 9371.048704248764, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.4666133546575, 1454.134789800069, 1469.1111877314218, 1490.3814916809524, 1492.8357880633127, 7543.698252069924, 7783.338416234613, 7980.241877021681, 8382.916170838298, 8481.533515656643, 7985.560794156319, 8271.751847444571, 8291.502509849399, 8404.03353614333, 8441.873371899412, 2261.1633695532637, 2287.6797248385237, 2309.503456756797, 2317.617593097137, 2326.895289711967, 2298.528801595669, 2319.1419174905222, 2322.129201549419, 2337.333859849943, 2341.098677009347, 2276.6698828669832, 2277.9088836446604, 2304.8962796674105, 2310.0297874892503, 2316.3745788637057, 1904.3430016644236, 1944.2028768204968, 1950.8536859735505, 2275.8520696154696, 2296.3007374542885, 2266.172846910882, 2317.7102862142233, 2326.674265602817, 2328.068772491017, 2331.778481044298, 8334.708285209988, 8339.916600834036, 8432.114627964036, 8435.578221088921, 8443.1134730853, 4603.401557797465, 5032.094223039888, 5069.059945231794, 5190.532852043297, 5191.273221748958, 3264.8041117533025, 3342.4317865962844, 3465.861365545375, 3524.18943642961, 3616.4213725803747, 1213.9332336721477, 1215.4166000319897, 1225.4669218889812, 1248.5505218121468, 1268.7439434959324, 10694.0, 10802.72993175169, 10805.02754752076, 10809.67570972872, 10867.347959122479, 4397.494288156839, 4653.767311634405, 4707.0, 4724.448797649231, 4745.549172828576, 7936.642851071716, 9454.810903782023, 9455.101765332283, 9477.668281610111, 9602.183814375716, 5204.9219261711105, 5223.347081614798, 5233.215017747369, 5237.9476205238225, 5245.029669511153, 4180.482769968717, 4624.768761561909, 4701.670883038223, 4755.500672429414, 4810.7113573185725, 3740.981295093507, 3821.522309711286, 3865.7100717446306, 3876.8643097492018, 3900.6294402031763, 1273.0268084469035, 1282.3139620303093, 1284.3963337231487, 1287.3691890973544, 1294.766941950458, 791.9643616037176, 828.4118276024091, 898.9640414383482, 903.823754367892, 915.2312057871287, 5155.510226528474, 5299.49654782795, 5421.484958928895, 5659.971700141556, 5841.153032810219, 748.6780684305692, 924.930630202724, 989.2975987049275, 991.8214721350082, 998.236349192868, 3112.4242015227255, 3651.2697460508007, 3707.888763337104, 3779.622037796253, 3838.9040273993373, 8302.958485207535, 9323.0, 9695.51522423876, 9992.950035249778, 10059.094681478702, 8847.557622118868, 9426.491761318157, 9458.57436415363, 9845.50772461374, 9943.303968722264, 6645.235797883209, 7419.962900185573, 7947.403944704055, 7965.322947549524, 7971.760847174594, 4692.765361731968, 4761.976190119027, 5659.151127330933, 5700.429957004267, 5959.91060134107, 8874.600642970952, 8962.058983806866, 9094.727158185266, 9107.043760405062, 9338.859917101114, 9117.817643647222, 9290.72127836166, 9305.720828375159, 9318.161365476868, 9452.763680908032, 6149.938500614937, 6529.085927969993, 6600.768973085919, 7110.715571377087, 7244.963775181091, 7807.921920780719, 7908.84182316362, 7924.049114106344, 7927.207279272026, 7935.722249721232, 6996.265392133752, 7065.788026359218, 7103.25415831351, 7191.8561628768175, 7244.348008679244, 6880.380765731108, 6955.756548520778, 6978.965105174441, 7056.188538317954, 7082.929170708433, 6349.936500635119, 6354.205724284465, 6438.195225596801, 6456.644884531396, 6485.805425837232, 4301.419308393327, 4911.877203069952, 4961.7271050092595, 4968.726720030289, 5066.518680725325, 4887.853364399074, 4933.827316044065, 4975.452700203047, 4993.27597498368, 5056.292119103255], "value": 4368.5854782913675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.92199106241316, 299.580587177951, 359.42312588295783, 359.82008995502247, 360.94044482660354, 4490.057088011514, 4590.357349971006, 5001.274815151805, 5369.624126311155, 5483.972580137099, 4419.646428285738, 4816.590589799871, 5325.0, 5394.676319420838, 5447.645903016301, 3638.5633723953165, 3833.8466461341513, 3834.94247586285, 4133.979330103361, 4164.083901541656, 4158.875233742978, 4193.833655590025, 4222.598853108965, 4223.387608796723, 4231.4710661167355, 3567.1973805893726, 4151.294279972412, 4204.474440694913, 4230.2174097792, 4238.830446782125, 3785.242951409729, 4055.8174882130374, 4071.633552980246, 4214.515330736958, 4223.36649502574, 2816.8309901405983, 3136.5765621641012, 3370.427027405347, 3501.404761190578, 3531.5762108546883, 4086.346184610448, 4405.493368262627, 4871.756412179377, 4898.681585696918, 5137.306463627438, 4068.410080538335, 4272.807723652443, 4502.977485112586, 4558.9772051139535, 4668.533146685339, 4658.697184682985, 5018.723970181639, 5446.91829622556, 5649.435056494359, 5679.586175875236, 3865.5361356637104, 3882.301185786557, 3894.591067937854, 3897.4153876918404, 3899.4345819856244, 5636.464535869045, 6002.339742628307, 6014.879702405971, 6029.849253768647, 6063.9090413643835, 3714.795686237283, 3920.4903362562686, 3948.368261078214, 3966.643002129822, 3972.3445631470486, 3579.910502237413, 3810.942835857464, 3993.5407428145554, 4045.0, 4052.6555242804693, 4310.900720316362, 4376.562343765661, 4449.775112443778, 4684.906301873943, 4691.601213896789, 5486.890262194734, 5682.573806964494, 5744.253247077853, 5763.798267060716, 5803.100519419497, 1221.4808706299787, 1294.4627979388636, 1295.838020247469, 1297.8183054372396, 1298.7597294500547, 769.5498133591911, 783.4995982693192, 868.8479516084786, 877.7410663854179, 883.5008220355479, 4874.926876096862, 5126.8974620508125, 5249.766304918341, 5271.78912843482, 5310.468953104736, 4614.861554153381, 5080.314157588752, 5109.540141387293, 5132.486751324912, 5845.298564172326, 4499.257622492318, 4675.509071547439, 4755.0489902019735, 4910.656254062182, 5286.9471305287225, 2534.657821194152, 2567.8202525823012, 2969.5248760198697, 2975.702429757007, 3001.864916078782, 5717.283241325923, 5785.768569257267, 5936.881262374728, 5950.67271300074, 5974.3726908674835, 4490.550944905483, 5398.64908780928, 5458.344998600193, 5648.717564121777, 5867.501262392659, 4929.482404347492, 5099.847004589868, 5220.608454365938, 5928.4960778334325, 6004.937481884271, 5245.9475405246985, 5592.665684094859, 5614.887702246014, 5623.578231632562, 5640.630935007389, 5289.68261904287, 5348.946510535001, 5522.978249023943, 5764.19301297827, 5838.883222335615, 4979.0, 5655.830325090254, 5747.166660834072, 6155.722992465439, 6179.907301390393, 3655.2872189923773, 3681.8895433135976, 3957.228340473695, 3981.601839816111, 3999.560048394616, 4758.547937945889, 5825.0, 5859.677717725566, 5868.501177399798, 5898.911516327173, 4821.421429428491, 4977.631151433385, 5354.064762399556, 5461.726913654381, 5729.28383952006, 4624.953750462587, 4976.502349765139, 5255.948810238044, 5480.0, 5640.830775076754, 5324.334458192726, 5409.323834520685, 5545.140503222007, 5566.944330556643, 5570.384588469298, 5423.83728488146, 5462.535684466865, 5626.606137570331, 5698.544116470755, 5722.740996980658, 5498.477644623755, 5534.08687566547, 5537.390887002346, 5537.846615038721, 5565.55475561946], "value": 4070.903835245717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.7804658602417, 776.3013288040762, 811.2212276214833, 845.2772879188294, 848.6944699908034, 15243.161626110574, 15838.0, 15839.84160158401, 15875.920620396912, 15954.68090638188, 14485.130892146479, 14567.635809104777, 14579.854201457982, 14616.853831461682, 14682.63293417665, 676.1818199978032, 691.495208497797, 844.4933040175875, 844.6452489954203, 856.7386946981168, 870.7344260000729, 885.1590988560861, 886.3219636977685, 886.4902680958443, 886.8935727712651, 919.1497864475367, 929.5816882402912, 931.4271722890442, 933.5005771912029, 934.1405906565942, 923.0400383601046, 923.0769230769231, 923.7967647117657, 926.3839546701436, 928.0533855467386, 687.6252442418894, 688.8243497908052, 690.3234829866759, 690.6443181761397, 691.3017851969497, 13105.069083034361, 13636.181829090187, 15260.542183734506, 15327.846721532753, 15815.367385304575, 14465.493707720285, 14576.708465830625, 14763.040402373812, 14787.778183327258, 14798.40806367744, 11839.230450020807, 11888.227265227819, 11901.464434100486, 12002.21985570935, 12012.279263244145, 3668.0646435158865, 3693.316736403774, 3721.3301605710963, 3721.7394782364972, 3853.0, 101833.4908325461, 104089.42748015284, 104460.0, 105736.47131764292, 105980.47009764978, 11336.206465547497, 11381.317120972768, 11476.368799716096, 11476.4835582399, 11594.362310072858, 21475.248366307103, 22142.45720684715, 23817.0, 23914.923828427764, 23982.52034959326, 7845.882311765328, 7923.16806735296, 7973.72091976778, 8098.109207987117, 8211.137830527829, 3584.4802503636824, 4217.98768295613, 4369.934450983238, 4413.16149931512, 4485.663575231871, 3529.735269854771, 3699.112213068844, 3732.8880133596035, 3753.774773513598, 3758.1732018955786, 663.598522893652, 678.1760161403918, 681.431005110738, 689.5931400473745, 691.7717153339386, 665.215046245435, 692.2281655953575, 699.6816448515913, 703.5040296590994, 706.4842664854685, 10867.78264434707, 11011.889881101246, 11026.173037022256, 11193.49629266685, 11403.0, 570.5777724483852, 579.8027074091987, 611.4527497889453, 614.5114633866087, 714.2678754276908, 4389.956100439019, 4816.0, 4986.975065124724, 5032.823851165192, 5066.113430149709, 80160.78552930264, 80307.0, 91623.0, 93220.53389733144, 95504.0, 70346.0, 73330.0, 81472.77790833142, 83105.16894831095, 85441.14558854332, 65033.0, 65687.67156164188, 66895.0, 74516.88224676634, 76086.0, 10243.897561024294, 11049.83425248638, 11180.273282236629, 11262.068452011681, 11419.143564232882, 45819.0, 47138.76430617825, 47161.0, 47245.527544724115, 48762.756186218845, 61972.69013654903, 65563.67218163879, 66751.66624166848, 67180.6640966792, 68296.6585167071, 10083.848742269018, 12345.234703415545, 12804.871951280367, 13142.540011099565, 13562.457501699822, 13196.0, 14896.584824441661, 14950.551483455514, 15227.847721522643, 15351.0, 11372.461282223514, 14040.859591404364, 14301.785473218119, 14404.42382304696, 14821.481248156262, 14041.859581404055, 14060.156390616596, 14308.496013197857, 14407.279636018367, 14426.350814213176, 13800.654983625489, 13824.861751382357, 13999.510017149758, 14188.077774944595, 14265.144091354552, 10134.733158355206, 11071.225014249247, 11137.434873521694, 11255.380954047607, 11526.0, 9472.857907131536, 9751.0, 11235.831462528233, 11311.0, 11365.31808091517], "value": 7540.611056156332, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 117.0, 167.0, 173.0, 162.0, 117.0, 114.0, 114.0, 118.0], "value": 131.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 96.0, 178.0, 146.0, 114.0, 140.0, 143.0, 145.0, 115.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 211.0, 210.0, 209.0, 197.0, 195.0, 198.0, 216.0, 204.0, 272.0], "value": 210.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 50.0, 75.0, 109.0, 87.0, 81.0, 83.0, 76.0, 71.0, 60.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 85.0, 74.0, 69.0, 69.0, 80.0, 67.0, 72.0, 83.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 91.0, 86.0, 104.0, 89.0, 89.0, 86.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 99.0, 134.0, 120.0, 172.0, 171.0, 99.0, 101.0, 120.0], "value": 121.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 148.0, 111.0, 94.0, 94.0, 95.0, 94.0, 100.0, 94.0, 96.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 60.0, 59.0, 59.0, 57.0, 58.0, 59.0], "value": 58.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 74.0, 74.0, 69.0, 82.0, 67.0, 76.0, 100.0, 103.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 108.0, 111.0, 86.0, 88.0, 97.0, 81.0, 93.0, 109.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 145.0, 152.0, 146.0, 157.0, 140.0, 140.0, 142.0, 187.0, 145.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 182.0, 175.0, 235.0, 236.0, 177.0, 178.0, 176.0, 175.0], "value": 188.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 74.0, 64.0, 90.0, 88.0, 88.0, 86.0, 90.0], "value": 78.5, "unit": "score"}]}]} 13:08:41 INFO - Return code: 0 13:08:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:08:41 ERROR - # TBPL SUCCESS # 13:08:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:08:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:08:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:08:41 INFO - 2017-01-19 13:08:41.829 screenresolution[1659:10718] starting screenresolution argv=screenresolution get 13:08:41 INFO - 2017-01-19 13:08:41.832 screenresolution[1659:10718] Display 0: 1600x1200x32@60 13:08:41 INFO - 2017-01-19 13:08:41.838 screenresolution[1660:10721] starting screenresolution argv=screenresolution list 13:08:41 INFO - Available Modes on Display 0 13:08:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:08:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:08:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:08:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:08:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:08:42 INFO - Intel Iris: 13:08:42 INFO - Chipset Model: Intel Iris 13:08:42 INFO - Type: GPU 13:08:42 INFO - Bus: Built-In 13:08:42 INFO - VRAM (Dynamic, Max): 1536 MB 13:08:42 INFO - Vendor: Intel (0x8086) 13:08:42 INFO - Device ID: 0x0a2e 13:08:42 INFO - Revision ID: 0x0009 13:08:42 INFO - Displays: 13:08:42 INFO - Display: 13:08:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:08:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:08:42 INFO - Display Serial Number: 1600x1200 60 13:08:42 INFO - Main Display: Yes 13:08:42 INFO - Mirror: Off 13:08:42 INFO - Online: Yes 13:08:42 INFO - Rotation: Supported 13:08:42 INFO - Return code: 0 13:08:42 INFO - Running post-action listener: _resource_record_post_action 13:08:42 INFO - [mozharness: 2017-01-19 21:08:42.074186Z] Finished run-tests step (success) 13:08:42 INFO - Running post-run listener: _resource_record_post_run 13:08:42 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 37575168; Write bytes: 554577920; Read time: 388; Write time: 2073 13:08:42 INFO - TinderboxPrint: CPU usage
54.4% 13:08:42 INFO - TinderboxPrint: I/O read bytes / time
37,575,168 / 388 13:08:42 INFO - TinderboxPrint: I/O write bytes / time
554,577,920 / 2,073 13:08:42 INFO - TinderboxPrint: CPU idle
2,187.8 (45.6%) 13:08:42 INFO - TinderboxPrint: CPU system
968.6 (20.2%) 13:08:42 INFO - TinderboxPrint: CPU user
1,645.4 (34.3%) 13:08:42 INFO - TinderboxPrint: Swap in / out
288,051,200 / 0 13:08:42 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 252350976; Write bytes: 239329280; Read time: 17895; Write time: 426 13:08:42 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30208512; Write bytes: 301711360; Read time: 290; Write time: 1602 13:08:42 INFO - Running post-run listener: _upload_blobber_files 13:08:42 INFO - Blob upload gear active. 13:08:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:08:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:08:43 INFO - (blobuploader) - INFO - Open directory for files ... 13:08:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:08:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:08:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:44 INFO - (blobuploader) - INFO - Done attempting. 13:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:45 INFO - (blobuploader) - INFO - Done attempting. 13:08:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:08:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:08:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:46 INFO - (blobuploader) - INFO - Done attempting. 13:08:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:08:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:08:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:49 INFO - (blobuploader) - INFO - Done attempting. 13:08:49 INFO - (blobuploader) - INFO - Iteration through files over. 13:08:49 INFO - Return code: 0 13:08:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:08:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:08:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85a860492e00cf490c376a45b6428ce7d67a3c684d0c58bbdc0a2e819f96c181f78e238890b7ffcd8afb98cd11f1a38e1fba616c80fc6da6d477af6196f879d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05df6d75bcdee348a541e49b96642601d6fa44884f9a41a628d44ddb70819b22c186d1d8adb2ab9112eaaa363c1a144cb9c809bdbb20e7e4ca90617fc654d3a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b8de1e6c60276ebf0061f3cb22a624592ad7d2b70c03409f3231490644b4c1599d7b7e5a7e4404edb98c38196ff67ce7fd55c89f07fc654db7cf60d0ca9915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74ccbda1945d9f6289d09972d04745c36f048243c6732db8f7df92debdc0eda213999d3863dabc54a3eeff461afb0a3373b2441eda3020da10d99c89c0da9014"} 13:08:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:08:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:08:49 INFO - Contents: 13:08:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85a860492e00cf490c376a45b6428ce7d67a3c684d0c58bbdc0a2e819f96c181f78e238890b7ffcd8afb98cd11f1a38e1fba616c80fc6da6d477af6196f879d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05df6d75bcdee348a541e49b96642601d6fa44884f9a41a628d44ddb70819b22c186d1d8adb2ab9112eaaa363c1a144cb9c809bdbb20e7e4ca90617fc654d3a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b8de1e6c60276ebf0061f3cb22a624592ad7d2b70c03409f3231490644b4c1599d7b7e5a7e4404edb98c38196ff67ce7fd55c89f07fc654db7cf60d0ca9915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74ccbda1945d9f6289d09972d04745c36f048243c6732db8f7df92debdc0eda213999d3863dabc54a3eeff461afb0a3373b2441eda3020da10d99c89c0da9014"} 13:08:49 INFO - Running post-run listener: copy_logs_to_upload_dir 13:08:49 INFO - Copying logs to upload dir... 13:08:49 INFO - mkdir: /builds/slave/test/build/upload/logs 13:08:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.489833 ========= master_lag: 2.83 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-19 13:08:51.951183) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2017-01-19 13:08:51.954488) ========= 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.exiWpEuYPH/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.b62SuuuxuF/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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/85a860492e00cf490c376a45b6428ce7d67a3c684d0c58bbdc0a2e819f96c181f78e238890b7ffcd8afb98cd11f1a38e1fba616c80fc6da6d477af6196f879d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05df6d75bcdee348a541e49b96642601d6fa44884f9a41a628d44ddb70819b22c186d1d8adb2ab9112eaaa363c1a144cb9c809bdbb20e7e4ca90617fc654d3a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b8de1e6c60276ebf0061f3cb22a624592ad7d2b70c03409f3231490644b4c1599d7b7e5a7e4404edb98c38196ff67ce7fd55c89f07fc654db7cf60d0ca9915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74ccbda1945d9f6289d09972d04745c36f048243c6732db8f7df92debdc0eda213999d3863dabc54a3eeff461afb0a3373b2441eda3020da10d99c89c0da9014"} build_url:https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/I5vM5wREQx6CvNpKF5tLdg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85a860492e00cf490c376a45b6428ce7d67a3c684d0c58bbdc0a2e819f96c181f78e238890b7ffcd8afb98cd11f1a38e1fba616c80fc6da6d477af6196f879d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05df6d75bcdee348a541e49b96642601d6fa44884f9a41a628d44ddb70819b22c186d1d8adb2ab9112eaaa363c1a144cb9c809bdbb20e7e4ca90617fc654d3a0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2b8de1e6c60276ebf0061f3cb22a624592ad7d2b70c03409f3231490644b4c1599d7b7e5a7e4404edb98c38196ff67ce7fd55c89f07fc654db7cf60d0ca9915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74ccbda1945d9f6289d09972d04745c36f048243c6732db8f7df92debdc0eda213999d3863dabc54a3eeff461afb0a3373b2441eda3020da10d99c89c0da9014"}' ========= master_lag: 5.44 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2017-01-19 13:08:57.409221) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 13:08:57.409835) ========= 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.exiWpEuYPH/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.b62SuuuxuF/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005293 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 13:08:57.446401) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 13:08:57.447176) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 13:08:57.450043) ========= ========= Total master_lag: 8.38 =========