builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0074 starttime: 1484667039.08 results: success (0) buildid: 20170117065524 builduid: a840005bf62e4dda82dc989f8c6df3cd revision: 7a4f03169c77e4618312be942f7c5fa17f7bcb68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.079032) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.079497) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.095684) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.124188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.124760) ========= 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.xYFwPaudfR/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.sOutSvkFlV/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-17 07:30:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.6M=0.001s 2017-01-17 07:30:39 (13.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136232 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.285211) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.285554) ========= 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.xYFwPaudfR/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.sOutSvkFlV/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.036735 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:39.344788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-17 07:30:39.345177) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7a4f03169c77e4618312be942f7c5fa17f7bcb68 --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 7a4f03169c77e4618312be942f7c5fa17f7bcb68 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xYFwPaudfR/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.sOutSvkFlV/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-17 07:30:39,440 truncating revision to first 12 chars 2017-01-17 07:30:39,441 Setting DEBUG logging. 2017-01-17 07:30:39,441 attempt 1/10 2017-01-17 07:30:39,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7a4f03169c77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-17 07:30:40,329 unpacking tar archive at: mozilla-central-7a4f03169c77/testing/mozharness/ program finished with exit code 0 elapsedTime=1.165399 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-17 07:30:40.536192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:40.536734) ========= script_repo_revision: 7a4f03169c77e4618312be942f7c5fa17f7bcb68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:40.537307) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:40.537723) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 07:30:40.559253) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-17 07:30:40.559839) ========= /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.xYFwPaudfR/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.sOutSvkFlV/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:30:40 INFO - MultiFileLogger online at 20170117 07:30:40 in /builds/slave/test 07:30:40 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 07:30:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:40 INFO - {'append_to_log': False, 07:30:40 INFO - 'base_work_dir': '/builds/slave/test', 07:30:40 INFO - 'blob_upload_branch': 'Firefox', 07:30:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:40 INFO - 'branch': 'Firefox', 07:30:40 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:40 INFO - 'config_files': ('talos/mac_config.py',), 07:30:40 INFO - 'default_actions': ('clobber', 07:30:40 INFO - 'read-buildbot-config', 07:30:40 INFO - 'download-and-extract', 07:30:40 INFO - 'populate-webroot', 07:30:40 INFO - 'create-virtualenv', 07:30:40 INFO - 'install', 07:30:40 INFO - 'run-tests'), 07:30:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:40 INFO - 'download_minidump_stackwalk': True, 07:30:40 INFO - 'download_symbols': 'ondemand', 07:30:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:40 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:40 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:40 INFO - 'installer_path': 'installer.exe', 07:30:40 INFO - 'log_level': 'info', 07:30:40 INFO - 'log_name': 'talos', 07:30:40 INFO - 'log_to_console': True, 07:30:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:30:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:30:40 INFO - 'opt_config_files': (), 07:30:40 INFO - 'pip_index': False, 07:30:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:40 INFO - 'cmd': ('bash', 07:30:40 INFO - '-c', 07:30:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:40 INFO - 'enabled': True, 07:30:40 INFO - 'halt_on_failure': False, 07:30:40 INFO - 'name': 'check_screen_resolution'},), 07:30:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:40 INFO - 'cmd': ('bash', 07:30:40 INFO - '-c', 07:30:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:40 INFO - 'enabled': True, 07:30:40 INFO - 'halt_on_failure': False, 07:30:40 INFO - 'name': 'check_screen_resolution'},), 07:30:40 INFO - 'run_cmd_checks_enabled': True, 07:30:40 INFO - 'sps_profile': False, 07:30:40 INFO - 'sps_profile_interval': 0, 07:30:40 INFO - 'suite': 'dromaeojs-e10s', 07:30:40 INFO - 'system_bits': '32', 07:30:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:40 INFO - 'title': 't-yosemite-r7-0074', 07:30:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:40 INFO - 'use_talos_json': True, 07:30:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:40 INFO - 'work_dir': 'build'} 07:30:40 INFO - [mozharness: 2017-01-17 15:30:40.768990Z] Running clobber step. 07:30:40 INFO - Running pre-action listener: _resource_record_pre_action 07:30:40 INFO - Running main action method: clobber 07:30:40 INFO - rmtree: /builds/slave/test/build 07:30:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:30:43 INFO - Running post-action listener: _resource_record_post_action 07:30:43 INFO - [mozharness: 2017-01-17 15:30:43.659909Z] Finished clobber step (success) 07:30:43 INFO - [mozharness: 2017-01-17 15:30:43.660153Z] Running read-buildbot-config step. 07:30:43 INFO - Running pre-action listener: _resource_record_pre_action 07:30:43 INFO - Running main action method: read_buildbot_config 07:30:43 INFO - Using buildbot properties: 07:30:43 INFO - { 07:30:43 INFO - "project": "", 07:30:43 INFO - "product": "firefox", 07:30:43 INFO - "script_repo_revision": "production", 07:30:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:30:43 INFO - "repository": "", 07:30:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:30:43 INFO - "buildid": "20170117065524", 07:30:43 INFO - "pgo_build": "False", 07:30:43 INFO - "basedir": "/builds/slave/test", 07:30:43 INFO - "buildnumber": 40, 07:30:43 INFO - "slavename": "t-yosemite-r7-0074", 07:30:43 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:30:43 INFO - "platform": "yosemite_r7", 07:30:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:30:43 INFO - "branch": "mozilla-central", 07:30:43 INFO - "repo_path": "mozilla-central", 07:30:43 INFO - "revision": "7a4f03169c77e4618312be942f7c5fa17f7bcb68", 07:30:43 INFO - "stage_platform": "macosx64", 07:30:43 INFO - "builduid": "a840005bf62e4dda82dc989f8c6df3cd", 07:30:43 INFO - "slavebuilddir": "test" 07:30:43 INFO - } 07:30:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:30:43 INFO - Running post-action listener: _resource_record_post_action 07:30:43 INFO - [mozharness: 2017-01-17 15:30:43.665989Z] Finished read-buildbot-config step (success) 07:30:43 INFO - [mozharness: 2017-01-17 15:30:43.666212Z] Running download-and-extract step. 07:30:43 INFO - Running pre-action listener: _resource_record_pre_action 07:30:43 INFO - Running main action method: download_and_extract 07:30:43 INFO - mkdir: /builds/slave/test/build/tests 07:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:43 INFO - trying https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:30:43 INFO - Downloading https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/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 07:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/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 07:30:44 INFO - Downloaded 1199 bytes. 07:30:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:30:44 INFO - Using the following test package requirements: 07:30:44 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:30:44 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:30:44 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:30:44 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'jsshell-mac.zip'], 07:30:44 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:30:44 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:30:44 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:30:44 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:30:44 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:30:44 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:30:44 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:30:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:30:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:30:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:30:44 INFO - Fetch https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:30:45 INFO - Expected file size: 16326588 07:30:45 INFO - Obtained file size: 16326588 07:30:52 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 07:30:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:30:53 INFO - Expected file size: 16326588 07:30:53 INFO - Obtained file size: 16326588 07:30:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:30:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:30:59 INFO - Fetch https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:31:01 INFO - Expected file size: 14403463 07:31:01 INFO - Obtained file size: 14403463 07:31:01 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')]} 07:31:01 INFO - trying https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:31:01 INFO - Downloading https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/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 07:31:04 INFO - Downloaded 70113210 bytes. 07:31:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:31:04 INFO - mkdir: /builds/slave/test/properties 07:31:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:31:04 INFO - Writing to file /builds/slave/test/properties/build_url 07:31:04 INFO - Contents: 07:31:04 INFO - build_url:https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:31:05 INFO - Running post-action listener: _resource_record_post_action 07:31:05 INFO - Running post-action listener: set_extra_try_arguments 07:31:05 INFO - [mozharness: 2017-01-17 15:31:05.456378Z] Finished download-and-extract step (success) 07:31:05 INFO - [mozharness: 2017-01-17 15:31:05.456509Z] Running populate-webroot step. 07:31:05 INFO - Running pre-action listener: _resource_record_pre_action 07:31:05 INFO - Running main action method: populate_webroot 07:31:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:31:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:31:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:31:05 INFO - '--tppagecycles', 07:31:05 INFO - '7'], 07:31:05 INFO - 'tests': ['tsvgm']}}, 07:31:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:31:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:31:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:31:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:31:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['damp', 'tps']}, 07:31:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'tests': ['damp', 'tps']}, 07:31:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['dromaeo_dom']}, 07:31:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:31:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['basic_compositor_video']}, 07:31:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:31:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['a11yr', 07:31:05 INFO - 'ts_paint', 07:31:05 INFO - 'tpaint', 07:31:05 INFO - 'sessionrestore', 07:31:05 INFO - 'sessionrestore_no_auto_restore', 07:31:05 INFO - 'tabpaint']}, 07:31:05 INFO - 'other-e10s': {'tests': ['a11yr', 07:31:05 INFO - 'ts_paint', 07:31:05 INFO - 'tpaint', 07:31:05 INFO - 'sessionrestore', 07:31:05 INFO - 'sessionrestore_no_auto_restore', 07:31:05 INFO - 'tabpaint']}, 07:31:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:31:05 INFO - 'ts_paint', 07:31:05 INFO - 'tpaint', 07:31:05 INFO - 'sessionrestore', 07:31:05 INFO - 'sessionrestore_no_auto_restore', 07:31:05 INFO - 'tabpaint']}, 07:31:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:31:05 INFO - 'ts_paint', 07:31:05 INFO - 'tpaint', 07:31:05 INFO - 'sessionrestore', 07:31:05 INFO - 'sessionrestore_no_auto_restore', 07:31:05 INFO - 'tabpaint']}, 07:31:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['a11yr', 07:31:05 INFO - 'ts_paint', 07:31:05 INFO - 'tpaint', 07:31:05 INFO - 'sessionrestore', 07:31:05 INFO - 'sessionrestore_no_auto_restore', 07:31:05 INFO - 'tabpaint']}, 07:31:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['a11yr', 07:31:05 INFO - 'ts_paint', 07:31:05 INFO - 'tpaint', 07:31:05 INFO - 'sessionrestore', 07:31:05 INFO - 'sessionrestore_no_auto_restore', 07:31:05 INFO - 'tabpaint']}, 07:31:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['tsvgx', 07:31:05 INFO - 'tsvgr_opacity', 07:31:05 INFO - 'tart', 07:31:05 INFO - 'tscrollx', 07:31:05 INFO - 'cart', 07:31:05 INFO - 'tsvg_static']}, 07:31:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:31:05 INFO - 'tsvgr_opacity', 07:31:05 INFO - 'tart', 07:31:05 INFO - 'tscrollx', 07:31:05 INFO - 'cart', 07:31:05 INFO - 'tsvg_static']}, 07:31:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'talos_options': ['--disable-e10s'], 07:31:05 INFO - 'tests': ['tp5o']}, 07:31:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:31:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'talos_options': ['--disable-e10s', 07:31:05 INFO - '--xperf_path', 07:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:05 INFO - 'tests': ['tp5n']}, 07:31:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:31:05 INFO - 'talos_options': ['--xperf_path', 07:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:05 INFO - 'tests': ['tp5n']}}, 07:31:05 INFO - 'talos.zip': {'path': '', 07:31:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:31:05 INFO - Running post-action listener: _resource_record_post_action 07:31:05 INFO - [mozharness: 2017-01-17 15:31:05.474850Z] Finished populate-webroot step (success) 07:31:05 INFO - [mozharness: 2017-01-17 15:31:05.474964Z] Running create-virtualenv step. 07:31:05 INFO - Running pre-action listener: _resource_record_pre_action 07:31:05 INFO - Running main action method: create_virtualenv 07:31:05 INFO - Creating virtualenv /builds/slave/test/build/venv 07:31:05 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 07:31:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:31:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:31:05 INFO - Using real prefix '/tools/python27' 07:31:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:31:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:31:09 INFO - Installing pip.................done. 07:31:09 INFO - Return code: 0 07:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:31:09 INFO - Reading from file tmpfile_stdout 07:31:09 INFO - Output received: 07:31:09 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) 07:31:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:09 INFO - 'HOME': '/Users/cltbld', 07:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:09 INFO - 'LOGNAME': 'cltbld', 07:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:09 INFO - 'MOZ_NO_REMOTE': '1', 07:31:09 INFO - 'NO_EM_RESTART': '1', 07:31:09 INFO - 'PAGER': '/bin/cat', 07:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:09 INFO - 'PWD': '/builds/slave/test', 07:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:09 INFO - 'SHELL': '/bin/bash', 07:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:09 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:09 INFO - 'USER': 'cltbld', 07:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:09 INFO - 'XPC_FLAGS': '0x0', 07:31:09 INFO - 'XPC_SERVICE_NAME': '0', 07:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:09 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 07:31:09 INFO - Cleaning up... 07:31:09 INFO - Return code: 0 07:31:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:09 INFO - 'HOME': '/Users/cltbld', 07:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:09 INFO - 'LOGNAME': 'cltbld', 07:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:09 INFO - 'MOZ_NO_REMOTE': '1', 07:31:09 INFO - 'NO_EM_RESTART': '1', 07:31:09 INFO - 'PAGER': '/bin/cat', 07:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:09 INFO - 'PWD': '/builds/slave/test', 07:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:09 INFO - 'SHELL': '/bin/bash', 07:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:09 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:09 INFO - 'USER': 'cltbld', 07:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:09 INFO - 'XPC_FLAGS': '0x0', 07:31:09 INFO - 'XPC_SERVICE_NAME': '0', 07:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:10 INFO - Downloading/unpacking psutil>=3.1.1 07:31:10 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 07:31:10 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 07:31:10 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 07:31:10 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 07:31:10 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 07:31:10 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 07:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:14 INFO - Installing collected packages: psutil 07:31:14 INFO - Running setup.py install for psutil 07:31:14 INFO - building 'psutil._psutil_osx' extension 07:31:14 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 07:31:14 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 07:31:15 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 07:31:15 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 07:31:15 INFO - building 'psutil._psutil_posix' extension 07:31:15 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 07:31:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:31:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:31:15 INFO - ^ 07:31:15 INFO - 1 warning generated. 07:31:15 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 07:31:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:15 INFO - Successfully installed psutil 07:31:15 INFO - Cleaning up... 07:31:15 INFO - Return code: 0 07:31:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:15 INFO - 'HOME': '/Users/cltbld', 07:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:15 INFO - 'LOGNAME': 'cltbld', 07:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:15 INFO - 'MOZ_NO_REMOTE': '1', 07:31:15 INFO - 'NO_EM_RESTART': '1', 07:31:15 INFO - 'PAGER': '/bin/cat', 07:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:15 INFO - 'PWD': '/builds/slave/test', 07:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:15 INFO - 'SHELL': '/bin/bash', 07:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:15 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:15 INFO - 'USER': 'cltbld', 07:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:15 INFO - 'XPC_FLAGS': '0x0', 07:31:15 INFO - 'XPC_SERVICE_NAME': '0', 07:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:31:18 INFO - Installing collected packages: mozsystemmonitor 07:31:18 INFO - Running setup.py install for mozsystemmonitor 07:31:18 INFO - Successfully installed mozsystemmonitor 07:31:18 INFO - Cleaning up... 07:31:18 INFO - Return code: 0 07:31:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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', '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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:31: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 jsonschema==2.5.1 07:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:18 INFO - 'HOME': '/Users/cltbld', 07:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:18 INFO - 'LOGNAME': 'cltbld', 07:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:18 INFO - 'MOZ_NO_REMOTE': '1', 07:31:18 INFO - 'NO_EM_RESTART': '1', 07:31:18 INFO - 'PAGER': '/bin/cat', 07:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:18 INFO - 'PWD': '/builds/slave/test', 07:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:18 INFO - 'SHELL': '/bin/bash', 07:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:18 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:18 INFO - 'USER': 'cltbld', 07:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:18 INFO - 'XPC_FLAGS': '0x0', 07:31:18 INFO - 'XPC_SERVICE_NAME': '0', 07:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:18 INFO - Downloading/unpacking jsonschema==2.5.1 07:31:18 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 07:31:18 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 07:31:18 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 07:31:18 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 07:31:18 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 07:31:18 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 07:31:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:31:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:22 INFO - Downloading functools32-3.2.3-2.tar.gz 07:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:31:22 INFO - warning: no files found matching '*.txt' 07:31:22 INFO - no previously-included directories found matching 'build' 07:31:22 INFO - no previously-included directories found matching 'dist' 07:31:22 INFO - no previously-included directories found matching '.git*' 07:31:22 INFO - Installing collected packages: jsonschema, functools32 07:31:22 INFO - Running setup.py install for functools32 07:31:22 INFO - warning: no files found matching '*.txt' 07:31:22 INFO - no previously-included directories found matching 'build' 07:31:22 INFO - no previously-included directories found matching 'dist' 07:31:22 INFO - no previously-included directories found matching '.git*' 07:31:22 INFO - Successfully installed jsonschema functools32 07:31:22 INFO - Cleaning up... 07:31:22 INFO - Return code: 0 07:31:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:31:22 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')]} 07:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:22 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')]} 07:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:22 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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:22 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 07:31:22 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 07:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:22 INFO - 'HOME': '/Users/cltbld', 07:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:22 INFO - 'LOGNAME': 'cltbld', 07:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:22 INFO - 'MOZ_NO_REMOTE': '1', 07:31:22 INFO - 'NO_EM_RESTART': '1', 07:31:22 INFO - 'PAGER': '/bin/cat', 07:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:22 INFO - 'PWD': '/builds/slave/test', 07:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:22 INFO - 'SHELL': '/bin/bash', 07:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:22 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:22 INFO - 'USER': 'cltbld', 07:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:22 INFO - 'XPC_FLAGS': '0x0', 07:31:22 INFO - 'XPC_SERVICE_NAME': '0', 07:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:31:22 INFO - Cleaning up... 07:31:22 INFO - Return code: 0 07:31:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:31:22 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')]} 07:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:22 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')]} 07:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:22 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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:22 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 07:31:22 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 07:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:22 INFO - 'HOME': '/Users/cltbld', 07:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:22 INFO - 'LOGNAME': 'cltbld', 07:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:22 INFO - 'MOZ_NO_REMOTE': '1', 07:31:22 INFO - 'NO_EM_RESTART': '1', 07:31:22 INFO - 'PAGER': '/bin/cat', 07:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:22 INFO - 'PWD': '/builds/slave/test', 07:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:22 INFO - 'SHELL': '/bin/bash', 07:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:22 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:22 INFO - 'USER': 'cltbld', 07:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:22 INFO - 'XPC_FLAGS': '0x0', 07:31:22 INFO - 'XPC_SERVICE_NAME': '0', 07:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:22 INFO - Downloading/unpacking blobuploader==1.2.4 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:25 INFO - Downloading blobuploader-1.2.4.tar.gz 07:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:31:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:31:25 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 07:31:25 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 07:31:25 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 07:31:25 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 07:31:25 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 07:31:25 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 07:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:31:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31: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 07:31:26 INFO - Downloading docopt-0.6.1.tar.gz 07:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:31:26 INFO - Installing collected packages: blobuploader, requests, docopt 07:31:26 INFO - Running setup.py install for blobuploader 07:31:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:31:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:31:27 INFO - Running setup.py install for requests 07:31:27 INFO - Running setup.py install for docopt 07:31:27 INFO - Successfully installed blobuploader requests docopt 07:31:27 INFO - Cleaning up... 07:31:27 INFO - Return code: 0 07:31:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:27 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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:27 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 07:31:27 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 07:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:27 INFO - 'HOME': '/Users/cltbld', 07:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:27 INFO - 'LOGNAME': 'cltbld', 07:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:27 INFO - 'MOZ_NO_REMOTE': '1', 07:31:27 INFO - 'NO_EM_RESTART': '1', 07:31:27 INFO - 'PAGER': '/bin/cat', 07:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:27 INFO - 'PWD': '/builds/slave/test', 07:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:27 INFO - 'SHELL': '/bin/bash', 07:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:27 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:27 INFO - 'USER': 'cltbld', 07:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:27 INFO - 'XPC_FLAGS': '0x0', 07:31:27 INFO - 'XPC_SERVICE_NAME': '0', 07:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-v9LwuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TtkXI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-v2U_zV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-eFgmYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-XedU_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TSUrPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-A93HBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NgXsyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-OGXEad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NVYIY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-suKCie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6verBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BT1pYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-VBhFjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jfsOt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-jxkfaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-rJxrKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:29 INFO - Running setup.py install for manifestparser 07:31:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:29 INFO - Running setup.py install for mozcrash 07:31:30 INFO - Running setup.py install for mozdebug 07:31:30 INFO - Running setup.py install for mozdevice 07:31:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Running setup.py install for mozfile 07:31:30 INFO - Running setup.py install for mozhttpd 07:31:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Running setup.py install for mozinfo 07:31:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Running setup.py install for mozInstall 07:31:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:30 INFO - Running setup.py install for mozleak 07:31:31 INFO - Running setup.py install for mozlog 07:31:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:31 INFO - Running setup.py install for moznetwork 07:31:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:31 INFO - Running setup.py install for mozprocess 07:31:31 INFO - Running setup.py install for mozprofile 07:31:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:31 INFO - Running setup.py install for mozrunner 07:31:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:31 INFO - Running setup.py install for mozscreenshot 07:31:32 INFO - Running setup.py install for moztest 07:31:32 INFO - Running setup.py install for mozversion 07:31:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:32 INFO - Cleaning up... 07:31:32 INFO - Return code: 0 07:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:32 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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:32 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 07:31:32 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 07:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:32 INFO - 'HOME': '/Users/cltbld', 07:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:32 INFO - 'LOGNAME': 'cltbld', 07:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:32 INFO - 'MOZ_NO_REMOTE': '1', 07:31:32 INFO - 'NO_EM_RESTART': '1', 07:31:32 INFO - 'PAGER': '/bin/cat', 07:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:32 INFO - 'PWD': '/builds/slave/test', 07:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:32 INFO - 'SHELL': '/bin/bash', 07:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:32 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:32 INFO - 'USER': 'cltbld', 07:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:32 INFO - 'XPC_FLAGS': '0x0', 07:31:32 INFO - 'XPC_SERVICE_NAME': '0', 07:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Oapxzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:32 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)) 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-tHddj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:32 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)) 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-70gRBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:32 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)) 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-zMh7WH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-172mii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-MOdxaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Tt4eQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ArOXTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vCuoyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-yWbYRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-URoDmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-fRwdId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:33 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)) 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-gHmy2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:34 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)) 07:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GfykQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:34 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)) 07:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-I8qvV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:34 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)) 07:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-yXM0NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:34 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)) 07:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-uHjA1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:34 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)) 07:31:34 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)) 07:31:34 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)) 07:31:34 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)) 07:31:34 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)) 07:31:34 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)) 07:31:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:34 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 07:31:34 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 07:31:34 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 07:31:34 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 07:31:34 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 07:31:34 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 07:31:37 INFO - Downloading blessings-1.6.tar.gz 07:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:37 INFO - Installing collected packages: blessings 07:31:37 INFO - Running setup.py install for blessings 07:31:37 INFO - Successfully installed blessings 07:31:37 INFO - Cleaning up... 07:31:37 INFO - Return code: 0 07:31:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:37 INFO - Reading from file tmpfile_stdout 07:31:37 INFO - Current package versions: 07:31:37 INFO - blessings == 1.6 07:31:37 INFO - blobuploader == 1.2.4 07:31:37 INFO - docopt == 0.6.1 07:31:37 INFO - functools32 == 3.2.3-2 07:31:37 INFO - jsonschema == 2.5.1 07:31:37 INFO - manifestparser == 1.1 07:31:37 INFO - mozInstall == 1.12 07:31:37 INFO - mozcrash == 1.0 07:31:37 INFO - mozdebug == 0.1 07:31:37 INFO - mozdevice == 0.49 07:31:37 INFO - mozfile == 1.2 07:31:37 INFO - mozhttpd == 0.7 07:31:37 INFO - mozinfo == 0.9 07:31:37 INFO - mozleak == 0.1 07:31:37 INFO - mozlog == 3.4 07:31:37 INFO - moznetwork == 0.27 07:31:37 INFO - mozprocess == 0.24 07:31:37 INFO - mozprofile == 0.28 07:31:37 INFO - mozrunner == 6.13 07:31:37 INFO - mozscreenshot == 0.1 07:31:37 INFO - mozsystemmonitor == 0.3 07:31:37 INFO - moztest == 0.8 07:31:37 INFO - mozversion == 1.4 07:31:37 INFO - psutil == 3.1.1 07:31:37 INFO - requests == 1.2.3 07:31:37 INFO - wsgiref == 0.1.2 07:31:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:37 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 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:37 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 07:31:37 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 07:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:37 INFO - 'HOME': '/Users/cltbld', 07:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:37 INFO - 'LOGNAME': 'cltbld', 07:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:37 INFO - 'MOZ_NO_REMOTE': '1', 07:31:37 INFO - 'NO_EM_RESTART': '1', 07:31:37 INFO - 'PAGER': '/bin/cat', 07:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:37 INFO - 'PWD': '/builds/slave/test', 07:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:37 INFO - 'SHELL': '/bin/bash', 07:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:37 INFO - 'USER': 'cltbld', 07:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:37 INFO - 'XPC_FLAGS': '0x0', 07:31:37 INFO - 'XPC_SERVICE_NAME': '0', 07:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 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)) 07:31:38 INFO - Cleaning up... 07:31:38 INFO - Return code: 0 07:31:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b354b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b357768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3a0588b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, '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.xYFwPaudfR/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.sOutSvkFlV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 07:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:31:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:31:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:38 INFO - 'HOME': '/Users/cltbld', 07:31:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:38 INFO - 'LOGNAME': 'cltbld', 07:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:38 INFO - 'MOZ_NO_REMOTE': '1', 07:31:38 INFO - 'NO_EM_RESTART': '1', 07:31:38 INFO - 'PAGER': '/bin/cat', 07:31:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:38 INFO - 'PWD': '/builds/slave/test', 07:31:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:38 INFO - 'SHELL': '/bin/bash', 07:31:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:31:38 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:31:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:38 INFO - 'USER': 'cltbld', 07:31:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:38 INFO - 'XPC_FLAGS': '0x0', 07:31:38 INFO - 'XPC_SERVICE_NAME': '0', 07:31:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:31:38 INFO - Cleaning up... 07:31:38 INFO - Return code: 0 07:31:38 INFO - Running post-action listener: _resource_record_post_action 07:31:38 INFO - Running post-action listener: _start_resource_monitoring 07:31:38 INFO - Starting resource monitoring. 07:31:38 INFO - [mozharness: 2017-01-17 15:31:38.369062Z] Finished create-virtualenv step (success) 07:31:38 INFO - [mozharness: 2017-01-17 15:31:38.370002Z] Running install step. 07:31:38 INFO - Running pre-action listener: _resource_record_pre_action 07:31:38 INFO - Running main action method: install 07:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:38 INFO - Reading from file tmpfile_stdout 07:31:38 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:38 INFO - Reading from file tmpfile_stdout 07:31:38 INFO - Output received: 07:31:38 INFO - Usage: mozinstall [options] installer 07:31:38 INFO - Options: 07:31:38 INFO - -h, --help show this help message and exit 07:31:38 INFO - -d DEST, --destination=DEST 07:31:38 INFO - Directory to install application into. [default: 07:31:38 INFO - "/builds/slave/test"] 07:31:38 INFO - --app=APP Application being installed. [default: firefox] 07:31:38 INFO - mkdir: /builds/slave/test/build/application 07:31:38 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'] 07:31:38 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 07:32:00 INFO - Reading from file tmpfile_stdout 07:32:00 INFO - Output received: 07:32:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:32:00 INFO - Running post-action listener: _resource_record_post_action 07:32:00 INFO - [mozharness: 2017-01-17 15:32:00.734970Z] Finished install step (success) 07:32:00 INFO - [mozharness: 2017-01-17 15:32:00.735107Z] Running run-tests step. 07:32:00 INFO - Running pre-action listener: _resource_record_pre_action 07:32:00 INFO - Running main action method: run_tests 07:32:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:00 INFO - 2017-01-17 07:32:00.749 screenresolution[1630:7981] starting screenresolution argv=screenresolution get 07:32:00 INFO - 2017-01-17 07:32:00.752 screenresolution[1630:7981] Display 0: 1600x1200x32@60 07:32:00 INFO - 2017-01-17 07:32:00.758 screenresolution[1631:7983] starting screenresolution argv=screenresolution list 07:32:00 INFO - Available Modes on Display 0 07:32:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:00 INFO - Intel Iris: 07:32:00 INFO - Chipset Model: Intel Iris 07:32:00 INFO - Type: GPU 07:32:00 INFO - Bus: Built-In 07:32:00 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:00 INFO - Vendor: Intel (0x8086) 07:32:00 INFO - Device ID: 0x0a2e 07:32:00 INFO - Revision ID: 0x0009 07:32:00 INFO - Displays: 07:32:00 INFO - Display: 07:32:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:00 INFO - Display Serial Number: 1600x1200 60 07:32:00 INFO - Main Display: Yes 07:32:00 INFO - Mirror: Off 07:32:00 INFO - Online: Yes 07:32:00 INFO - Rotation: Supported 07:32:00 INFO - Return code: 0 07:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:32:00 INFO - Python 2.7.3 07:32:00 INFO - Return code: 0 07:32:00 INFO - grabbing minidump binary from tooltool 07:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:00 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 0x10b132d30>, '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 0x10b059db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b127be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:32:00 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 07:32:00 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 07:32:00 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 07:32:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:32:01 INFO - Return code: 0 07:32:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:32:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:32:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:32:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:32:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:32:01 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/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--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 07:32:01 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/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --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 07:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xYFwPaudfR/Render', 07:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:01 INFO - 'HOME': '/Users/cltbld', 07:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:01 INFO - 'LOGNAME': 'cltbld', 07:32:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:32:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:01 INFO - 'MOZ_NO_REMOTE': '1', 07:32:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:32:01 INFO - 'NO_EM_RESTART': '1', 07:32:01 INFO - 'PAGER': '/bin/cat', 07:32:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:01 INFO - 'PWD': '/builds/slave/test', 07:32:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:01 INFO - 'SHELL': '/bin/bash', 07:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sOutSvkFlV/Listeners', 07:32:01 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 07:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:01 INFO - 'USER': 'cltbld', 07:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:01 INFO - 'XPC_FLAGS': '0x0', 07:32:01 INFO - 'XPC_SERVICE_NAME': '0', 07:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:01 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/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--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 07:32:01 INFO - mozversion application_buildid: 20170117065524 07:32:01 INFO - mozversion application_changeset: 7a4f03169c77e4618312be942f7c5fa17f7bcb68 07:32:01 INFO - mozversion application_display_name: Nightly 07:32:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:32:01 INFO - mozversion application_name: Firefox 07:32:01 INFO - mozversion application_remotingname: firefox 07:32:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:32:01 INFO - mozversion application_vendor: Mozilla 07:32:01 INFO - mozversion application_version: 53.0a1 07:32:01 INFO - mozversion platform_buildid: 20170117065524 07:32:01 INFO - mozversion platform_changeset: 7a4f03169c77e4618312be942f7c5fa17f7bcb68 07:32:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:32:01 INFO - mozversion platform_version: 53.0a1 07:32:01 INFO - using testdate: 1484667121 07:32:01 INFO - actual date: 1484667121 07:32:01 INFO - starting webserver on 'localhost:49241' 07:32:01 INFO - SUITE-START | Running 2 tests 07:32:01 INFO - TEST-START | dromaeo_css 07:32:01 INFO - operating with platform_type : mac_ 07:32:01 INFO - Initialising browser for dromaeo_css test... 07:32:01 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp68I2E2/profile http://localhost:49241/getInfo.html) 07:32:04 INFO - PROCESS | 1641 | 2017-01-17 07:32:04.397 plugin-container[1643:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 07:32:04 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:04 INFO - PROCESS | 1641 | 2017-01-17 07:32:04.399 plugin-container[1643:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 07:32:04 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:04 INFO - PROCESS | 1641 | 2017-01-17 07:32:04.399 plugin-container[1643:8159] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:32:04 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 07:32:04 INFO - PROCESS | 1641 | colorDepth:24 07:32:04 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 07:32:04 INFO - PROCESS | 1641 | __metrics 07:32:04 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3253: TypeError: this.tabs is undefined 07:32:05 INFO - TEST-INFO | 1641: exit 0 07:32:05 INFO - Browser initialized. 07:32:05 INFO - Running cycle 1/1 for dromaeo_css test... 07:32:05 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp68I2E2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:08 INFO - PROCESS | 1645 | 2017-01-17 07:32:08.109 plugin-container[1646:8287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872f, name = 'com.apple.tsm.portname' 07:32:08 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:08 INFO - PROCESS | 1645 | 2017-01-17 07:32:08.110 plugin-container[1646:8287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 07:32:08 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:32:08 INFO - PROCESS | 1645 | 2017-01-17 07:32:08.110 plugin-container[1646:8287] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:32:17 INFO - PROCESS | 1645 | RSS: Main: 233734144 07:32:17 INFO - PROCESS | 1645 | 07:35:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:35:21 INFO - PROCESS | 1645 | RSS: Main: 261083136 07:35:21 INFO - PROCESS | 1645 | 07:37:05 INFO - PROCESS | 1645 | ************************* 07:37:05 INFO - PROCESS | 1645 | A coding exception was thrown and uncaught in a Task. 07:37:05 INFO - PROCESS | 1645 | 07:37:05 ERROR - PROCESS | 1645 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:37:05 INFO - PROCESS | 1645 | Full stack: 07:37:05 INFO - PROCESS | 1645 | ************************* 07:38:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:38:24 INFO - PROCESS | 1645 | RSS: Main: 262406144 07:38:24 INFO - PROCESS | 1645 | 07:41:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 07:41:27 INFO - PROCESS | 1645 | RSS: Main: 270794752 07:41:27 INFO - PROCESS | 1645 | 07:44:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 07:44:29 INFO - PROCESS | 1645 | RSS: Main: 267100160 07:44:29 INFO - PROCESS | 1645 | 07:47:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 07:47:33 INFO - PROCESS | 1645 | RSS: Main: 271958016 07:47:33 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 07:50:36 INFO - PROCESS | 1645 | RSS: Main: 274755584 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | __start_tp_report 07:50:36 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 07:50:36 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 07:50:36 INFO - PROCESS | 1645 | |i|pagename|runs| 07:50:36 INFO - PROCESS | 1645 | |0;dojo.html;2863.742263196313;3000.91966891919;3064.739497142743;3352.68149525795;3387.3056023515173;4457.732536047839;4467;5006.874828129298;5582.916256256154;5627.577931655127;4515.616172625322;5531.9723401383035;5544.3346798384255;5621.297337832771;5861.824145275648;5050.242463630448;5091.949080509185;5113.079645663779;5162.328897243353;5195.740212989354;5134.127198376266;5227.634065615409;5231.319928409302;5245;5295.391030031538;5061.544460998491;5085.79656813727;5148.099082660538;5152;5190.870228244286;5080.13637681595;5118.795248190105;5128.692278463304;5207.6875387476875;5223.503767142153;4962.206047032458;5122;5122.410922743895;5122.769475373617;5151.791180730821;4298.914021719579;5543.861403464905;5650.7174641267775;5689.743961521742;5698.230738850244;6067;6118.418750218722;6119.533019689815;6123.418275263843;6129.908051379233;6153.538484613672;6246.593971391891;6256.71844766982;6272.247330320345;6274.309825919146;5738.2868547654325;5765.308163020464;5789.392113828072;5805.071188609844;5823.039198532237;6157.599756015845;6164.445199932027;6167.907481387782;6200.906986395207;6223.253209614875;6074.3925607438905;6120.357362476965;6142.539309551712;6146.293176284695;6160.507159427235;6201.410865967726;6202.41077097675;6212.4408803207025;6228.719707613168;6250;6052.152698622196;6115.388461153849;6122.916604401471;6130;6135.294441139237;6173.166622505994;6176.042713379434;6180.690965451709;6192.380761923861;6195.938040619626;4946.3817022872145;4965.776540055706;4988.251762235694;4994.525520075587;4998.525140111684;4361.127774445124;4387.319965405367;4400.9559904401185;4433.445819272591;4473.418455600816;5599.8600034998635;5774.335951365562;5781.392953739881;5804.709764511842;5832;4985.326980857611;5209.973950130225;6197.721102550396;6245.375462453719;6283.842903927439;5571.387147413781;5667.773289068391;5750.7987220447085;5764.7117644117625;5770.798022069207;5307.734613269321;5347.598930080259;5360.088784906575;5368.758405871745;5369.033573956725;5335.466453354634;6279.309275979547;6324.873502529832;6330.39861213184;6336.112944187817;6144.170536977451;6200.968995154995;6220.160278362544;6228.781992630417;6234.501239900706;5082.720450375265;6186.845328866814;6198.915189841669;6222.942099843128;6299.086632438362;6133.478654314434;6190.50475961931;6194.4115309045565;6201.906971395523;6208.192934918432;6147.354527774698;6235;6240.563160578716;6253.280872699515;6253.749850005949;5710.857228569319;5771.394003629726;5772.6536407815665;6045.395460454095;6147.938520614915;5803.912941305799;5994.220751302477;6026.8794624106395;6091.42131497507;6126.792505495216;5604.355499117491;5731.369549349485;5775.7400916957995;5861.472467477777;5974.1934838796205;5961.791337303173;5989.401059893976;6055.72749226277;6072.666003369857;6109.419605137505;5709;5903.2916050072545;5937.050071988461;5951.791687291097;5968.4926781224085 07:50:36 INFO - PROCESS | 1645 | |1;ext.html;420.1722606465264;485.9222524396096;490.8355700840218;526.2080568744041;535.8987281137262;40028.79985600072;42200.36699449507;42326.7883660582;44393;44667.32999005017;42853.35719964203;43220.78389608047;43272.35091473623;43307.35038974418;43393;9343.813123737522;9577.090176433228;11089.55641774328;11444.027257683107;11465.59870404536;9508.47703376314;11104.666859994213;11118.833217501704;11268.549258029669;11286.153538484605;9510.904890951071;11240.662780116569;11250.156238282121;11280.605178818743;11330.886691133066;11169.888301116966;11197.552097916156;11210.66368008961;11241.775164496747;11253.831192532118;8441.366897482652;8558.529280889548;9967.501624918725;10341.224408169417;10864.837027444595;36980.63019369799;37259.06852328686;37354;37401.064973375614;37420.81289593562;3878.3988481785086;3896.902577435558;3980.2835489611857;4083.509978802533;4177.143685544486;28410.28974275639;28765.137045888656;28978.855105724546;28989;29068.8546557268;2939.5884576159356;2953.6898625644426;3230;3269.2480729432127;3303.0256074458093;414608.92695536633;479558;483861;485945;486707;55073.44926550683;55125.72437137869;55514.44485555174;55566.72216638891;55827.441725583034;57660;58063.709681452165;58139.70930145322;58174.709126454945;58293.70853145707;13767.862321376659;15325.46360877364;15568.143752093743;15595.688086238211;15617.609559761097;10812.135029197645;11977.760444791056;12156.270623762603;12162.878371216351;12332.630021099381;17853.37513187032;17893.284268629108;18037.458876233733;18047.368342107962;18056.09719514019;7549.2073332299315;7845.490043147177;7863.33161681252;7923.881141782877;7955.4828936118965;870.6996086350199;884.9778755531034;895.5477483870734;909.8043920557112;923.879895613566;23013.88493057524;27438.451230975268;30574.847125764674;30665.84667076695;31183;906.4380084347615;1035.2597892506894;1043.8173319669027;1066.9573217071231;1077.8221593437152;13102.410391532403;14442.494512692216;14559.272036398137;14622.049566778125;14860.479883204036;644791.2083516606;645788;649137;653153;653766;52704.945901081766;52883;53066.46933530692;53081;53095.46904530905;1386.334559411488;1450.9564713058414;1451.0060608483222;1451.6298343922233;1459.773735071087;27306.863465682545;27496.58755118715;27706.861465692542;27811.860940695977;27977.16068517948;37674;46997;47045.82385440391;47221.7638911817;47226;582667;583471;585973;586697;586780;24643.507129857662;24668.519888806728;24958;24959.75040249574;24988.625170622818;42552;42593.14813703646;42627.14745705131;43675;46115.769421152676;35548.57805687847;35818.462723059696;35855.64144358523;36698.8165059173;37192.07019824526;32747.67252327446;35583;35911.52262352438;36134;36835.447468287464;35321.646783532866;35638.465423018155;36352.09119772027;36431.45352819763;36576.08559786026;24533;31361;32439.67560324461;32453.35093298168;32599.022029339158;23362.76637233606;23637.881810590836;23756.762432376145;23796.524069518167;24359.391015224763 07:50:36 INFO - PROCESS | 1645 | |2;jquery.html;330.06920484234456;370.353732736375;382.86791057085316;394.6487626012847;413.76903711458414;19559.119839607218;20944.16223351068;21352;21394.786052139472;21509.03209355579;17558.29766809329;21638.24266150685;21751.564968700615;21798.128074876986;22159.00284487198;5712.628679135853;5807.303123625171;5942.64344139353;5982.581219314649;6030.216071910668;4093.7543747375103;5810.070388737803;5918.940810591882;5926.081457374114;5960.254968128984;4153.065560248935;4332.436783218193;4461.866144015668;6621.536492445531;6661.933380666204;6335.936640633581;6599.967000164993;6614.801555953329;6617.702203400859;6617.834554136137;5685.914711279334;5723.713814309268;5765.625234359753;5766.279215098113;5783.768649254067;22527;24831.00675972959;25828.87085564579;25901;26149.869250653624;2476.7770900618852;2680.3969106950976;2766.336079340964;2769.9307517312027;2776.5141100307364;8804.723804199708;10297.330673506198;10579;11937.701557461047;15388.30752616135;1775.3786174838833;1806.0608483588592;1816.2008716164978;1828.3235202974984;1856.60083082138;142764;144324.55675443215;144847.30916145066;146000;146360;10608.89391106079;11213.383263920563;11861.169718119821;12106.33415162157;12143.878561214451;15571.299291531908;15760.448384306494;15800.68398632021;15812.525624231292;15819.841801582066;8587.227149556446;8740.169683880022;8759.605817738217;8777.780555486164;8784.736457906272;8441.11368306319;8443.957780211182;8501.489910605384;8620.655173793104;8630.611622476878;4816.109019831342;4823.252395878644;4827.131116399011;4835.661503694778;4889.364382630235;2020.7170996060563;2061.062216691432;2068.389825001598;2069.317125348631;2073.844461665381;856.3748463621591;896.1620884473039;918.4626993209048;920.8204400141908;933.2673851026912;20793.58412831735;21251.68122478164;21328.466788330104;21370.465738356666;21437.785622143892;908.418612088264;933.2114363362947;940.6707652321769;941.505709502503;943.5423819447562;10702.143828493701;10974;11044.282121662067;11044.33733975964;11086.501107450184;519656;667674;677634;677800;685378;104830.4758476218;149373;152335;152940;159128;1035.1873779083382;1060.8754719996828;1081.1242893256488;1082.0532034469838;1082.9458527073457;16351.427700030441;16801.579960501087;17590;19111.713324300425;19898.60202795965;58300;59384.70307648607;59629.10556341572;60131.09803352866;60285;74207;86890;87363.56318218622;87610.56194718985;87757.24485510381;7285.526440781332;8065.919340806517;8159.877601836096;8587.398882078194;8703.303735701254;11372.943135284271;12249.713780053322;13216.14095083855;13402.865971340552;13648.317584120952;9477.763055923657;11500.712482187677;11596.188266821242;11624.500732342587;11633.917011991998;11149.55401783952;11189.944050279697;11211.215214934877;11507.136964727511;11557.190996630155;9211.631534738803;11596.94201528987;11776.293422394683;11834.218958544563;12002.919737223692;8296.336293096658;8522.531260780717;8539;8551.486910785372;8672.78318042054;11199.552017919192;11338;11461.541538338373;11527.942360288145;11609 07:50:36 INFO - PROCESS | 1645 | |3;mootools.html;1425.5606807698437;1434.9210793406364;1452.7433769789136;1454.112991075444;1465.2746890289304;7470.028896243494;7718.8456230875345;8038.316743076838;8487.745367638965;8495.405321627488;7978.960105199466;8226.465279756812;8262.586870656472;8326;8369.20492553208;2269.149069099092;2319.512902290521;2321.849079809807;2331.114176612885;2336.2874323331375;2287.165184707578;2315.166540045582;2323.6979192704925;2326.5579539887453;2333.46330344021;2194.4952660888007;2200.933971980843;2210.403191138392;2214.3689048621145;2214.9667754983607;2264.6942662740485;2267.9206227781947;2286.0969916882823;2287.325239054483;2292;2254.1096266974455;2318.4899322149267;2325.930222093323;2326.8487548309477;2333.1484008336834;8345.41582089254;8371.95814020926;8409.032961209477;8429.95785021077;8432.578371081483;5022;5146.871328216787;5194.71429071401;5201.713905735183;5206.947930520684;3253.398121347558;3328.5839270091237;3471.6701913318193;3534.6288639692975;3537.575490941078;1188.9464974076102;1195.276857501208;1204.566356111799;1207.1007099710685;1239.3183748938047;10488.895111048869;10583.15334773224;10646.680599582025;10680.46597670121;10691.198160138021;4389.473263208435;4611.515790841913;4682.41469816273;4689.484156742756;4708.788104535304;9422.952885235667;9423.38747981379;9443.622255109856;9453.007434219307;9557.99641037681;4583.862484125481;5166.4058633257655;5211.791528338824;5216.347956505437;5221.164613661796;3780;4147.212029714345;4286.335617979218;4698.083873644675;4701.694389864648;3156.9684303157132;3223.903282901517;3822.5604055533413;3924.5486769021927;3953.486046813896;1142.1433924556582;1243.3161761031397;1260.8928241099427;1261.6656586004717;1273.9745205095846;788.1094363369356;849.8895143631288;903.9909600904039;908.2098574240321;934.4066517761271;5321.255024296602;5434.103372943499;5585.69278689676;5684.602077854511;5736.4550367715;947.3747326764302;987.2151639446657;991.509202944547;1001.4692213127055;1041.2763129624989;3444.6727560881677;3576.159602493412;3656.195636959918;3701.296753616858;3713.535808023997;9694.660686875926;9985;9991.750206244902;10023.749406264902;10038.64864729731;8653.61058752345;9814.901850981541;9893.60425582983;9897.554610042565;9918.305718599773;6163.753449862045;7794.337481314152;7804.219578042151;7806.765797026098;7811.336036436966;5539.501444869981;5585.245991791218;5687.630304030225;5821.546516914648;5852.122181672783;9192.908070919206;9352.345335826427;9361.672341468016;9377.343585948918;9418.293627977791;7805.180456052033;9109.817803644022;9349;9416;9486.292467355943;6240.251169859464;7100.9289907100265;7120.465965052538;7165.498415110892;7248.891266630899;6716.83207919806;7884.448088633741;7978.321842643209;7990.92009079925;7993.960030199812;7066.505344625827;7150.606716630427;7196.532225405541;7228.746993855189;7296.124465064227;5204.453532379197;7014;7028.789136325919;7067.25793791665;7152.427805775422;6308.243010838729;6389.7124629390855;6409.262934762562;6433.260175079926;6437.356264373525;4940.110780059624;4998.2002879539095;4998.575121114746;5050.949490505195;5089.13484707608;4926.408830940167;4949.678270912379;4978.551930326288;5026.773795179299;5226.895462090661 07:50:36 INFO - PROCESS | 1645 | |4;prototype.html;295.8860838577147;342.4230172159911;350.71592010471517;367.8583745258075;368.53012409178314;4767.523247675231;5054.772535235914;5103.413107492639;5342.385625653046;5450.5367043801225;4505.70712903661;5368.46315368463;5391.272178255935;5396.892062158764;5426.403095659484;4219.130990926169;4231.048014196812;4236.0090757344;4237.889247429988;4243.986769393734;4066.0762364318425;4126.834926602947;4184.518780340254;4251.383549385338;4255.276602977486;3451.4822776583587;3459.183928437565;3680.6871415929677;4054.533728621202;4169.9791501042455;3536.734744894143;3617.3127105849803;3961.771850726284;4157.877373109243;4220.725652832556;3100.705432983863;3705.5368078990127;3737;3741.9232326059027;3789.8484060637543;4859.829905953275;5015.974920125413;5235.319408476912;5272.868178295535;5275.762590683428;3924.077841707225;4303.730399532145;4651.580908241895;4655.647933487883;4731.36126622905;5021.949780502185;5272.657277277003;5525.530329921921;5550.833474995757;5696.458836410535;3854.7879866607327;3870.9419358709642;3876.8757060452435;3879.476270703475;3901.51231096113;4997.301457213137;5609.495145436889;6012.669303188323;6016.947034269029;6057.182280392135;3685.4287585424304;3899.4150877368056;3906.3945088511327;3909.7458665186673;3928.860630417147;3487.459443786217;3966.667998880896;3973.2848087344555;3982.3030969580786;3982.8406863725704;4632.420947381577;4641.767911604406;4653.86038418848;4673.485916549177;4694.741789201558;4748.002919386899;5280.313559237275;5500.80747173847;5685.971570142123;5789.8552536186935;1082.9928166804948;1124.0782558302278;1155.5839897636845;1275.024606176276;1306.8693130686854;836.506461187902;872.5297873083874;874.8993865705526;889.9243564297108;891.4383938118943;4945.887175385509;5118.744062796845;5156.1750119980625;5172.069027574998;5257.7896884124975;5121.923171152435;5259;5640.379558248589;5641.774329026875;5660.094384898396;4725.810967561259;4847.903041939141;5098.439171691111;5150.072986862326;5162.850661096728;2544.5572250639593;2609.395221938532;2767.6540432445945;2906.2589039794716;2949.808262462933;5165.74171291434;5477.808276710296;5587.469190426903;5771.585961439474;5857.707114644336;4825.879353016203;5149.356330458692;5376.166694162411;5713.714314284352;5825;5613.9860000100125;5650.830475085754;5840.620359676607;5841.94158058414;5862.6842397253695;5258.898059513585;5602.747876345493;5607.523335531996;5623.662580245198;5654.578979717212;4647.883802904955;4826.91394436249;5016.949830501649;5033.446320904771;5505.421930697219;5135.281060651437;5231.058409486177;5437.972810135924;5635.985522605806;5761.625494342854;3387.3056023514628;3583.3695668471305;3748.850045998239;3854.7301688881507;3905.047476261869;5325.813596524103;5783.652980821164;5792.82621521355;5828.388019257917;5832.708364581838;4555.954440455553;4898.92651610219;5546.750396232099;5574.829285850017;5593.888122237614;4486.461624605068;4521.977390113028;5173.3533308336455;5617.719114044363;5645.604807663424;5090.872728181825;5470.671759694431;5494.945050549443;5505.311836020497;5527.806526771544;4688.26534182341;5507.834764957058;5577.52591029767;5589.329280486368;5594.384617692132;4561.8139283785795;5483.917741233805;5492.642978206404;5514.669119852661;5516.806911758069 07:50:36 INFO - PROCESS | 1645 | |5;yui.html;694.3684529908581;695.8956156576514;709.7090193020861;711.6797441151476;815.4699445360515;14783.482578109768;15106.773398399033;15328.770068448968;15514.767278490817;15593.376264949417;14074.437022519112;14251.216183109926;14494;14525.854741452582;14535.49125780598;857.661223816592;877.6928075173702;886.3883920095113;893.2675206330812;893.307686542928;625.5746092656991;680.5270337115707;860.3633311349588;886.441541828647;886.9113088691144;925.8842644669417;937.6577549194564;939.6100618243421;941.6892425499568;943.4009404028423;924.4453328003211;932.1144912333276;933.6732143749701;936.1901954809109;936.241644268145;600.3996003996004;683.7846078485261;696.0916004613977;697.135551915626;697.5256825358754;14771.778423323658;15281.847181528154;15666.295016724274;15783.526494205193;15784;13844.653883652889;13896.51362202318;14221;14257.358418871176;14491.78262326066;10223.182145428422;10269.229807764448;10383.532741026673;10542.262041657086;10810.405427701473;3641.2236412236202;3648.3980143276326;3679.5400574928135;3693.3351996640595;3697;74302;97616.99999999929;104076.47961760219;106317.93682063236;108426;10719.517621707042;10745.570177192825;10749.78500429987;10850.9884715731;11089.445527723581;18176.545586360447;22645;22655.88672056629;22802.885985570298;22842.31473055811;5752.712364381764;7587.658555365022;7907.683692652229;7948.642311096015;8091.150429204967;3854.325493038707;4204;4398.714083584557;4403.185410699031;4484.596386325246;3545.2377738786286;3648.1244501319493;3659.2681463707363;3675.9448608270895;3718.8326525305883;656.8456412743002;669.3340126573993;675.4596322941627;682.6245564939263;683.3132701634826;628.6668065925068;641.9935800642028;685.9931400685929;686.5571706249441;715.8747219236612;7913.802154946173;9859.26055545837;10191.592336306612;10217.33587316822;10443.686689399177;660.2506155513527;697.5431092634333;704.7427688893517;707.5719189890145;709.9503034787618;4676.532346765364;4868.269759536098;4872.756362181947;4874.025194961022;4896.167651499182;102916;105726.47136764268;106633;106729;108313;78314.60842695751;91767.0823291772;92473;93676;93967.53016234875;82021;83000;83223;83819.5809020951;83954;9446.38598491096;9448.244140468883;9456.952715236655;9482;9485.478298693639;52618.473815262885;53337.73331133319;53714.38856834301;54368.640783980714;54945.72527137339;69392;71624.28375716385;71994;72156;72485.91271130832;11428.485718142538;11482;11600.767984640092;11777.35224562623;12850;14466.927665361605;14718.852811472176;14837.480688175861;14941.701165976838;15691.843081569496;11471.483783229942;13732.382042808298;13759.17444953306;13834.792478112635;13991.440342386191;13690.52083177084;13782.173069615856;13830.723385532032;14009.751034238308;14172.433102675777;13585.86414135846;13775.586732398044;13827.101238419471;13973.371198295898;14004.649883752987;10691.411972341595;10861.490177383303;10865.719113408148;11323.547058117583;11410.885891140982;9135.766671499263;9765.462899540593;10067.244956628429;10698.251122421667;11308.587840217513 07:50:36 INFO - PROCESS | 1645 | __end_tp_report 07:50:36 INFO - PROCESS | 1645 | __start_cc_report 07:50:36 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,403 07:50:36 INFO - PROCESS | 1645 | __end_cc_report 07:50:36 INFO - PROCESS | 1645 | __startTimestamp1484668236260__endTimestamp 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | ------- Summary: start ------- 07:50:36 INFO - PROCESS | 1645 | Number of tests: 6 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5635.92 Median:5804.71 stddev:686.16 (11.8%) stddev-sans-first:652.89 07:50:36 INFO - PROCESS | 1645 | Values: 2863.7 3000.9 3064.7 3352.7 3387.3 4457.7 4467.0 5006.9 5582.9 5627.6 4515.6 5532.0 5544.3 5621.3 5861.8 5050.2 5091.9 5113.1 5162.3 5195.7 5134.1 5227.6 5231.3 5245.0 5295.4 5061.5 5085.8 5148.1 5152.0 5190.9 5080.1 5118.8 5128.7 5207.7 5223.5 4962.2 5122.0 5122.4 5122.8 5151.8 4298.9 5543.9 5650.7 5689.7 5698.2 6067.0 6118.4 6119.5 6123.4 6129.9 6153.5 6246.6 6256.7 6272.2 6274.3 5738.3 5765.3 5789.4 5805.1 5823.0 6157.6 6164.4 6167.9 6200.9 6223.3 6074.4 6120.4 6142.5 6146.3 6160.5 6201.4 6202.4 6212.4 6228.7 6250.0 6052.2 6115.4 6122.9 6130.0 6135.3 6173.2 6176.0 6180.7 6192.4 6195.9 4946.4 4965.8 4988.3 4994.5 4998.5 4361.1 4387.3 4401.0 4433.4 4473.4 5599.9 5774.3 5781.4 5804.7 5832.0 4985.3 5210.0 6197.7 6245.4 6283.8 5571.4 5667.8 5750.8 5764.7 5770.8 5307.7 5347.6 5360.1 5368.8 5369.0 5335.5 6279.3 6324.9 6330.4 6336.1 6144.2 6201.0 6220.2 6228.8 6234.5 5082.7 6186.8 6198.9 6222.9 6299.1 6133.5 6190.5 6194.4 6201.9 6208.2 6147.4 6235.0 6240.6 6253.3 6253.7 5710.9 5771.4 5772.7 6045.4 6147.9 5803.9 5994.2 6026.9 6091.4 6126.8 5604.4 5731.4 5775.7 5861.5 5974.2 5961.8 5989.4 6055.7 6072.7 6109.4 5709.0 5903.3 5937.1 5951.8 5968.5 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:69069.51 Median:36243.05 stddev:153392.62 (423.2%) stddev-sans-first:153736.33 07:50:36 INFO - PROCESS | 1645 | Values: 420.2 485.9 490.8 526.2 535.9 40028.8 42200.4 42326.8 44393.0 44667.3 42853.4 43220.8 43272.4 43307.4 43393.0 9343.8 9577.1 11089.6 11444.0 11465.6 9508.5 11104.7 11118.8 11268.5 11286.2 9510.9 11240.7 11250.2 11280.6 11330.9 11169.9 11197.6 11210.7 11241.8 11253.8 8441.4 8558.5 9967.5 10341.2 10864.8 36980.6 37259.1 37354.0 37401.1 37420.8 3878.4 3896.9 3980.3 4083.5 4177.1 28410.3 28765.1 28978.9 28989.0 29068.9 2939.6 2953.7 3230.0 3269.2 3303.0 414608.9 479558.0 483861.0 485945.0 486707.0 55073.4 55125.7 55514.4 55566.7 55827.4 57660.0 58063.7 58139.7 58174.7 58293.7 13767.9 15325.5 15568.1 15595.7 15617.6 10812.1 11977.8 12156.3 12162.9 12332.6 17853.4 17893.3 18037.5 18047.4 18056.1 7549.2 7845.5 7863.3 7923.9 7955.5 870.7 885.0 895.5 909.8 923.9 23013.9 27438.5 30574.8 30665.8 31183.0 906.4 1035.3 1043.8 1067.0 1077.8 13102.4 14442.5 14559.3 14622.0 14860.5 644791.2 645788.0 649137.0 653153.0 653766.0 52704.9 52883.0 53066.5 53081.0 53095.5 1386.3 1451.0 1451.0 1451.6 1459.8 27306.9 27496.6 27706.9 27811.9 27977.2 37674.0 46997.0 47045.8 47221.8 47226.0 582667.0 583471.0 585973.0 586697.0 586780.0 24643.5 24668.5 24958.0 24959.8 24988.6 42552.0 42593.1 42627.1 43675.0 46115.8 35548.6 35818.5 35855.6 36698.8 37192.1 32747.7 35583.0 35911.5 36134.0 36835.4 35321.6 35638.5 36352.1 36431.5 36576.1 24533.0 31361.0 32439.7 32453.4 32599.0 23362.8 23637.9 23756.8 23796.5 24359.4 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:38055.31 Median:25864.94 stddev:109032.16 (421.5%) stddev-sans-first:109301.22 07:50:36 INFO - PROCESS | 1645 | Values: 330.1 370.4 382.9 394.6 413.8 19559.1 20944.2 21352.0 21394.8 21509.0 17558.3 21638.2 21751.6 21798.1 22159.0 5712.6 5807.3 5942.6 5982.6 6030.2 4093.8 5810.1 5918.9 5926.1 5960.3 4153.1 4332.4 4461.9 6621.5 6661.9 6335.9 6600.0 6614.8 6617.7 6617.8 5685.9 5723.7 5765.6 5766.3 5783.8 22527.0 24831.0 25828.9 25901.0 26149.9 2476.8 2680.4 2766.3 2769.9 2776.5 8804.7 10297.3 10579.0 11937.7 15388.3 1775.4 1806.1 1816.2 1828.3 1856.6 142764.0 144324.6 144847.3 146000.0 146360.0 10608.9 11213.4 11861.2 12106.3 12143.9 15571.3 15760.4 15800.7 15812.5 15819.8 8587.2 8740.2 8759.6 8777.8 8784.7 8441.1 8444.0 8501.5 8620.7 8630.6 4816.1 4823.3 4827.1 4835.7 4889.4 2020.7 2061.1 2068.4 2069.3 2073.8 856.4 896.2 918.5 920.8 933.3 20793.6 21251.7 21328.5 21370.5 21437.8 908.4 933.2 940.7 941.5 943.5 10702.1 10974.0 11044.3 11044.3 11086.5 519656.0 667674.0 677634.0 677800.0 685378.0 104830.5 149373.0 152335.0 152940.0 159128.0 1035.2 1060.9 1081.1 1082.1 1082.9 16351.4 16801.6 17590.0 19111.7 19898.6 58300.0 59384.7 59629.1 60131.1 60285.0 74207.0 86890.0 87363.6 87610.6 87757.2 7285.5 8065.9 8159.9 8587.4 8703.3 11372.9 12249.7 13216.1 13402.9 13648.3 9477.8 11500.7 11596.2 11624.5 11633.9 11149.6 11189.9 11211.2 11507.1 11557.2 9211.6 11596.9 11776.3 11834.2 12002.9 8296.3 8522.5 8539.0 8551.5 8672.8 11199.6 11338.0 11461.5 11527.9 11609.0 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5373.87 Median:5209.37 stddev:2929.20 (56.2%) stddev-sans-first:2922.38 07:50:36 INFO - PROCESS | 1645 | Values: 1425.6 1434.9 1452.7 1454.1 1465.3 7470.0 7718.8 8038.3 8487.7 8495.4 7979.0 8226.5 8262.6 8326.0 8369.2 2269.1 2319.5 2321.8 2331.1 2336.3 2287.2 2315.2 2323.7 2326.6 2333.5 2194.5 2200.9 2210.4 2214.4 2215.0 2264.7 2267.9 2286.1 2287.3 2292.0 2254.1 2318.5 2325.9 2326.8 2333.1 8345.4 8372.0 8409.0 8430.0 8432.6 5022.0 5146.9 5194.7 5201.7 5206.9 3253.4 3328.6 3471.7 3534.6 3537.6 1188.9 1195.3 1204.6 1207.1 1239.3 10488.9 10583.2 10646.7 10680.5 10691.2 4389.5 4611.5 4682.4 4689.5 4708.8 9423.0 9423.4 9443.6 9453.0 9558.0 4583.9 5166.4 5211.8 5216.3 5221.2 3780.0 4147.2 4286.3 4698.1 4701.7 3157.0 3223.9 3822.6 3924.5 3953.5 1142.1 1243.3 1260.9 1261.7 1274.0 788.1 849.9 904.0 908.2 934.4 5321.3 5434.1 5585.7 5684.6 5736.5 947.4 987.2 991.5 1001.5 1041.3 3444.7 3576.2 3656.2 3701.3 3713.5 9694.7 9985.0 9991.8 10023.7 10038.6 8653.6 9814.9 9893.6 9897.6 9918.3 6163.8 7794.3 7804.2 7806.8 7811.3 5539.5 5585.2 5687.6 5821.5 5852.1 9192.9 9352.3 9361.7 9377.3 9418.3 7805.2 9109.8 9349.0 9416.0 9486.3 6240.3 7100.9 7120.5 7165.5 7248.9 6716.8 7884.4 7978.3 7990.9 7994.0 7066.5 7150.6 7196.5 7228.7 7296.1 5204.5 7014.0 7028.8 7067.3 7152.4 6308.2 6389.7 6409.3 6433.3 6437.4 4940.1 4998.2 4998.6 5050.9 5089.1 4926.4 4949.7 4978.6 5026.8 5226.9 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4494.04 Median:5044.11 stddev:1366.78 (27.1%) stddev-sans-first:1333.80 07:50:36 INFO - PROCESS | 1645 | Values: 295.9 342.4 350.7 367.9 368.5 4767.5 5054.8 5103.4 5342.4 5450.5 4505.7 5368.5 5391.3 5396.9 5426.4 4219.1 4231.0 4236.0 4237.9 4244.0 4066.1 4126.8 4184.5 4251.4 4255.3 3451.5 3459.2 3680.7 4054.5 4170.0 3536.7 3617.3 3961.8 4157.9 4220.7 3100.7 3705.5 3737.0 3741.9 3789.8 4859.8 5016.0 5235.3 5272.9 5275.8 3924.1 4303.7 4651.6 4655.6 4731.4 5021.9 5272.7 5525.5 5550.8 5696.5 3854.8 3870.9 3876.9 3879.5 3901.5 4997.3 5609.5 6012.7 6016.9 6057.2 3685.4 3899.4 3906.4 3909.7 3928.9 3487.5 3966.7 3973.3 3982.3 3982.8 4632.4 4641.8 4653.9 4673.5 4694.7 4748.0 5280.3 5500.8 5686.0 5789.9 1083.0 1124.1 1155.6 1275.0 1306.9 836.5 872.5 874.9 889.9 891.4 4945.9 5118.7 5156.2 5172.1 5257.8 5121.9 5259.0 5640.4 5641.8 5660.1 4725.8 4847.9 5098.4 5150.1 5162.9 2544.6 2609.4 2767.7 2906.3 2949.8 5165.7 5477.8 5587.5 5771.6 5857.7 4825.9 5149.4 5376.2 5713.7 5825.0 5614.0 5650.8 5840.6 5841.9 5862.7 5258.9 5602.7 5607.5 5623.7 5654.6 4647.9 4826.9 5016.9 5033.4 5505.4 5135.3 5231.1 5438.0 5636.0 5761.6 3387.3 3583.4 3748.9 3854.7 3905.0 5325.8 5783.7 5792.8 5828.4 5832.7 4556.0 4898.9 5546.8 5574.8 5593.9 4486.5 4522.0 5173.4 5617.7 5645.6 5090.9 5470.7 5494.9 5505.3 5527.8 4688.3 5507.8 5577.5 5589.3 5594.4 4561.8 5483.9 5492.6 5514.7 5516.8 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20673.71 Median:4301.36 stddev:29797.45 (692.7%) stddev-sans-first:29843.28 07:50:36 INFO - PROCESS | 1645 | Values: 694.4 695.9 709.7 711.7 815.5 14783.5 15106.8 15328.8 15514.8 15593.4 14074.4 14251.2 14494.0 14525.9 14535.5 857.7 877.7 886.4 893.3 893.3 625.6 680.5 860.4 886.4 886.9 925.9 937.7 939.6 941.7 943.4 924.4 932.1 933.7 936.2 936.2 600.4 683.8 696.1 697.1 697.5 14771.8 15281.8 15666.3 15783.5 15784.0 13844.7 13896.5 14221.0 14257.4 14491.8 10223.2 10269.2 10383.5 10542.3 10810.4 3641.2 3648.4 3679.5 3693.3 3697.0 74302.0 97617.0 104076.5 106317.9 108426.0 10719.5 10745.6 10749.8 10851.0 11089.4 18176.5 22645.0 22655.9 22802.9 22842.3 5752.7 7587.7 7907.7 7948.6 8091.2 3854.3 4204.0 4398.7 4403.2 4484.6 3545.2 3648.1 3659.3 3675.9 3718.8 656.8 669.3 675.5 682.6 683.3 628.7 642.0 686.0 686.6 715.9 7913.8 9859.3 10191.6 10217.3 10443.7 660.3 697.5 704.7 707.6 710.0 4676.5 4868.3 4872.8 4874.0 4896.2 102916.0 105726.5 106633.0 106729.0 108313.0 78314.6 91767.1 92473.0 93676.0 93967.5 82021.0 83000.0 83223.0 83819.6 83954.0 9446.4 9448.2 9457.0 9482.0 9485.5 52618.5 53337.7 53714.4 54368.6 54945.7 69392.0 71624.3 71994.0 72156.0 72485.9 11428.5 11482.0 11600.8 11777.4 12850.0 14466.9 14718.9 14837.5 14941.7 15691.8 11471.5 13732.4 13759.2 13834.8 13991.4 13690.5 13782.2 13830.7 14009.8 14172.4 13585.9 13775.6 13827.1 13973.4 14004.6 10691.4 10861.5 10865.7 11323.5 11410.9 9135.8 9765.5 10067.2 10698.3 11308.6 07:50:36 INFO - PROCESS | 1645 | -------- Summary: end -------- 07:50:36 INFO - PROCESS | 1645 | 07:50:36 INFO - TEST-INFO | 1645: exit 0 07:50:36 INFO - TEST-OK | dromaeo_css | took 1115573ms 07:50:36 INFO - TEST-START | kraken 07:50:36 INFO - operating with platform_type : mac_ 07:50:36 INFO - Initialising browser for kraken test... 07:50:36 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp9pJ_Vy/profile http://localhost:49241/getInfo.html) 07:50:39 INFO - PROCESS | 1652 | 2017-01-17 07:50:39.839 plugin-container[1653:10666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 07:50:39 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:39 INFO - PROCESS | 1652 | 2017-01-17 07:50:39.843 plugin-container[1653:10666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 07:50:39 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:39 INFO - PROCESS | 1652 | 2017-01-17 07:50:39.843 plugin-container[1653:10666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:50:39 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 07:50:39 INFO - PROCESS | 1652 | colorDepth:24 07:50:39 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 07:50:39 INFO - PROCESS | 1652 | __metrics 07:50:39 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3253: TypeError: this.tabs is undefined 07:50:40 INFO - TEST-INFO | 1652: exit 0 07:50:40 INFO - Browser initialized. 07:50:40 INFO - Running cycle 1/1 for kraken test... 07:50:40 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp9pJ_Vy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:43 INFO - PROCESS | 1654 | 2017-01-17 07:50:43.407 plugin-container[1655:10784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 07:50:43 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:43 INFO - PROCESS | 1654 | 2017-01-17 07:50:43.408 plugin-container[1655:10784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 07:50:43 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:50:43 INFO - PROCESS | 1654 | 2017-01-17 07:50:43.408 plugin-container[1655:10784] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:50:53 INFO - PROCESS | 1654 | RSS: Main: 233762816 07:50:53 INFO - PROCESS | 1654 | 07:50:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 07:50:56 INFO - PROCESS | 1654 | RSS: Main: 246435840 07:50:56 INFO - PROCESS | 1654 | 07:50:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 07:51:00 INFO - PROCESS | 1654 | RSS: Main: 254455808 07:51:00 INFO - PROCESS | 1654 | 07:51:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 07:51:04 INFO - PROCESS | 1654 | RSS: Main: 257204224 07:51:04 INFO - PROCESS | 1654 | 07:51:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 07:51:07 INFO - PROCESS | 1654 | RSS: Main: 258371584 07:51:07 INFO - PROCESS | 1654 | 07:51:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:51:09 INFO - PROCESS | 1654 | RSS: Main: 260038656 07:51:09 INFO - PROCESS | 1654 | 07:51:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 07:51:13 INFO - PROCESS | 1654 | RSS: Main: 266104832 07:51:13 INFO - PROCESS | 1654 | 07:51:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 07:51:16 INFO - PROCESS | 1654 | RSS: Main: 265142272 07:51:16 INFO - PROCESS | 1654 | 07:51:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 07:51:20 INFO - PROCESS | 1654 | RSS: Main: 265469952 07:51:20 INFO - PROCESS | 1654 | 07:51:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:51:22 INFO - PROCESS | 1654 | RSS: Main: 264744960 07:51:22 INFO - PROCESS | 1654 | 07:51:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:51:25 INFO - PROCESS | 1654 | RSS: Main: 264724480 07:51:25 INFO - PROCESS | 1654 | 07:51:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:51:28 INFO - PROCESS | 1654 | RSS: Main: 264790016 07:51:28 INFO - PROCESS | 1654 | 07:51:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:32 INFO - PROCESS | 1654 | RSS: Main: 265027584 07:51:32 INFO - PROCESS | 1654 | 07:51:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:35 INFO - PROCESS | 1654 | RSS: Main: 264822784 07:51:35 INFO - PROCESS | 1654 | 07:51:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 07:51:38 INFO - PROCESS | 1654 | RSS: Main: 266891264 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | __start_tp_report 07:51:38 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 07:51:38 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 07:51:38 INFO - PROCESS | 1654 | |i|pagename|runs| 07:51:38 INFO - PROCESS | 1654 | |0;ai-astar;105;112;118;118;116;117;120;117;117;119 07:51:38 INFO - PROCESS | 1654 | |1;audio-beat-detection;159;107;133;183;143;116;119;145;163;100 07:51:38 INFO - PROCESS | 1654 | |2;audio-dft;212;216;250;251;202;198;249;277;197;201 07:51:38 INFO - PROCESS | 1654 | |3;audio-fft;85;58;62;84;62;62;56;59;74;60 07:51:38 INFO - PROCESS | 1654 | |4;audio-oscillator;92;72;87;74;72;72;83;69;76;83 07:51:38 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;89;86;89;85;105;117;88;86;89;86 07:51:38 INFO - PROCESS | 1654 | |6;imaging-darkroom;97;96;96;95;97;119;96;96;101;155 07:51:38 INFO - PROCESS | 1654 | |7;imaging-desaturate;96;91;92;93;135;142;96;93;101;147 07:51:38 INFO - PROCESS | 1654 | |8;json-parse-financial;61;63;62;63;64;63;58;98;96;99 07:51:38 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;68;69;81;77;59;60;65;89;106;108 07:51:38 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;88;94;108;112;79;92;94;117;113;114 07:51:38 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;141;144;147;146;159;174;155;149;204;192 07:51:38 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;247;247;179;177;174;174;178;175;174;175 07:51:38 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;72;63;61;73;80;93;91;93;92;71 07:51:38 INFO - PROCESS | 1654 | __end_tp_report 07:51:38 INFO - PROCESS | 1654 | __start_cc_report 07:51:38 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,929 07:51:38 INFO - PROCESS | 1654 | __end_cc_report 07:51:38 INFO - PROCESS | 1654 | __startTimestamp1484668298086__endTimestamp 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | ------- Summary: start ------- 07:51:38 INFO - PROCESS | 1654 | Number of tests: 14 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.50 stddev:4.38 (3.7%) stddev-sans-first:2.26 07:51:38 INFO - PROCESS | 1654 | Values: 105.0 112.0 118.0 118.0 116.0 117.0 120.0 117.0 117.0 119.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:144.00 stddev:26.72 (18.6%) stddev-sans-first:27.11 07:51:38 INFO - PROCESS | 1654 | Values: 159.0 107.0 133.0 183.0 143.0 116.0 119.0 145.0 163.0 100.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:225.30 Median:232.50 stddev:28.77 (12.4%) stddev-sans-first:30.11 07:51:38 INFO - PROCESS | 1654 | Values: 212.0 216.0 250.0 251.0 202.0 198.0 249.0 277.0 197.0 201.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.20 Median:62.00 stddev:10.78 (17.4%) stddev-sans-first:9.03 07:51:38 INFO - PROCESS | 1654 | Values: 85.0 58.0 62.0 84.0 62.0 62.0 56.0 59.0 74.0 60.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.50 stddev:7.72 (9.7%) stddev-sans-first:6.31 07:51:38 INFO - PROCESS | 1654 | Values: 92.0 72.0 87.0 74.0 72.0 72.0 83.0 69.0 76.0 83.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:92.00 Median:87.00 stddev:10.51 (12.1%) stddev-sans-first:11.09 07:51:38 INFO - PROCESS | 1654 | Values: 89.0 86.0 89.0 85.0 105.0 117.0 88.0 86.0 89.0 86.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:96.00 stddev:19.04 (19.8%) stddev-sans-first:19.99 07:51:38 INFO - PROCESS | 1654 | Values: 97.0 96.0 96.0 95.0 97.0 119.0 96.0 96.0 101.0 155.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:92.50 stddev:22.94 (24.8%) stddev-sans-first:23.87 07:51:38 INFO - PROCESS | 1654 | Values: 96.0 91.0 92.0 93.0 135.0 142.0 96.0 93.0 101.0 147.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:72.70 Median:63.50 stddev:17.32 (27.3%) stddev-sans-first:17.85 07:51:38 INFO - PROCESS | 1654 | Values: 61.0 63.0 62.0 63.0 64.0 63.0 58.0 98.0 96.0 99.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:68.50 stddev:17.79 (26.0%) stddev-sans-first:18.49 07:51:38 INFO - PROCESS | 1654 | Values: 68.0 69.0 81.0 77.0 59.0 60.0 65.0 89.0 106.0 108.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:101.10 Median:83.50 stddev:13.21 (15.8%) stddev-sans-first:13.13 07:51:38 INFO - PROCESS | 1654 | Values: 88.0 94.0 108.0 112.0 79.0 92.0 94.0 117.0 113.0 114.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:161.10 Median:157.00 stddev:21.79 (13.9%) stddev-sans-first:21.86 07:51:38 INFO - PROCESS | 1654 | Values: 141.0 144.0 147.0 146.0 159.0 174.0 155.0 149.0 204.0 192.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:177.50 stddev:30.09 (17.0%) stddev-sans-first:23.82 07:51:38 INFO - PROCESS | 1654 | Values: 247.0 247.0 179.0 177.0 174.0 174.0 178.0 175.0 174.0 175.0 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:85.50 stddev:12.63 (14.8%) stddev-sans-first:13.14 07:51:38 INFO - PROCESS | 1654 | Values: 72.0 63.0 61.0 73.0 80.0 93.0 91.0 93.0 92.0 71.0 07:51:38 INFO - PROCESS | 1654 | -------- Summary: end -------- 07:51:38 INFO - PROCESS | 1654 | 07:51:38 INFO - TEST-INFO | 1654: exit 0 07:51:38 INFO - TEST-OK | kraken | took 62058ms 07:51:38 INFO - SUITE-END | took 1177s 07:51:39 INFO - Completed test suite (00:19:37) 07:51:39 INFO - javascript benchmark 07:51:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7248.57257534806, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2863.742263196313, 3000.91966891919, 3064.739497142743, 3352.68149525795, 3387.3056023515173, 4457.732536047839, 4467.0, 5006.874828129298, 5582.916256256154, 5627.577931655127, 4515.616172625322, 5531.9723401383035, 5544.3346798384255, 5621.297337832771, 5861.824145275648, 5050.242463630448, 5091.949080509185, 5113.079645663779, 5162.328897243353, 5195.740212989354, 5134.127198376266, 5227.634065615409, 5231.319928409302, 5245.0, 5295.391030031538, 5061.544460998491, 5085.79656813727, 5148.099082660538, 5152.0, 5190.870228244286, 5080.13637681595, 5118.795248190105, 5128.692278463304, 5207.6875387476875, 5223.503767142153, 4962.206047032458, 5122.0, 5122.410922743895, 5122.769475373617, 5151.791180730821, 4298.914021719579, 5543.861403464905, 5650.7174641267775, 5689.743961521742, 5698.230738850244, 6067.0, 6118.418750218722, 6119.533019689815, 6123.418275263843, 6129.908051379233, 6153.538484613672, 6246.593971391891, 6256.71844766982, 6272.247330320345, 6274.309825919146, 5738.2868547654325, 5765.308163020464, 5789.392113828072, 5805.071188609844, 5823.039198532237, 6157.599756015845, 6164.445199932027, 6167.907481387782, 6200.906986395207, 6223.253209614875, 6074.3925607438905, 6120.357362476965, 6142.539309551712, 6146.293176284695, 6160.507159427235, 6201.410865967726, 6202.41077097675, 6212.4408803207025, 6228.719707613168, 6250.0, 6052.152698622196, 6115.388461153849, 6122.916604401471, 6130.0, 6135.294441139237, 6173.166622505994, 6176.042713379434, 6180.690965451709, 6192.380761923861, 6195.938040619626, 4946.3817022872145, 4965.776540055706, 4988.251762235694, 4994.525520075587, 4998.525140111684, 4361.127774445124, 4387.319965405367, 4400.9559904401185, 4433.445819272591, 4473.418455600816, 5599.8600034998635, 5774.335951365562, 5781.392953739881, 5804.709764511842, 5832.0, 4985.326980857611, 5209.973950130225, 6197.721102550396, 6245.375462453719, 6283.842903927439, 5571.387147413781, 5667.773289068391, 5750.7987220447085, 5764.7117644117625, 5770.798022069207, 5307.734613269321, 5347.598930080259, 5360.088784906575, 5368.758405871745, 5369.033573956725, 5335.466453354634, 6279.309275979547, 6324.873502529832, 6330.39861213184, 6336.112944187817, 6144.170536977451, 6200.968995154995, 6220.160278362544, 6228.781992630417, 6234.501239900706, 5082.720450375265, 6186.845328866814, 6198.915189841669, 6222.942099843128, 6299.086632438362, 6133.478654314434, 6190.50475961931, 6194.4115309045565, 6201.906971395523, 6208.192934918432, 6147.354527774698, 6235.0, 6240.563160578716, 6253.280872699515, 6253.749850005949, 5710.857228569319, 5771.394003629726, 5772.6536407815665, 6045.395460454095, 6147.938520614915, 5803.912941305799, 5994.220751302477, 6026.8794624106395, 6091.42131497507, 6126.792505495216, 5604.355499117491, 5731.369549349485, 5775.7400916957995, 5861.472467477777, 5974.1934838796205, 5961.791337303173, 5989.401059893976, 6055.72749226277, 6072.666003369857, 6109.419605137505, 5709.0, 5903.2916050072545, 5937.050071988461, 5951.791687291097, 5968.4926781224085], "value": 5590.822287098502, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.1722606465264, 485.9222524396096, 490.8355700840218, 526.2080568744041, 535.8987281137262, 40028.79985600072, 42200.36699449507, 42326.7883660582, 44393.0, 44667.32999005017, 42853.35719964203, 43220.78389608047, 43272.35091473623, 43307.35038974418, 43393.0, 9343.813123737522, 9577.090176433228, 11089.55641774328, 11444.027257683107, 11465.59870404536, 9508.47703376314, 11104.666859994213, 11118.833217501704, 11268.549258029669, 11286.153538484605, 9510.904890951071, 11240.662780116569, 11250.156238282121, 11280.605178818743, 11330.886691133066, 11169.888301116966, 11197.552097916156, 11210.66368008961, 11241.775164496747, 11253.831192532118, 8441.366897482652, 8558.529280889548, 9967.501624918725, 10341.224408169417, 10864.837027444595, 36980.63019369799, 37259.06852328686, 37354.0, 37401.064973375614, 37420.81289593562, 3878.3988481785086, 3896.902577435558, 3980.2835489611857, 4083.509978802533, 4177.143685544486, 28410.28974275639, 28765.137045888656, 28978.855105724546, 28989.0, 29068.8546557268, 2939.5884576159356, 2953.6898625644426, 3230.0, 3269.2480729432127, 3303.0256074458093, 414608.92695536633, 479558.0, 483861.0, 485945.0, 486707.0, 55073.44926550683, 55125.72437137869, 55514.44485555174, 55566.72216638891, 55827.441725583034, 57660.0, 58063.709681452165, 58139.70930145322, 58174.709126454945, 58293.70853145707, 13767.862321376659, 15325.46360877364, 15568.143752093743, 15595.688086238211, 15617.609559761097, 10812.135029197645, 11977.760444791056, 12156.270623762603, 12162.878371216351, 12332.630021099381, 17853.37513187032, 17893.284268629108, 18037.458876233733, 18047.368342107962, 18056.09719514019, 7549.2073332299315, 7845.490043147177, 7863.33161681252, 7923.881141782877, 7955.4828936118965, 870.6996086350199, 884.9778755531034, 895.5477483870734, 909.8043920557112, 923.879895613566, 23013.88493057524, 27438.451230975268, 30574.847125764674, 30665.84667076695, 31183.0, 906.4380084347615, 1035.2597892506894, 1043.8173319669027, 1066.9573217071231, 1077.8221593437152, 13102.410391532403, 14442.494512692216, 14559.272036398137, 14622.049566778125, 14860.479883204036, 644791.2083516606, 645788.0, 649137.0, 653153.0, 653766.0, 52704.945901081766, 52883.0, 53066.46933530692, 53081.0, 53095.46904530905, 1386.334559411488, 1450.9564713058414, 1451.0060608483222, 1451.6298343922233, 1459.773735071087, 27306.863465682545, 27496.58755118715, 27706.861465692542, 27811.860940695977, 27977.16068517948, 37674.0, 46997.0, 47045.82385440391, 47221.7638911817, 47226.0, 582667.0, 583471.0, 585973.0, 586697.0, 586780.0, 24643.507129857662, 24668.519888806728, 24958.0, 24959.75040249574, 24988.625170622818, 42552.0, 42593.14813703646, 42627.14745705131, 43675.0, 46115.769421152676, 35548.57805687847, 35818.462723059696, 35855.64144358523, 36698.8165059173, 37192.07019824526, 32747.67252327446, 35583.0, 35911.52262352438, 36134.0, 36835.447468287464, 35321.646783532866, 35638.465423018155, 36352.09119772027, 36431.45352819763, 36576.08559786026, 24533.0, 31361.0, 32439.67560324461, 32453.35093298168, 32599.022029339158, 23362.76637233606, 23637.881810590836, 23756.762432376145, 23796.524069518167, 24359.391015224763], "value": 19580.09186213439, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.06920484234456, 370.353732736375, 382.86791057085316, 394.6487626012847, 413.76903711458414, 19559.119839607218, 20944.16223351068, 21352.0, 21394.786052139472, 21509.03209355579, 17558.29766809329, 21638.24266150685, 21751.564968700615, 21798.128074876986, 22159.00284487198, 5712.628679135853, 5807.303123625171, 5942.64344139353, 5982.581219314649, 6030.216071910668, 4093.7543747375103, 5810.070388737803, 5918.940810591882, 5926.081457374114, 5960.254968128984, 4153.065560248935, 4332.436783218193, 4461.866144015668, 6621.536492445531, 6661.933380666204, 6335.936640633581, 6599.967000164993, 6614.801555953329, 6617.702203400859, 6617.834554136137, 5685.914711279334, 5723.713814309268, 5765.625234359753, 5766.279215098113, 5783.768649254067, 22527.0, 24831.00675972959, 25828.87085564579, 25901.0, 26149.869250653624, 2476.7770900618852, 2680.3969106950976, 2766.336079340964, 2769.9307517312027, 2776.5141100307364, 8804.723804199708, 10297.330673506198, 10579.0, 11937.701557461047, 15388.30752616135, 1775.3786174838833, 1806.0608483588592, 1816.2008716164978, 1828.3235202974984, 1856.60083082138, 142764.0, 144324.55675443215, 144847.30916145066, 146000.0, 146360.0, 10608.89391106079, 11213.383263920563, 11861.169718119821, 12106.33415162157, 12143.878561214451, 15571.299291531908, 15760.448384306494, 15800.68398632021, 15812.525624231292, 15819.841801582066, 8587.227149556446, 8740.169683880022, 8759.605817738217, 8777.780555486164, 8784.736457906272, 8441.11368306319, 8443.957780211182, 8501.489910605384, 8620.655173793104, 8630.611622476878, 4816.109019831342, 4823.252395878644, 4827.131116399011, 4835.661503694778, 4889.364382630235, 2020.7170996060563, 2061.062216691432, 2068.389825001598, 2069.317125348631, 2073.844461665381, 856.3748463621591, 896.1620884473039, 918.4626993209048, 920.8204400141908, 933.2673851026912, 20793.58412831735, 21251.68122478164, 21328.466788330104, 21370.465738356666, 21437.785622143892, 908.418612088264, 933.2114363362947, 940.6707652321769, 941.505709502503, 943.5423819447562, 10702.143828493701, 10974.0, 11044.282121662067, 11044.33733975964, 11086.501107450184, 519656.0, 667674.0, 677634.0, 677800.0, 685378.0, 104830.4758476218, 149373.0, 152335.0, 152940.0, 159128.0, 1035.1873779083382, 1060.8754719996828, 1081.1242893256488, 1082.0532034469838, 1082.9458527073457, 16351.427700030441, 16801.579960501087, 17590.0, 19111.713324300425, 19898.60202795965, 58300.0, 59384.70307648607, 59629.10556341572, 60131.09803352866, 60285.0, 74207.0, 86890.0, 87363.56318218622, 87610.56194718985, 87757.24485510381, 7285.526440781332, 8065.919340806517, 8159.877601836096, 8587.398882078194, 8703.303735701254, 11372.943135284271, 12249.713780053322, 13216.14095083855, 13402.865971340552, 13648.317584120952, 9477.763055923657, 11500.712482187677, 11596.188266821242, 11624.500732342587, 11633.917011991998, 11149.55401783952, 11189.944050279697, 11211.215214934877, 11507.136964727511, 11557.190996630155, 9211.631534738803, 11596.94201528987, 11776.293422394683, 11834.218958544563, 12002.919737223692, 8296.336293096658, 8522.531260780717, 8539.0, 8551.486910785372, 8672.78318042054, 11199.552017919192, 11338.0, 11461.541538338373, 11527.942360288145, 11609.0], "value": 9923.240176669588, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.5606807698437, 1434.9210793406364, 1452.7433769789136, 1454.112991075444, 1465.2746890289304, 7470.028896243494, 7718.8456230875345, 8038.316743076838, 8487.745367638965, 8495.405321627488, 7978.960105199466, 8226.465279756812, 8262.586870656472, 8326.0, 8369.20492553208, 2269.149069099092, 2319.512902290521, 2321.849079809807, 2331.114176612885, 2336.2874323331375, 2287.165184707578, 2315.166540045582, 2323.6979192704925, 2326.5579539887453, 2333.46330344021, 2194.4952660888007, 2200.933971980843, 2210.403191138392, 2214.3689048621145, 2214.9667754983607, 2264.6942662740485, 2267.9206227781947, 2286.0969916882823, 2287.325239054483, 2292.0, 2254.1096266974455, 2318.4899322149267, 2325.930222093323, 2326.8487548309477, 2333.1484008336834, 8345.41582089254, 8371.95814020926, 8409.032961209477, 8429.95785021077, 8432.578371081483, 5022.0, 5146.871328216787, 5194.71429071401, 5201.713905735183, 5206.947930520684, 3253.398121347558, 3328.5839270091237, 3471.6701913318193, 3534.6288639692975, 3537.575490941078, 1188.9464974076102, 1195.276857501208, 1204.566356111799, 1207.1007099710685, 1239.3183748938047, 10488.895111048869, 10583.15334773224, 10646.680599582025, 10680.46597670121, 10691.198160138021, 4389.473263208435, 4611.515790841913, 4682.41469816273, 4689.484156742756, 4708.788104535304, 9422.952885235667, 9423.38747981379, 9443.622255109856, 9453.007434219307, 9557.99641037681, 4583.862484125481, 5166.4058633257655, 5211.791528338824, 5216.347956505437, 5221.164613661796, 3780.0, 4147.212029714345, 4286.335617979218, 4698.083873644675, 4701.694389864648, 3156.9684303157132, 3223.903282901517, 3822.5604055533413, 3924.5486769021927, 3953.486046813896, 1142.1433924556582, 1243.3161761031397, 1260.8928241099427, 1261.6656586004717, 1273.9745205095846, 788.1094363369356, 849.8895143631288, 903.9909600904039, 908.2098574240321, 934.4066517761271, 5321.255024296602, 5434.103372943499, 5585.69278689676, 5684.602077854511, 5736.4550367715, 947.3747326764302, 987.2151639446657, 991.509202944547, 1001.4692213127055, 1041.2763129624989, 3444.6727560881677, 3576.159602493412, 3656.195636959918, 3701.296753616858, 3713.535808023997, 9694.660686875926, 9985.0, 9991.750206244902, 10023.749406264902, 10038.64864729731, 8653.61058752345, 9814.901850981541, 9893.60425582983, 9897.554610042565, 9918.305718599773, 6163.753449862045, 7794.337481314152, 7804.219578042151, 7806.765797026098, 7811.336036436966, 5539.501444869981, 5585.245991791218, 5687.630304030225, 5821.546516914648, 5852.122181672783, 9192.908070919206, 9352.345335826427, 9361.672341468016, 9377.343585948918, 9418.293627977791, 7805.180456052033, 9109.817803644022, 9349.0, 9416.0, 9486.292467355943, 6240.251169859464, 7100.9289907100265, 7120.465965052538, 7165.498415110892, 7248.891266630899, 6716.83207919806, 7884.448088633741, 7978.321842643209, 7990.92009079925, 7993.960030199812, 7066.505344625827, 7150.606716630427, 7196.532225405541, 7228.746993855189, 7296.124465064227, 5204.453532379197, 7014.0, 7028.789136325919, 7067.25793791665, 7152.427805775422, 6308.243010838729, 6389.7124629390855, 6409.262934762562, 6433.260175079926, 6437.356264373525, 4940.110780059624, 4998.2002879539095, 4998.575121114746, 5050.949490505195, 5089.13484707608, 4926.408830940167, 4949.678270912379, 4978.551930326288, 5026.773795179299, 5226.895462090661], "value": 4378.877253301228, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.8860838577147, 342.4230172159911, 350.71592010471517, 367.8583745258075, 368.53012409178314, 4767.523247675231, 5054.772535235914, 5103.413107492639, 5342.385625653046, 5450.5367043801225, 4505.70712903661, 5368.46315368463, 5391.272178255935, 5396.892062158764, 5426.403095659484, 4219.130990926169, 4231.048014196812, 4236.0090757344, 4237.889247429988, 4243.986769393734, 4066.0762364318425, 4126.834926602947, 4184.518780340254, 4251.383549385338, 4255.276602977486, 3451.4822776583587, 3459.183928437565, 3680.6871415929677, 4054.533728621202, 4169.9791501042455, 3536.734744894143, 3617.3127105849803, 3961.771850726284, 4157.877373109243, 4220.725652832556, 3100.705432983863, 3705.5368078990127, 3737.0, 3741.9232326059027, 3789.8484060637543, 4859.829905953275, 5015.974920125413, 5235.319408476912, 5272.868178295535, 5275.762590683428, 3924.077841707225, 4303.730399532145, 4651.580908241895, 4655.647933487883, 4731.36126622905, 5021.949780502185, 5272.657277277003, 5525.530329921921, 5550.833474995757, 5696.458836410535, 3854.7879866607327, 3870.9419358709642, 3876.8757060452435, 3879.476270703475, 3901.51231096113, 4997.301457213137, 5609.495145436889, 6012.669303188323, 6016.947034269029, 6057.182280392135, 3685.4287585424304, 3899.4150877368056, 3906.3945088511327, 3909.7458665186673, 3928.860630417147, 3487.459443786217, 3966.667998880896, 3973.2848087344555, 3982.3030969580786, 3982.8406863725704, 4632.420947381577, 4641.767911604406, 4653.86038418848, 4673.485916549177, 4694.741789201558, 4748.002919386899, 5280.313559237275, 5500.80747173847, 5685.971570142123, 5789.8552536186935, 1082.9928166804948, 1124.0782558302278, 1155.5839897636845, 1275.024606176276, 1306.8693130686854, 836.506461187902, 872.5297873083874, 874.8993865705526, 889.9243564297108, 891.4383938118943, 4945.887175385509, 5118.744062796845, 5156.1750119980625, 5172.069027574998, 5257.7896884124975, 5121.923171152435, 5259.0, 5640.379558248589, 5641.774329026875, 5660.094384898396, 4725.810967561259, 4847.903041939141, 5098.439171691111, 5150.072986862326, 5162.850661096728, 2544.5572250639593, 2609.395221938532, 2767.6540432445945, 2906.2589039794716, 2949.808262462933, 5165.74171291434, 5477.808276710296, 5587.469190426903, 5771.585961439474, 5857.707114644336, 4825.879353016203, 5149.356330458692, 5376.166694162411, 5713.714314284352, 5825.0, 5613.9860000100125, 5650.830475085754, 5840.620359676607, 5841.94158058414, 5862.6842397253695, 5258.898059513585, 5602.747876345493, 5607.523335531996, 5623.662580245198, 5654.578979717212, 4647.883802904955, 4826.91394436249, 5016.949830501649, 5033.446320904771, 5505.421930697219, 5135.281060651437, 5231.058409486177, 5437.972810135924, 5635.985522605806, 5761.625494342854, 3387.3056023514628, 3583.3695668471305, 3748.850045998239, 3854.7301688881507, 3905.047476261869, 5325.813596524103, 5783.652980821164, 5792.82621521355, 5828.388019257917, 5832.708364581838, 4555.954440455553, 4898.92651610219, 5546.750396232099, 5574.829285850017, 5593.888122237614, 4486.461624605068, 4521.977390113028, 5173.3533308336455, 5617.719114044363, 5645.604807663424, 5090.872728181825, 5470.671759694431, 5494.945050549443, 5505.311836020497, 5527.806526771544, 4688.26534182341, 5507.834764957058, 5577.52591029767, 5589.329280486368, 5594.384617692132, 4561.8139283785795, 5483.917741233805, 5492.642978206404, 5514.669119852661, 5516.806911758069], "value": 4062.611740793342, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.3684529908581, 695.8956156576514, 709.7090193020861, 711.6797441151476, 815.4699445360515, 14783.482578109768, 15106.773398399033, 15328.770068448968, 15514.767278490817, 15593.376264949417, 14074.437022519112, 14251.216183109926, 14494.0, 14525.854741452582, 14535.49125780598, 857.661223816592, 877.6928075173702, 886.3883920095113, 893.2675206330812, 893.307686542928, 625.5746092656991, 680.5270337115707, 860.3633311349588, 886.441541828647, 886.9113088691144, 925.8842644669417, 937.6577549194564, 939.6100618243421, 941.6892425499568, 943.4009404028423, 924.4453328003211, 932.1144912333276, 933.6732143749701, 936.1901954809109, 936.241644268145, 600.3996003996004, 683.7846078485261, 696.0916004613977, 697.135551915626, 697.5256825358754, 14771.778423323658, 15281.847181528154, 15666.295016724274, 15783.526494205193, 15784.0, 13844.653883652889, 13896.51362202318, 14221.0, 14257.358418871176, 14491.78262326066, 10223.182145428422, 10269.229807764448, 10383.532741026673, 10542.262041657086, 10810.405427701473, 3641.2236412236202, 3648.3980143276326, 3679.5400574928135, 3693.3351996640595, 3697.0, 74302.0, 97616.99999999929, 104076.47961760219, 106317.93682063236, 108426.0, 10719.517621707042, 10745.570177192825, 10749.78500429987, 10850.9884715731, 11089.445527723581, 18176.545586360447, 22645.0, 22655.88672056629, 22802.885985570298, 22842.31473055811, 5752.712364381764, 7587.658555365022, 7907.683692652229, 7948.642311096015, 8091.150429204967, 3854.325493038707, 4204.0, 4398.714083584557, 4403.185410699031, 4484.596386325246, 3545.2377738786286, 3648.1244501319493, 3659.2681463707363, 3675.9448608270895, 3718.8326525305883, 656.8456412743002, 669.3340126573993, 675.4596322941627, 682.6245564939263, 683.3132701634826, 628.6668065925068, 641.9935800642028, 685.9931400685929, 686.5571706249441, 715.8747219236612, 7913.802154946173, 9859.26055545837, 10191.592336306612, 10217.33587316822, 10443.686689399177, 660.2506155513527, 697.5431092634333, 704.7427688893517, 707.5719189890145, 709.9503034787618, 4676.532346765364, 4868.269759536098, 4872.756362181947, 4874.025194961022, 4896.167651499182, 102916.0, 105726.47136764268, 106633.0, 106729.0, 108313.0, 78314.60842695751, 91767.0823291772, 92473.0, 93676.0, 93967.53016234875, 82021.0, 83000.0, 83223.0, 83819.5809020951, 83954.0, 9446.38598491096, 9448.244140468883, 9456.952715236655, 9482.0, 9485.478298693639, 52618.473815262885, 53337.73331133319, 53714.38856834301, 54368.640783980714, 54945.72527137339, 69392.0, 71624.28375716385, 71994.0, 72156.0, 72485.91271130832, 11428.485718142538, 11482.0, 11600.767984640092, 11777.35224562623, 12850.0, 14466.927665361605, 14718.852811472176, 14837.480688175861, 14941.701165976838, 15691.843081569496, 11471.483783229942, 13732.382042808298, 13759.17444953306, 13834.792478112635, 13991.440342386191, 13690.52083177084, 13782.173069615856, 13830.723385532032, 14009.751034238308, 14172.433102675777, 13585.86414135846, 13775.586732398044, 13827.101238419471, 13973.371198295898, 14004.649883752987, 10691.411972341595, 10861.490177383303, 10865.719113408148, 11323.547058117583, 11410.885891140982, 9135.766671499263, 9765.462899540593, 10067.244956628429, 10698.251122421667, 11308.587840217513], "value": 7505.0721387854355, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 112.0, 118.0, 118.0, 116.0, 117.0, 120.0, 117.0, 117.0, 119.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 107.0, 133.0, 183.0, 143.0, 116.0, 119.0, 145.0, 163.0, 100.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 216.0, 250.0, 251.0, 202.0, 198.0, 249.0, 277.0, 197.0, 201.0], "value": 225.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 62.0, 84.0, 62.0, 62.0, 56.0, 59.0, 74.0, 60.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 87.0, 74.0, 72.0, 72.0, 83.0, 69.0, 76.0, 83.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 105.0, 117.0, 88.0, 86.0, 89.0, 86.0], "value": 92.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 95.0, 97.0, 119.0, 96.0, 96.0, 101.0, 155.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 91.0, 92.0, 93.0, 135.0, 142.0, 96.0, 93.0, 101.0, 147.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 63.0, 64.0, 63.0, 58.0, 98.0, 96.0, 99.0], "value": 72.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 81.0, 77.0, 59.0, 60.0, 65.0, 89.0, 106.0, 108.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 108.0, 112.0, 79.0, 92.0, 94.0, 117.0, 113.0, 114.0], "value": 101.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 144.0, 147.0, 146.0, 159.0, 174.0, 155.0, 149.0, 204.0, 192.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [247.0, 247.0, 179.0, 177.0, 174.0, 174.0, 178.0, 175.0, 174.0, 175.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 63.0, 61.0, 73.0, 80.0, 93.0, 91.0, 93.0, 92.0, 71.0], "value": 78.9, "unit": "score"}]}]} 07:51:39 INFO - Return code: 0 07:51:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:51:39 ERROR - # TBPL SUCCESS # 07:51:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:51:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:51:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:51:39 INFO - 2017-01-17 07:51:39.411 screenresolution[1659:11172] starting screenresolution argv=screenresolution get 07:51:39 INFO - 2017-01-17 07:51:39.414 screenresolution[1659:11172] Display 0: 1600x1200x32@60 07:51:39 INFO - 2017-01-17 07:51:39.421 screenresolution[1660:11175] starting screenresolution argv=screenresolution list 07:51:39 INFO - Available Modes on Display 0 07:51:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:51:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:51:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:51:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:51:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:51:39 INFO - Intel Iris: 07:51:39 INFO - Chipset Model: Intel Iris 07:51:39 INFO - Type: GPU 07:51:39 INFO - Bus: Built-In 07:51:39 INFO - VRAM (Dynamic, Max): 1536 MB 07:51:39 INFO - Vendor: Intel (0x8086) 07:51:39 INFO - Device ID: 0x0a2e 07:51:39 INFO - Revision ID: 0x0009 07:51:39 INFO - Displays: 07:51:39 INFO - Display: 07:51:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:51:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:51:39 INFO - Display Serial Number: 1600x1200 60 07:51:39 INFO - Main Display: Yes 07:51:39 INFO - Mirror: Off 07:51:39 INFO - Online: Yes 07:51:39 INFO - Rotation: Supported 07:51:39 INFO - Return code: 0 07:51:39 INFO - Running post-action listener: _resource_record_post_action 07:51:39 INFO - [mozharness: 2017-01-17 15:51:39.656602Z] Finished run-tests step (success) 07:51:39 INFO - Running post-run listener: _resource_record_post_run 07:51:39 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 34415104; Write bytes: 557480448; Read time: 319; Write time: 1712 07:51:39 INFO - TinderboxPrint: CPU usage
54.5% 07:51:39 INFO - TinderboxPrint: I/O read bytes / time
34,415,104 / 319 07:51:39 INFO - TinderboxPrint: I/O write bytes / time
557,480,448 / 1,712 07:51:39 INFO - TinderboxPrint: CPU idle
2,187.1 (45.5%) 07:51:39 INFO - TinderboxPrint: CPU system
967.1 (20.1%) 07:51:39 INFO - TinderboxPrint: CPU user
1,647.5 (34.3%) 07:51:39 INFO - TinderboxPrint: Swap in / out
281,907,200 / 0 07:51:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245721600; Write bytes: 238039040; Read time: 17783; Write time: 463 07:51:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32301568; Write bytes: 300876288; Read time: 293; Write time: 1128 07:51:40 INFO - Running post-run listener: _upload_blobber_files 07:51:40 INFO - Blob upload gear active. 07:51:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:40 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 07:51:40 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'] 07:51:40 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 07:51:40 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:51:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:51:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:42 INFO - (blobuploader) - INFO - Done attempting. 07:51:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:51:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:51:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:43 INFO - (blobuploader) - INFO - Done attempting. 07:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:51:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:44 INFO - (blobuploader) - INFO - Done attempting. 07:51:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:51:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:51:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:46 INFO - (blobuploader) - INFO - Done attempting. 07:51:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:46 INFO - Return code: 0 07:51:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c56aa59be7a09cc54ccaf2a02e9c8391a09c01b7fc293cf74fc0d4910fe8aab89312955bb3be367aabc7de058e8729712c33af51ba84f262892ea773eb3566a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd7a8e8c8a466e5fde8c28473c1d024c4810e9a87f519783796b53738c263bb52f4eadaffd9dd428037ebbf7cb76274280a3348847b9869d2ee1ea9399aa955b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2abbc5a32be0afdca94d6ce4259db719f6957cdceef28f3eb7d7761cea068c35b233bc54166d4ae48923995f9841797e73ee9d39d56c33cbb43d6a0abbf3726d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86c0b1eaba167b436057e9470df181a9de11927512224e539f8a4775f4154b732a732611dfc3a56bcd9d9e1002cf4c385505f7a1d43b6098d06d56b28de25504"} 07:51:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:46 INFO - Contents: 07:51:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c56aa59be7a09cc54ccaf2a02e9c8391a09c01b7fc293cf74fc0d4910fe8aab89312955bb3be367aabc7de058e8729712c33af51ba84f262892ea773eb3566a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd7a8e8c8a466e5fde8c28473c1d024c4810e9a87f519783796b53738c263bb52f4eadaffd9dd428037ebbf7cb76274280a3348847b9869d2ee1ea9399aa955b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2abbc5a32be0afdca94d6ce4259db719f6957cdceef28f3eb7d7761cea068c35b233bc54166d4ae48923995f9841797e73ee9d39d56c33cbb43d6a0abbf3726d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86c0b1eaba167b436057e9470df181a9de11927512224e539f8a4775f4154b732a732611dfc3a56bcd9d9e1002cf4c385505f7a1d43b6098d06d56b28de25504"} 07:51:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:46 INFO - Copying logs to upload dir... 07:51:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.358412 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-17 07:51:46.959877) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 07:51:46.964797) ========= 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.xYFwPaudfR/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.sOutSvkFlV/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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/5c56aa59be7a09cc54ccaf2a02e9c8391a09c01b7fc293cf74fc0d4910fe8aab89312955bb3be367aabc7de058e8729712c33af51ba84f262892ea773eb3566a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd7a8e8c8a466e5fde8c28473c1d024c4810e9a87f519783796b53738c263bb52f4eadaffd9dd428037ebbf7cb76274280a3348847b9869d2ee1ea9399aa955b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2abbc5a32be0afdca94d6ce4259db719f6957cdceef28f3eb7d7761cea068c35b233bc54166d4ae48923995f9841797e73ee9d39d56c33cbb43d6a0abbf3726d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86c0b1eaba167b436057e9470df181a9de11927512224e539f8a4775f4154b732a732611dfc3a56bcd9d9e1002cf4c385505f7a1d43b6098d06d56b28de25504"} build_url:https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010618 build_url: 'https://queue.taskcluster.net/v1/task/J-xXnEkfTzmpme_BlJX-AQ/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/5c56aa59be7a09cc54ccaf2a02e9c8391a09c01b7fc293cf74fc0d4910fe8aab89312955bb3be367aabc7de058e8729712c33af51ba84f262892ea773eb3566a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd7a8e8c8a466e5fde8c28473c1d024c4810e9a87f519783796b53738c263bb52f4eadaffd9dd428037ebbf7cb76274280a3348847b9869d2ee1ea9399aa955b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2abbc5a32be0afdca94d6ce4259db719f6957cdceef28f3eb7d7761cea068c35b233bc54166d4ae48923995f9841797e73ee9d39d56c33cbb43d6a0abbf3726d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86c0b1eaba167b436057e9470df181a9de11927512224e539f8a4775f4154b732a732611dfc3a56bcd9d9e1002cf4c385505f7a1d43b6098d06d56b28de25504"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 07:51:46.998404) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 07:51:46.998714) ========= 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.xYFwPaudfR/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.sOutSvkFlV/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005246 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 07:51:47.047346) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 07:51:47.047730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 07:51:47.048090) ========= ========= Total master_lag: 0.18 =========