builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0219 starttime: 1482365742.06 results: success (0) buildid: 20161221133153 builduid: 7aac1914e8eb4f528158b5cadd0348de revision: 38e67fedc9a71c3b726cf875ac114ba5a6593fc8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.063762) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.064154) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.064436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.093390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.093729) ========= 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.ud023hzEJN/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.Cy8OWzHmUq/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 16:15:42-- 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% 8.22M=0.001s 2016-12-21 16:15:42 (8.22 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118883 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.537322) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.537657) ========= 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.ud023hzEJN/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.Cy8OWzHmUq/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.019596 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:42.583081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 16:15:42.583448) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 38e67fedc9a71c3b726cf875ac114ba5a6593fc8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 38e67fedc9a71c3b726cf875ac114ba5a6593fc8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ud023hzEJN/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.Cy8OWzHmUq/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 16:15:42,664 truncating revision to first 12 chars 2016-12-21 16:15:42,664 Setting DEBUG logging. 2016-12-21 16:15:42,664 attempt 1/10 2016-12-21 16:15:42,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/38e67fedc9a7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 16:15:43,454 unpacking tar archive at: graphics-38e67fedc9a7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036819 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 16:15:43.646915) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:43.647260) ========= script_repo_revision: 38e67fedc9a71c3b726cf875ac114ba5a6593fc8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:43.647675) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:43.647977) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 16:15:43.669577) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-21 16:15:43.669967) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ud023hzEJN/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.Cy8OWzHmUq/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:15:43 INFO - MultiFileLogger online at 20161221 16:15:43 in /builds/slave/test 16:15:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 16:15:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:15:43 INFO - {'append_to_log': False, 16:15:43 INFO - 'base_work_dir': '/builds/slave/test', 16:15:43 INFO - 'blob_upload_branch': 'Graphics', 16:15:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:15:43 INFO - 'branch': 'Graphics', 16:15:43 INFO - 'buildbot_json_path': 'buildprops.json', 16:15:43 INFO - 'config_files': ('talos/mac_config.py',), 16:15:43 INFO - 'default_actions': ('clobber', 16:15:43 INFO - 'read-buildbot-config', 16:15:43 INFO - 'download-and-extract', 16:15:43 INFO - 'populate-webroot', 16:15:43 INFO - 'create-virtualenv', 16:15:43 INFO - 'install', 16:15:43 INFO - 'run-tests'), 16:15:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:15:43 INFO - 'download_minidump_stackwalk': True, 16:15:43 INFO - 'download_symbols': 'ondemand', 16:15:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:15:43 INFO - 'tooltool.py': '/tools/tooltool.py', 16:15:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:15:43 INFO - '/tools/misc-python/virtualenv.py')}, 16:15:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:15:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:15:43 INFO - 'installer_path': 'installer.exe', 16:15:43 INFO - 'log_level': 'info', 16:15:43 INFO - 'log_name': 'talos', 16:15:43 INFO - 'log_to_console': True, 16:15:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:15:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:15:43 INFO - 'opt_config_files': (), 16:15:43 INFO - 'pip_index': False, 16:15:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:15:43 INFO - 'cmd': ('bash', 16:15:43 INFO - '-c', 16:15:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:15:43 INFO - 'enabled': True, 16:15:43 INFO - 'halt_on_failure': False, 16:15:43 INFO - 'name': 'check_screen_resolution'},), 16:15:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:15:43 INFO - 'cmd': ('bash', 16:15:43 INFO - '-c', 16:15:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:15:43 INFO - 'enabled': True, 16:15:43 INFO - 'halt_on_failure': False, 16:15:43 INFO - 'name': 'check_screen_resolution'},), 16:15:43 INFO - 'run_cmd_checks_enabled': True, 16:15:43 INFO - 'sps_profile': False, 16:15:43 INFO - 'sps_profile_interval': 0, 16:15:43 INFO - 'suite': 'dromaeojs-e10s', 16:15:43 INFO - 'system_bits': '32', 16:15:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:15:43 INFO - 'title': 't-yosemite-r7-0219', 16:15:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:15:43 INFO - 'use_talos_json': True, 16:15:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:15:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:15:43 INFO - 'work_dir': 'build'} 16:15:43 INFO - [mozharness: 2016-12-22 00:15:43.866371Z] Running clobber step. 16:15:43 INFO - Running pre-action listener: _resource_record_pre_action 16:15:43 INFO - Running main action method: clobber 16:15:43 INFO - rmtree: /builds/slave/test/build 16:15:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:15:45 INFO - Running post-action listener: _resource_record_post_action 16:15:45 INFO - [mozharness: 2016-12-22 00:15:45.350130Z] Finished clobber step (success) 16:15:45 INFO - [mozharness: 2016-12-22 00:15:45.350261Z] Running read-buildbot-config step. 16:15:45 INFO - Running pre-action listener: _resource_record_pre_action 16:15:45 INFO - Running main action method: read_buildbot_config 16:15:45 INFO - Using buildbot properties: 16:15:45 INFO - { 16:15:45 INFO - "project": "", 16:15:45 INFO - "product": "firefox", 16:15:45 INFO - "script_repo_revision": "default", 16:15:45 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 16:15:45 INFO - "repository": "", 16:15:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 16:15:45 INFO - "buildid": "20161221133153", 16:15:45 INFO - "pgo_build": "False", 16:15:45 INFO - "basedir": "/builds/slave/test", 16:15:45 INFO - "buildnumber": 15, 16:15:45 INFO - "slavename": "t-yosemite-r7-0219", 16:15:45 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:15:45 INFO - "platform": "yosemite_r7", 16:15:45 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 16:15:45 INFO - "branch": "graphics", 16:15:45 INFO - "repo_path": "projects/graphics", 16:15:45 INFO - "revision": "38e67fedc9a71c3b726cf875ac114ba5a6593fc8", 16:15:45 INFO - "stage_platform": "macosx64", 16:15:45 INFO - "builduid": "7aac1914e8eb4f528158b5cadd0348de", 16:15:45 INFO - "slavebuilddir": "test" 16:15:45 INFO - } 16:15:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:15:45 INFO - Running post-action listener: _resource_record_post_action 16:15:45 INFO - [mozharness: 2016-12-22 00:15:45.353568Z] Finished read-buildbot-config step (success) 16:15:45 INFO - [mozharness: 2016-12-22 00:15:45.353707Z] Running download-and-extract step. 16:15:45 INFO - Running pre-action listener: _resource_record_pre_action 16:15:45 INFO - Running main action method: download_and_extract 16:15:45 INFO - mkdir: /builds/slave/test/build/tests 16:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:45 INFO - trying https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:15:45 INFO - Downloading https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/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 16:15:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/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 16:15:45 INFO - Downloaded 1199 bytes. 16:15:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:15:45 INFO - Using the following test package requirements: 16:15:45 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:15:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:15:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:15:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'jsshell-mac.zip'], 16:15:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:15:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:15:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:15:45 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:15:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:15:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:15:45 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:15:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:15:46 INFO - Expected file size: 16276038 16:15:46 INFO - Obtained file size: 16276038 16:15:53 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 16:15:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:15:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:15:53 INFO - Fetch https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:15:54 INFO - Expected file size: 16276038 16:15:54 INFO - Obtained file size: 16276038 16:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:16:01 INFO - Expected file size: 14409734 16:16:01 INFO - Obtained file size: 14409734 16:16: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')]} 16:16:01 INFO - trying https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:16:01 INFO - Downloading https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/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 16:16:03 INFO - Downloaded 69657797 bytes. 16:16:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:16:03 INFO - mkdir: /builds/slave/test/properties 16:16:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:03 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:03 INFO - Contents: 16:16:03 INFO - build_url:https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:16:04 INFO - Running post-action listener: _resource_record_post_action 16:16:04 INFO - Running post-action listener: set_extra_try_arguments 16:16:04 INFO - [mozharness: 2016-12-22 00:16:04.445350Z] Finished download-and-extract step (success) 16:16:04 INFO - [mozharness: 2016-12-22 00:16:04.445491Z] Running populate-webroot step. 16:16:04 INFO - Running pre-action listener: _resource_record_pre_action 16:16:04 INFO - Running main action method: populate_webroot 16:16:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:04 INFO - '--tppagecycles', 16:16:04 INFO - '7'], 16:16:04 INFO - 'tests': ['tsvgm']}}, 16:16:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['damp', 'tps']}, 16:16:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'tests': ['damp', 'tps']}, 16:16:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['dromaeo_dom']}, 16:16:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['basic_compositor_video']}, 16:16:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:16:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['a11yr', 16:16:04 INFO - 'ts_paint', 16:16:04 INFO - 'tpaint', 16:16:04 INFO - 'sessionrestore', 16:16:04 INFO - 'sessionrestore_no_auto_restore', 16:16:04 INFO - 'tabpaint']}, 16:16:04 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:04 INFO - 'ts_paint', 16:16:04 INFO - 'tpaint', 16:16:04 INFO - 'sessionrestore', 16:16:04 INFO - 'sessionrestore_no_auto_restore', 16:16:04 INFO - 'tabpaint']}, 16:16:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:04 INFO - 'ts_paint', 16:16:04 INFO - 'tpaint', 16:16:04 INFO - 'sessionrestore', 16:16:04 INFO - 'sessionrestore_no_auto_restore', 16:16:04 INFO - 'tabpaint']}, 16:16:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:04 INFO - 'ts_paint', 16:16:04 INFO - 'tpaint', 16:16:04 INFO - 'sessionrestore', 16:16:04 INFO - 'sessionrestore_no_auto_restore', 16:16:04 INFO - 'tabpaint']}, 16:16:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['a11yr', 16:16:04 INFO - 'ts_paint', 16:16:04 INFO - 'tpaint', 16:16:04 INFO - 'sessionrestore', 16:16:04 INFO - 'sessionrestore_no_auto_restore', 16:16:04 INFO - 'tabpaint']}, 16:16:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['a11yr', 16:16:04 INFO - 'ts_paint', 16:16:04 INFO - 'tpaint', 16:16:04 INFO - 'sessionrestore', 16:16:04 INFO - 'sessionrestore_no_auto_restore', 16:16:04 INFO - 'tabpaint']}, 16:16:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['tsvgx', 16:16:04 INFO - 'tsvgr_opacity', 16:16:04 INFO - 'tart', 16:16:04 INFO - 'tscrollx', 16:16:04 INFO - 'cart', 16:16:04 INFO - 'tsvg_static']}, 16:16:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:04 INFO - 'tsvgr_opacity', 16:16:04 INFO - 'tart', 16:16:04 INFO - 'tscrollx', 16:16:04 INFO - 'cart', 16:16:04 INFO - 'tsvg_static']}, 16:16:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'talos_options': ['--disable-e10s'], 16:16:04 INFO - 'tests': ['tp5o']}, 16:16:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:16:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'talos_options': ['--disable-e10s', 16:16:04 INFO - '--xperf_path', 16:16:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:04 INFO - 'tests': ['tp5n']}, 16:16:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:16:04 INFO - 'talos_options': ['--xperf_path', 16:16:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:04 INFO - 'tests': ['tp5n']}}, 16:16:04 INFO - 'talos.zip': {'path': '', 16:16:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:04 INFO - Running post-action listener: _resource_record_post_action 16:16:04 INFO - [mozharness: 2016-12-22 00:16:04.463785Z] Finished populate-webroot step (success) 16:16:04 INFO - [mozharness: 2016-12-22 00:16:04.463873Z] Running create-virtualenv step. 16:16:04 INFO - Running pre-action listener: _resource_record_pre_action 16:16:04 INFO - Running main action method: create_virtualenv 16:16:04 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:04 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 16:16:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:04 INFO - Using real prefix '/tools/python27' 16:16:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:05 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:08 INFO - Installing pip.................done. 16:16:08 INFO - Return code: 0 16:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:16:08 INFO - Reading from file tmpfile_stdout 16:16:08 INFO - Output received: 16:16:08 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) 16:16:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:08 INFO - 'HOME': '/Users/cltbld', 16:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:08 INFO - 'LOGNAME': 'cltbld', 16:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:08 INFO - 'MOZ_NO_REMOTE': '1', 16:16:08 INFO - 'NO_EM_RESTART': '1', 16:16:08 INFO - 'PAGER': '/bin/cat', 16:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:08 INFO - 'PWD': '/builds/slave/test', 16:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:08 INFO - 'SHELL': '/bin/bash', 16:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:08 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:08 INFO - 'USER': 'cltbld', 16:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:08 INFO - 'XPC_FLAGS': '0x0', 16:16:08 INFO - 'XPC_SERVICE_NAME': '0', 16:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:08 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 16:16:08 INFO - Cleaning up... 16:16:08 INFO - Return code: 0 16:16:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:08 INFO - 'HOME': '/Users/cltbld', 16:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:08 INFO - 'LOGNAME': 'cltbld', 16:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:08 INFO - 'MOZ_NO_REMOTE': '1', 16:16:08 INFO - 'NO_EM_RESTART': '1', 16:16:08 INFO - 'PAGER': '/bin/cat', 16:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:08 INFO - 'PWD': '/builds/slave/test', 16:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:08 INFO - 'SHELL': '/bin/bash', 16:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:08 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:08 INFO - 'USER': 'cltbld', 16:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:08 INFO - 'XPC_FLAGS': '0x0', 16:16:08 INFO - 'XPC_SERVICE_NAME': '0', 16:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:08 INFO - Downloading/unpacking psutil>=3.1.1 16:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:12 INFO - Installing collected packages: psutil 16:16:12 INFO - Running setup.py install for psutil 16:16:12 INFO - building 'psutil._psutil_osx' extension 16:16:12 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 16:16:12 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 16:16:12 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 16:16:12 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 16:16:13 INFO - building 'psutil._psutil_posix' extension 16:16:13 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 16:16:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:16:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:16:13 INFO - ^ 16:16:13 INFO - 1 warning generated. 16:16:13 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 16:16:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:13 INFO - Successfully installed psutil 16:16:13 INFO - Cleaning up... 16:16:13 INFO - Return code: 0 16:16:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:13 INFO - 'HOME': '/Users/cltbld', 16:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:13 INFO - 'LOGNAME': 'cltbld', 16:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:13 INFO - 'MOZ_NO_REMOTE': '1', 16:16:13 INFO - 'NO_EM_RESTART': '1', 16:16:13 INFO - 'PAGER': '/bin/cat', 16:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:13 INFO - 'PWD': '/builds/slave/test', 16:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:13 INFO - 'SHELL': '/bin/bash', 16:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:13 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:13 INFO - 'USER': 'cltbld', 16:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:13 INFO - 'XPC_FLAGS': '0x0', 16:16:13 INFO - 'XPC_SERVICE_NAME': '0', 16:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:16:16 INFO - Installing collected packages: mozsystemmonitor 16:16:16 INFO - Running setup.py install for mozsystemmonitor 16:16:16 INFO - Successfully installed mozsystemmonitor 16:16:16 INFO - Cleaning up... 16:16:16 INFO - Return code: 0 16:16:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:16 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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:16 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 16:16:16 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 16:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:16 INFO - 'HOME': '/Users/cltbld', 16:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:16 INFO - 'LOGNAME': 'cltbld', 16:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:16 INFO - 'MOZ_NO_REMOTE': '1', 16:16:16 INFO - 'NO_EM_RESTART': '1', 16:16:16 INFO - 'PAGER': '/bin/cat', 16:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:16 INFO - 'PWD': '/builds/slave/test', 16:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:16 INFO - 'SHELL': '/bin/bash', 16:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:16 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:16 INFO - 'USER': 'cltbld', 16:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:16 INFO - 'XPC_FLAGS': '0x0', 16:16:16 INFO - 'XPC_SERVICE_NAME': '0', 16:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:16:16 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 16:16:16 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 16:16:16 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 16:16:16 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 16:16:16 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 16:16:16 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 16:16:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:19 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:19 INFO - warning: no files found matching '*.txt' 16:16:19 INFO - no previously-included directories found matching 'build' 16:16:19 INFO - no previously-included directories found matching 'dist' 16:16:19 INFO - no previously-included directories found matching '.git*' 16:16:19 INFO - Installing collected packages: jsonschema, functools32 16:16:19 INFO - Running setup.py install for functools32 16:16:20 INFO - warning: no files found matching '*.txt' 16:16:20 INFO - no previously-included directories found matching 'build' 16:16:20 INFO - no previously-included directories found matching 'dist' 16:16:20 INFO - no previously-included directories found matching '.git*' 16:16:20 INFO - Successfully installed jsonschema functools32 16:16:20 INFO - Cleaning up... 16:16:20 INFO - Return code: 0 16:16:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:16:20 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')]} 16:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 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')]} 16:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:20 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 16:16:20 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 16:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:20 INFO - 'HOME': '/Users/cltbld', 16:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:20 INFO - 'LOGNAME': 'cltbld', 16:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:20 INFO - 'MOZ_NO_REMOTE': '1', 16:16:20 INFO - 'NO_EM_RESTART': '1', 16:16:20 INFO - 'PAGER': '/bin/cat', 16:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:20 INFO - 'PWD': '/builds/slave/test', 16:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:20 INFO - 'SHELL': '/bin/bash', 16:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:20 INFO - 'USER': 'cltbld', 16:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:20 INFO - 'XPC_FLAGS': '0x0', 16:16:20 INFO - 'XPC_SERVICE_NAME': '0', 16:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:16:20 INFO - Cleaning up... 16:16:20 INFO - Return code: 0 16:16:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16:20 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')]} 16:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 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')]} 16:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:20 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 16:16:20 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 16:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:20 INFO - 'HOME': '/Users/cltbld', 16:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:20 INFO - 'LOGNAME': 'cltbld', 16:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:20 INFO - 'MOZ_NO_REMOTE': '1', 16:16:20 INFO - 'NO_EM_RESTART': '1', 16:16:20 INFO - 'PAGER': '/bin/cat', 16:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:20 INFO - 'PWD': '/builds/slave/test', 16:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:20 INFO - 'SHELL': '/bin/bash', 16:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:20 INFO - 'USER': 'cltbld', 16:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:20 INFO - 'XPC_FLAGS': '0x0', 16:16:20 INFO - 'XPC_SERVICE_NAME': '0', 16:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:20 INFO - Downloading/unpacking blobuploader==1.2.4 16:16:20 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 16:16:20 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 16:16:20 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 16:16:20 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 16:16:20 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 16:16:20 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 16:16:23 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16:23 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 16:16:23 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 16:16:23 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 16:16:23 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 16:16:23 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 16:16:23 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 16:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:24 INFO - Downloading docopt-0.6.1.tar.gz 16:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:24 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:24 INFO - Running setup.py install for blobuploader 16:16:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:24 INFO - Running setup.py install for requests 16:16:25 INFO - Running setup.py install for docopt 16:16:25 INFO - Successfully installed blobuploader requests docopt 16:16:25 INFO - Cleaning up... 16:16:25 INFO - Return code: 0 16:16:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:25 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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:25 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 16:16:25 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 16:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:25 INFO - 'HOME': '/Users/cltbld', 16:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:25 INFO - 'LOGNAME': 'cltbld', 16:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:25 INFO - 'MOZ_NO_REMOTE': '1', 16:16:25 INFO - 'NO_EM_RESTART': '1', 16:16:25 INFO - 'PAGER': '/bin/cat', 16:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:25 INFO - 'PWD': '/builds/slave/test', 16:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:25 INFO - 'SHELL': '/bin/bash', 16:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:25 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:25 INFO - 'USER': 'cltbld', 16:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:25 INFO - 'XPC_FLAGS': '0x0', 16:16:25 INFO - 'XPC_SERVICE_NAME': '0', 16:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-1xkEM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-lA6rH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:25 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-hOdHmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VLGLFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-1vH1S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-EUvmQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-XVx8EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-52nBCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-mnOFix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-WDQEah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-eOAzbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:26 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-b_DUqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:27 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-YY9vfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:27 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-DXyinb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:27 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-j5H7gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:27 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-YV_AUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:27 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-tuW7w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:27 INFO - Running setup.py install for manifestparser 16:16:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:27 INFO - Running setup.py install for mozcrash 16:16:27 INFO - Running setup.py install for mozdebug 16:16:27 INFO - Running setup.py install for mozdevice 16:16:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Running setup.py install for mozfile 16:16:28 INFO - Running setup.py install for mozhttpd 16:16:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Running setup.py install for mozinfo 16:16:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Running setup.py install for mozInstall 16:16:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:28 INFO - Running setup.py install for mozleak 16:16:28 INFO - Running setup.py install for mozlog 16:16:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:29 INFO - Running setup.py install for moznetwork 16:16:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:29 INFO - Running setup.py install for mozprocess 16:16:29 INFO - Running setup.py install for mozprofile 16:16:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:29 INFO - Running setup.py install for mozrunner 16:16:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:29 INFO - Running setup.py install for mozscreenshot 16:16:29 INFO - Running setup.py install for moztest 16:16:30 INFO - Running setup.py install for mozversion 16:16:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:30 INFO - Cleaning up... 16:16:30 INFO - Return code: 0 16:16:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:30 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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:30 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 16:16:30 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 16:16:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:30 INFO - 'HOME': '/Users/cltbld', 16:16:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:30 INFO - 'LOGNAME': 'cltbld', 16:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:30 INFO - 'MOZ_NO_REMOTE': '1', 16:16:30 INFO - 'NO_EM_RESTART': '1', 16:16:30 INFO - 'PAGER': '/bin/cat', 16:16:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:30 INFO - 'PWD': '/builds/slave/test', 16:16:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:30 INFO - 'SHELL': '/bin/bash', 16:16:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:30 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:30 INFO - 'USER': 'cltbld', 16:16:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:30 INFO - 'XPC_FLAGS': '0x0', 16:16:30 INFO - 'XPC_SERVICE_NAME': '0', 16:16:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ryE9EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:30 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)) 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-w3eijO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:30 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)) 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Bac_zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:30 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)) 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-KgdoMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:30 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)) 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-aYa_GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:30 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)) 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-owLRw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-WwLR3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-caQbWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-K_2CsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-JVYiAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-chhgnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-sUN_wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-HgA1xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-miAwOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:31 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)) 16:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:31 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-TgMmHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:32 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)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:32 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-REjWRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:32 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-RlDQiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:32 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)) 16:16:32 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)) 16:16:32 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)) 16:16:32 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)) 16:16:32 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)) 16:16:32 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)) 16:16:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:34 INFO - Downloading blessings-1.6.tar.gz 16:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:16:34 INFO - Installing collected packages: blessings 16:16:34 INFO - Running setup.py install for blessings 16:16:35 INFO - Successfully installed blessings 16:16:35 INFO - Cleaning up... 16:16:35 INFO - Return code: 0 16:16:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:35 INFO - Reading from file tmpfile_stdout 16:16:35 INFO - Current package versions: 16:16:35 INFO - blessings == 1.6 16:16:35 INFO - blobuploader == 1.2.4 16:16:35 INFO - docopt == 0.6.1 16:16:35 INFO - functools32 == 3.2.3-2 16:16:35 INFO - jsonschema == 2.5.1 16:16:35 INFO - manifestparser == 1.1 16:16:35 INFO - mozInstall == 1.12 16:16:35 INFO - mozcrash == 1.0 16:16:35 INFO - mozdebug == 0.1 16:16:35 INFO - mozdevice == 0.49 16:16:35 INFO - mozfile == 1.2 16:16:35 INFO - mozhttpd == 0.7 16:16:35 INFO - mozinfo == 0.9 16:16:35 INFO - mozleak == 0.1 16:16:35 INFO - mozlog == 3.4 16:16:35 INFO - moznetwork == 0.27 16:16:35 INFO - mozprocess == 0.23 16:16:35 INFO - mozprofile == 0.28 16:16:35 INFO - mozrunner == 6.13 16:16:35 INFO - mozscreenshot == 0.1 16:16:35 INFO - mozsystemmonitor == 0.3 16:16:35 INFO - moztest == 0.7 16:16:35 INFO - mozversion == 1.4 16:16:35 INFO - psutil == 3.1.1 16:16:35 INFO - requests == 1.2.3 16:16:35 INFO - wsgiref == 0.1.2 16:16:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:35 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 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:35 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 16:16:35 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 16:16:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:35 INFO - 'HOME': '/Users/cltbld', 16:16:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:35 INFO - 'LOGNAME': 'cltbld', 16:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:35 INFO - 'MOZ_NO_REMOTE': '1', 16:16:35 INFO - 'NO_EM_RESTART': '1', 16:16:35 INFO - 'PAGER': '/bin/cat', 16:16:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:35 INFO - 'PWD': '/builds/slave/test', 16:16:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:35 INFO - 'SHELL': '/bin/bash', 16:16:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:35 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:35 INFO - 'USER': 'cltbld', 16:16:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:35 INFO - 'XPC_FLAGS': '0x0', 16:16:35 INFO - 'XPC_SERVICE_NAME': '0', 16:16:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 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)) 16:16:35 INFO - Cleaning up... 16:16:35 INFO - Return code: 0 16:16:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10675eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106761768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff513d62da0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, '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.ud023hzEJN/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.Cy8OWzHmUq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:16:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:35 INFO - 'HOME': '/Users/cltbld', 16:16:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:35 INFO - 'LOGNAME': 'cltbld', 16:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:35 INFO - 'MOZ_NO_REMOTE': '1', 16:16:35 INFO - 'NO_EM_RESTART': '1', 16:16:35 INFO - 'PAGER': '/bin/cat', 16:16:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:35 INFO - 'PWD': '/builds/slave/test', 16:16:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:35 INFO - 'SHELL': '/bin/bash', 16:16:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:35 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:35 INFO - 'USER': 'cltbld', 16:16:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:35 INFO - 'XPC_FLAGS': '0x0', 16:16:35 INFO - 'XPC_SERVICE_NAME': '0', 16:16:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:16:36 INFO - Cleaning up... 16:16:36 INFO - Return code: 0 16:16:36 INFO - Running post-action listener: _resource_record_post_action 16:16:36 INFO - Running post-action listener: _start_resource_monitoring 16:16:36 INFO - Starting resource monitoring. 16:16:36 INFO - [mozharness: 2016-12-22 00:16:36.099618Z] Finished create-virtualenv step (success) 16:16:36 INFO - [mozharness: 2016-12-22 00:16:36.100581Z] Running install step. 16:16:36 INFO - Running pre-action listener: _resource_record_pre_action 16:16:36 INFO - Running main action method: install 16:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:36 INFO - Reading from file tmpfile_stdout 16:16:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:16:36 INFO - Reading from file tmpfile_stdout 16:16:36 INFO - Output received: 16:16:36 INFO - Usage: mozinstall [options] installer 16:16:36 INFO - Options: 16:16:36 INFO - -h, --help show this help message and exit 16:16:36 INFO - -d DEST, --destination=DEST 16:16:36 INFO - Directory to install application into. [default: 16:16:36 INFO - "/builds/slave/test"] 16:16:36 INFO - --app=APP Application being installed. [default: firefox] 16:16:36 INFO - mkdir: /builds/slave/test/build/application 16:16:36 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'] 16:16:36 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 16:16:58 INFO - Reading from file tmpfile_stdout 16:16:58 INFO - Output received: 16:16:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:16:58 INFO - Running post-action listener: _resource_record_post_action 16:16:58 INFO - [mozharness: 2016-12-22 00:16:58.132315Z] Finished install step (success) 16:16:58 INFO - [mozharness: 2016-12-22 00:16:58.132522Z] Running run-tests step. 16:16:58 INFO - Running pre-action listener: _resource_record_pre_action 16:16:58 INFO - Running main action method: run_tests 16:16:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:58 INFO - 2016-12-21 16:16:58.147 screenresolution[1909:17430] starting screenresolution argv=screenresolution get 16:16:58 INFO - 2016-12-21 16:16:58.149 screenresolution[1909:17430] Display 0: 1600x1200x32@60 16:16:58 INFO - 2016-12-21 16:16:58.155 screenresolution[1910:17432] starting screenresolution argv=screenresolution list 16:16:58 INFO - Available Modes on Display 0 16:16:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:58 INFO - Intel Iris: 16:16:58 INFO - Chipset Model: Intel Iris 16:16:58 INFO - Type: GPU 16:16:58 INFO - Bus: Built-In 16:16:58 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:58 INFO - Vendor: Intel (0x8086) 16:16:58 INFO - Device ID: 0x0a2e 16:16:58 INFO - Revision ID: 0x0009 16:16:58 INFO - Displays: 16:16:58 INFO - Display: 16:16:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:58 INFO - Display Serial Number: 1600x1200 60 16:16:58 INFO - Main Display: Yes 16:16:58 INFO - Mirror: Off 16:16:58 INFO - Online: Yes 16:16:58 INFO - Rotation: Supported 16:16:58 INFO - Return code: 0 16:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:16:58 INFO - Python 2.7.3 16:16:58 INFO - Return code: 0 16:16:58 INFO - grabbing minidump binary from tooltool 16:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:58 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 0x10653cd30>, '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 0x106463db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106531be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:16:58 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 16:16:58 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 16:16:58 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 16:16:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:16:58 INFO - Return code: 0 16:16:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:16:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:16:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:16:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:16:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 16:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --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 16:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ud023hzEJN/Render', 16:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:58 INFO - 'HOME': '/Users/cltbld', 16:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:58 INFO - 'LOGNAME': 'cltbld', 16:16:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:58 INFO - 'MOZ_NO_REMOTE': '1', 16:16:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:58 INFO - 'NO_EM_RESTART': '1', 16:16:58 INFO - 'PAGER': '/bin/cat', 16:16:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:58 INFO - 'PWD': '/builds/slave/test', 16:16:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:58 INFO - 'SHELL': '/bin/bash', 16:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cy8OWzHmUq/Listeners', 16:16:58 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:58 INFO - 'USER': 'cltbld', 16:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:58 INFO - 'XPC_FLAGS': '0x0', 16:16:58 INFO - 'XPC_SERVICE_NAME': '0', 16:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 16:16:58 INFO - mozversion application_buildid: 20161221133153 16:16:58 INFO - mozversion application_changeset: 38e67fedc9a71c3b726cf875ac114ba5a6593fc8 16:16:58 INFO - mozversion application_display_name: Nightly 16:16:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:16:58 INFO - mozversion application_name: Firefox 16:16:58 INFO - mozversion application_remotingname: firefox 16:16:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:16:58 INFO - mozversion application_vendor: Mozilla 16:16:58 INFO - mozversion application_version: 53.0a1 16:16:58 INFO - mozversion platform_buildid: 20161221133153 16:16:58 INFO - mozversion platform_changeset: 38e67fedc9a71c3b726cf875ac114ba5a6593fc8 16:16:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:16:58 INFO - mozversion platform_version: 53.0a1 16:16:58 INFO - using testdate: 1482365818 16:16:58 INFO - actual date: 1482365818 16:16:58 INFO - starting webserver on 'localhost:54559' 16:16:58 INFO - SUITE-START | Running 2 tests 16:16:58 INFO - TEST-START | dromaeo_css 16:16:58 INFO - operating with platform_type : mac_ 16:16:58 INFO - Initialising browser for dromaeo_css test... 16:16:58 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpaCZYkH/profile http://localhost:54559/getInfo.html) 16:17:01 INFO - PROCESS | 1920 | 2016-12-21 16:17:01.722 plugin-container[1922:17582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 16:17:01 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:01 INFO - PROCESS | 1920 | 2016-12-21 16:17:01.725 plugin-container[1922:17582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 16:17:01 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:01 INFO - PROCESS | 1920 | 2016-12-21 16:17:01.725 plugin-container[1922:17582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:17:01 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 16:17:01 INFO - PROCESS | 1920 | colorDepth:24 16:17:01 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 16:17:01 INFO - PROCESS | 1920 | __metrics 16:17:01 ERROR - PROCESS | 1920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 16:17:02 INFO - TEST-INFO | 1920: exit 0 16:17:02 INFO - Browser initialized. 16:17:02 INFO - Running cycle 1/1 for dromaeo_css test... 16:17:02 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpaCZYkH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:04 INFO - PROCESS | 1923 | 2016-12-21 16:17:04.889 plugin-container[1924:17707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 16:17:04 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:04 INFO - PROCESS | 1923 | 2016-12-21 16:17:04.890 plugin-container[1924:17707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:17:04 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:17:04 INFO - PROCESS | 1923 | 2016-12-21 16:17:04.890 plugin-container[1924:17707] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:17:14 INFO - PROCESS | 1923 | RSS: Main: 237002752 16:17:14 INFO - PROCESS | 1923 | 16:18:04 INFO - PROCESS | 1923 | 1482365884147 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:20:17 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:54559/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54559/tests/dromaeo/cssquery-ext.html) 16:20:19 INFO - PROCESS | 1923 | RSS: Main: 252039168 16:20:19 INFO - PROCESS | 1923 | 16:23:22 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:54559/tests/dromaeo/cssquery-ext.html (next: http://localhost:54559/tests/dromaeo/cssquery-jquery.html) 16:23:22 INFO - PROCESS | 1923 | RSS: Main: 263462912 16:23:22 INFO - PROCESS | 1923 | 16:26:25 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:54559/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54559/tests/dromaeo/cssquery-mootools.html) 16:26:25 INFO - PROCESS | 1923 | RSS: Main: 269639680 16:26:25 INFO - PROCESS | 1923 | 16:29:27 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:54559/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54559/tests/dromaeo/cssquery-prototype.html) 16:29:28 INFO - PROCESS | 1923 | RSS: Main: 268644352 16:29:28 INFO - PROCESS | 1923 | 16:32:31 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:54559/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54559/tests/dromaeo/cssquery-yui.html) 16:32:32 INFO - PROCESS | 1923 | RSS: Main: 273178624 16:32:32 INFO - PROCESS | 1923 | 16:35:34 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:54559/tests/dromaeo/cssquery-yui.html (next: http://localhost:54559/tests/dromaeo/cssquery-dojo.html) 16:35:35 INFO - PROCESS | 1923 | RSS: Main: 276852736 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | __start_tp_report 16:35:35 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 16:35:35 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 16:35:35 INFO - PROCESS | 1923 | |i|pagename|runs| 16:35:35 INFO - PROCESS | 1923 | |0;dojo.html;2733.9863300683496;3326.6340702522702;3350.91095393997;3384.373890830198;3407.693307602316;5043.243513472981;5452.536534394571;5517.144842549401;5541.889162216763;5582.358028826686;5449.073657478229;5494.423085576017;5530.834074977747;5542.0301447246675;5596.412376700459;5003.674761140514;5107.514786095315;5140.460251673577;5144.408393034792;5207.750139966419;5030.622703297249;5201.817936372227;5225.712585807779;5252.921206181892;5258.13240815265;5073.949260507385;5096.694198348074;5107.770150343244;5135.743212839362;5183.637145399824;4452.465704115494;4865.467377775989;5099.5410413063;5143.305653736735;5160.664556803796;5056.014077254937;5080.923786143211;5096.592272618182;5097.46476619957;5128.153854613985;4834.492378300298;5182.98931708317;5624.521915637176;5624.6625202487985;5666.348369937468;5566.526845218162;5590.468905453975;5974.014287642533;6142.498775697809;6149.661768602725;6240.531960102965;6250.6874656267455;6251.4061164189325;6262.060690896356;6276;5724.284464441945;5757.510611597977;5759.164921086419;5772.653640781483;5820.068788993783;6086.147939288503;6164.229471316085;6172;6181.258249010148;6234.064890266496;5014.498550144956;6118.816435506942;6122.571420000557;6130.264368275835;6145.324014358417;6216.129741836146;6230.00319948806;6231.345708700612;6235.844103897348;6247.312795592481;5695.401982791747;5716.885662286731;5792.986227410186;5806.854828629318;5824.504917082096;5406.945930540644;6139.815805525842;6170.5372097092895;6210;6251.12484252205;4770.737609431515;4811.398575178103;4840.419149701988;4882.829100981448;4928.753562321941;4272.722273052242;4295.806688699016;4360.542143074995;4382.145481631052;4402.977985110118;5769.249997500298;5785.363610002896;5791.913121303184;5826.300843898674;5875.265591801025;6222.973209420486;6242.968785156136;6245;6254.874902501924;6270.7491700331475;5591.10542313228;5635.3519345275;5643.097104463266;5793.304803423616;5808.186853840466;5305.124654432053;5319.9202011969855;5351.919721204185;5352.518273355417;5360.758765855546;6214.223222097238;6273.749050038039;6283.905741413791;6285.842853928689;6299.496040316763;5032.6980381177245;5066.620003499678;5170.482951704799;6046.27444706638;6191.442770150708;5899.941000589939;6182.103594978793;6194.504439644727;6226.1905952225925;6337.524685648687;6124.564025909317;6160.599561028519;6169.444749972345;6172.598781079216;6194.0708893664505;6180.227471566054;6217.844553886189;6240.750369985331;6259.467945224706;6276.964300890302;5844.912326315194;5850.3857095006115;5905.173275741486;6040.184575082308;6136.478399336106;5945.048792193401;5966.3138739043825;5977.983742763654;5980.940190598212;5985.491233245223;5027.899442011066;5598.468145526169;5759.798407055733;5827.76688932438;5830.329512106162;5972.820815375546;6053.727582258897;6058.4850287724275;6071.787487437919;6089.634621922699;4895.388076490439;5879.882402352015;5894.852628684317;5921.200637913998;5927.2590926134235 16:35:35 INFO - PROCESS | 1923 | |1;ext.html;446.52891199784216;465.8695565241732;484.65105124310526;486.8077109541729;508.0448756338085;38511.037224069376;40292.59707402929;40418;40513;40537.59462405375;42146.36780448288;42576.50982215623;42731.71804845852;42780.786096069474;42786.35820462696;8434.746957591251;9331.113544213305;9548.713538593853;9561.61753529858;9591.616335346578;8607;11222.326660400362;11484.942575287112;11503.367314797682;11661.883381166164;9834.26243031772;11409.31544107352;11457.911498407639;11473;11478.655640330804;11412.771744565103;11421.771564568662;11496.137789665725;11497.080233581335;11529.250598711056;11220.887791122066;11293.71765705864;11317.151213658926;11384.544618215345;11418;39895.00262493432;40196.598034019575;40243.195136097114;40251.59748402508;40327.7983610083;3325.5011748237716;3496.7552271341015;3528.2414280929465;3548.1661809474754;3803.2013277211818;27465.862670686718;27546.44907101847;27734.5839812403;28851.27871803201;28885.85557072201;3200.199950012497;3201.375731732313;3219.2917558137174;3223.4520131577456;3329.267561136546;488569.5571522155;489434;494128;495409;497022.51488742686;54391.728041359536;54676.726616366665;54810;54846.72576637171;56004.439955600734;56754;56799.71600142056;56998.14502782462;57110;57297.427025730045;14778.039427437398;15238.390464381522;15420.306086226146;15427.768583471257;15457.227138643248;10771.83842242367;11010.944945275383;11796.99725523323;11934.820977685342;12033;16137.59656008609;17405.738913916302;17478.388256411217;17480.650386992187;17700.91149544244;6333.891568975411;6409.487241020707;6486.962086066207;6934.826629334206;7409.407247420193;873.9388242823069;874.6181033966318;1027.2552399510296;1049.7847941172042;1051.2536099369381;29451.705482945326;30104.736766233822;30144.24639384033;30256.84871575672;30257.394852103276;1019.403648865422;1023.3757407981126;1062.585591619269;1064.744461329291;1080.6757972608186;12515.812262816065;14042.648933776532;15539.30073146711;15688.686226275411;15991.760123598155;649591;651049;656790;658075.7096214489;658891;58923;59138.40861591415;59473.702631486565;59705;59888.70055649694;1351.2703140304125;1366.0369439545098;1395.3511617097895;1401.9299035048207;1404.03121845926;27852.44295114046;28917;30019;30421.847890760404;30623.846880765454;47888.521114788404;50204.24693629672;51062.74468627633;51102;51166.48833511617;521740;607948;616849;617249;618032.9098354479;24806.875965620056;25452.618210727225;25587.6161857576;38306.80846595749;38741.83774486657;41651.958701032716;41905.37141942934;42570.787146064074;42718.78640606777;43011.784941076345;35450.645493545766;35568.644313556535;35650.64349356577;36444.81777591095;36903.63096369002;31624.893128740383;33299;34412.13969650678;35002.64997349994;35173;34394.82802585971;35147.689018969446;35150.94547163589;35347.82326088353;35432.29135417226;27988;30092.3981520364;31611.683883160873;31968.840155799073;32128.357432850746;18376.63246735031;21769.891150544147;24004.63993040141;24124.396890077187;24400.97164049313 16:35:35 INFO - PROCESS | 1923 | |2;jquery.html;344.67600455571767;369.90567405311657;383.53783690652756;387.34151941699116;402.65572935140455;18845.434636960897;19716.507087322825;19878.403647890587;20214.89892550537;20260.59478810425;16232.594185145377;17725;20253.088611012503;20473.87393693346;20729.378118656427;4624.606908412789;5607.551395888319;5782.3639399666;5828.708564571775;5835.9124613130625;5581.916271255935;5745.2818397700285;5745.396733343002;5754.712264386785;5762.366139724627;5625.296837895263;5739.311282646088;5740.942590574104;5748.511376533;5915.438033386821;5105.617078719111;6020.307664618559;6080.118382834508;6082.422169893853;6088.96955515224;5627.8593035174035;5786.68173250471;5854.531637469035;5859.8535036624;5862.794802181903;10455.947720261425;12802.103852730312;12960.935195324057;13199.538016169485;13236.602901912862;1994.6210220058142;2011.919523219084;2144.270947877729;2160.7083043789044;2188.3763127508582;8324.791880203044;9743.220542356594;9922.90077099227;9923.404595724207;10432.269741118125;1730.264637529045;1799.5501124718821;1848.223746026672;1881.1252767463059;1928.816762407569;105802.47098764534;107054.92945070527;108816.4559177207;111429;111854.99999999919;9255.398399104171;11174.832377514344;11308.830367544493;11425.371604561662;11672.124590655734;14257.429702811978;14281.285935703174;14285.642858928402;14286.85713142876;14324.283785810669;8104.675812967415;8265.421420500628;8404.151086760903;8409.495430274203;8533.53065581399;7471.4396420268695;8244.958775206085;8260.504369737835;8415.747527574182;8466.619002144918;3753.7935413551972;4507.301368287855;4569.474510431343;4576.977115114403;4601.838935637236;1875.859310551714;1935.9032048397523;1942.6211888681853;1956.9315073972343;1957.725918371429;738.9408847292203;804.4248362420897;808.9312408445348;875.7197696737111;964.1756298364797;15641.843581564266;19597.412077637695;20397.79602203959;21255.36233912985;21396.465088372915;787.2245837849714;862.943908645936;883.88067610873;918.8254231695956;935.5930170375908;10754.354738715701;11139.777204455866;11172.273802202993;11176.552937882556;11191.944040279746;522626;525389;531740;534664;535831;82012.44493884368;85221.57389213014;93419.53290233642;121772;121896;899.4603238057114;953.3136141977626;1020.1379834040224;1025.5897640943533;1035.7876635289747;17904.641907161524;19475.415737527896;19491.02544872779;19842.404727858186;19886.403407897786;49913.750431249064;51381.48618513917;52286.18019159139;52823.73588132035;53181;49307;69265.61468770697;73616.63191684007;74063.6296818534;74660.2533974675;7978.92021079805;8001.159878212704;8150.225728555778;8187.918120818954;8308.626111825104;10275.7431064221;12627.558035468715;12731.172473789175;12952.935235323763;13161.473541058633;8819.38264321517;8866.601002954754;10836.187285953427;10953.288036277936;10983.890161098287;10676.786464270515;10858.674239772503;10885.727856803644;11161.776764464503;11220.326780393203;8105.959470202612;9071.455712657262;10862.511186996762;11018.614348497764;11200.887991119984;6931.757388491378;7113.715451381887;7212.603306818175;7419.443541734284;8403.537805420761;9462.14840664316;9973.002699729968;10092.444915529717;10905.400202988912;11072.557097716 16:35:35 INFO - PROCESS | 1923 | |3;mootools.html;1267.5183430296486;1481.7184734900375;1491.2618253964285;1656.2877962476139;1747.196289706734;7479.700811967515;7671.386289096873;7994.160613135617;8426.241638252564;8463.492190468576;6895.44836413087;7901.723439679612;8206.343492520598;8207.343412526998;8308.210719981607;2250.313654335427;2309.434188623781;2314.8495347802423;2317.6639387288833;2326.6626339180807;2303.6890019847356;2329.1615018593293;2355.387599224197;2364.408897775556;2368.9931778993946;2280.726312842461;2283.6117859963765;2321.0483701682315;2321.6401457773986;2324;2264.445210923326;2275.4880151965676;2288.8168946484407;2296.299628613264;2298.2645553422913;2236.608593496148;2270.5572413379396;2271.9204827830945;2273.59075366434;2282.7374851891914;8304.377171712145;8372.874406883902;8376.329893608558;8377.874331885027;8394.202550757669;5031.471695471996;5151.2273159026445;5189.455107213764;5196.428392876781;5196.974015129938;3243.5783348164823;3341.18141055443;3492.214251793326;3519.436890097598;3526.2066035142143;1031.437866362834;1031.7730099378125;1062.0866055192605;1080.8432777247333;1081.5403453532217;10267.640632577824;10490.790184196274;10548.419836909045;10562.31344962575;10564.207684423698;4390.868273951786;4486.75322857246;4580.24425969718;4587.4173410173435;4645.233536466445;9131.634734610674;9138.862917056249;9155.862662060075;9231.95384023089;9259.166674999282;5110.897782044338;5120.283160357553;5146.433892271847;5162;5167.793288268427;4154.148399578079;4292.463442069741;4612.60792832613;4710.952890471051;4719.433667959798;3475.6792418880664;3734.7572407793405;3763.9435408468894;3858.6334298241622;3891.6886649068006;1113.6158025481195;1253.4422182129047;1258.8111783232405;1263.0463999680182;1270.0538099116218;532.424981020497;789.2541548236861;902.6750369866843;905.1401168889449;943.688582767685;4564.954350456453;5215.400228973716;5522.586050408431;5600.607957443021;5697.800576979786;882.3250213586483;894.7181637784078;897.8787863638456;934.6121359635675;1015.5376258188186;3213.8714451421683;3782.8486860525823;3825.445310430028;3887.455756194135;3905;7947.721829736047;9600.279979001603;9827.852582211273;9888.752781180528;9948;9845;9879.851802222973;9885;9885.604575817031;9889.950550247346;7403.8149046274275;7733.072031356273;7739.61301934901;7762.961185194151;7908.2091790821605;4521.864344069683;5610.410906854721;5655.293088363955;5702.258706368146;5732.254806875247;8609.569521524025;8717.346199035224;8721.345899057724;8810.339224558056;8867.556622168737;8926.86609700842;8952.552372381486;9079.364444488823;9115.407498512575;9128.224100951493;6472.385123413268;6663.600183988976;6837.35045170708;7141.607211603204;7508.624568771649;7653.158152603321;7776.688932442639;7845.882311765214;7866.7639970800965;7902.762917112495;6965.199002114619;7090.539114957512;7110.1112360954885;7113.786586402416;7172.677229524579;5695.715214239354;6832.282610326043;6974.192196805674;7049.682764275517;7121.608311542915;6362.331955144643;6545.116409284686;6599.406053455212;6633.834154146185;6637.6349300788925;3652.7077833773765;4767.976160119178;4823.2282834746275;4844.24914138295;4948.38145231846;4741.786619602057;4830.304816815069;4847.491013443538;4880.633952453509;4962.851114466572 16:35:35 INFO - PROCESS | 1923 | |4;prototype.html;351.97008254298385;358.2351679164982;358.8259694048387;362.06948143271785;368.75177524080976;5409.837704868855;5477.302036368728;5493.02251189572;5494.835154945348;5503.53219976302;4996.67521611095;5104.744762761866;5374.54887180462;5399.676019438836;5427.457254274571;3349.296647703985;3756.3801972674614;3762.3792074307844;3853.942190867125;3897.649211570958;4013.2374848778786;4147.460830092099;4199.811008504625;4228.577142285778;4235.903227902178;3321.335732853427;3480.3909315869746;3558.7508874378805;3834.5973672764376;3968.8490337802004;4011.3982902564558;4205.222033923734;4212.326027835532;4222.78886055696;4233.724807887493;2747.5466548019554;3048.2988912550018;3161.2571045804216;3681.9263614727824;3704.9629503705155;5209.9319639473815;5309.601779866487;5328.600354973354;5336.636680136477;5366;4799.6400269979895;4837.443693975203;4844.854654360375;4862.3192753014255;4873.780679869415;5553.750081246354;5682.573806964453;5697.088465845445;5720.1991721159075;5738.856528586777;3569.94645080324;4057.0263136846943;4084.407760874686;4099.754014759094;4100.753954762724;4685.765711714401;5662.288148214326;5837.059984510439;5995.062419414499;6005.579609427342;3687.170386663033;3821.3503704370323;4174.977130603022;4193.245215861174;4201.264778663722;3895.2599006188734;4227.471566054243;4265.0616864289805;4271.9359209611885;4297.88255053682;4705.435347758291;4833.830815921426;4844.456660253325;4850;4891.51084891508;5703.800366987219;5764.913526297109;5799.768009279666;5809.419058094241;5826.533877289807;1192.4633914738401;1338.9422356338578;1339.591424615482;1347.5148946379295;1388.5279005138302;904.5703290936752;914.4467597103657;956.531299663174;972.6304004478394;972.7713987212999;4658.557437043475;5076.873078173076;5097.643164978416;5121.820736274212;5191.532762051434;5040.84877453677;5421.322334708161;5455.672659640434;5504.422035686275;5667.489925906688;4533.52397998212;4644.419447569054;4767.928481072787;4862.343583616237;5045.747712614355;2566.3703548247;2570.537303285389;2682.45009773;2830.1462542035;2977.3003344214094;4948.505149485023;5183.170692689152;5794.62334948227;5858.648481091148;5913.645181289136;4901.730404827768;5348.331458567679;5423.240746295522;5759.712014399263;5769.567282453749;5185.274061631375;5626.751802389252;5787.508061814793;5861.296644402529;5933;4575.565321294472;5200.791968321376;5370.248165256945;5625.818578098645;5680.886382272249;4946.851594452172;4980.800767969241;5114.565261952627;5184.896302073862;5270.997021131945;4824.33560421654;5189.714565698923;5237.476252374732;5787.594868359174;5983.790567330123;4145.564715704808;4150.609545802069;4152.43942067817;4158.542560318302;4165.312723400605;5297.000764422836;5866.911996320144;5948.828677532756;5950.732217050157;5972.940270597239;5571.350765248769;5684.986956912843;5711.914736200109;5719.56918309725;5769.249997500298;4577.908441831079;5066.544011039025;5699.002674532023;5702.547904401147;5722.4277572242445;4997.751910875727;5376.924615076922;5416.9729151353995;5631.63394379364;5632.5775566831835;4890.902181956412;5637.351704554028;5706.857328566819;5769.922827782079;5785.508231800344;5164.786275225244;5608.766071464434;5630.324361076697;5637.774489020393;5665.886682266414 16:35:35 INFO - PROCESS | 1923 | |5;yui.html;728.2862794461431;741.7255615422293;770.9036370453694;799.588212070784;815.5759005317235;13240.53658121966;15229.771553426694;16004.839951600481;16052.197390130475;16094.114823684693;11777.764444711122;11952.521899124025;12322.199057061307;14406.063605865638;14452;675.5946432140701;705.2700455029046;832.646125396707;843.6541018182531;873.5588527793456;689.6413864790306;843.569779412501;847.4068152293388;851.1148405658139;857.5326447086321;875.6935072724527;904.5025236120141;907.2061945797427;907.7004588485817;908.7728067983004;898.6674930275807;900.9729708108702;901.8016036471965;901.9368644194909;903.2277402820592;656.3862788292913;660.2110477978817;665.6871270502904;666.2770893580471;668.6289109544201;14682;14846.554603361916;15088.24558772057;15277.923610381877;15409.152496612682;14163.291835408188;14415.279236038261;14607.853921460863;14661.41354345825;14742.189179595225;11853.703657408547;11871.881281187163;11924.284542927453;11959.641210763692;12030.4586293617;3660.158163622355;3663.6519530644814;3725.701943844486;3734.8692795752017;3860.6718428933573;96607.51696241544;98831;99883;99923.50038249762;103000.48499757529;10938.179636527293;10990.285631433931;11086.667399978014;11160.497777599865;11335.206535542598;22526.774732252794;22703.772962270166;22717.88641056784;23033.309000730005;23107.306780796604;7885.921140788633;7906.407019473562;7971.104745298294;8036.557989310527;8040.638171282307;3900.4929359183125;3961.643452089326;3975.9662487753394;3982.342913419311;3998.7200895937585;2947.8083924544835;3415.282790613949;3514.05120617437;3525.2244506208594;3610.332088563624;673.6833688166554;684.1687349869833;687.8968154776824;695.2942763095463;696.9789258735976;532.5925666864852;626.3110578363763;630.077765907233;646.890028695123;683.9829004274933;8743.893268526743;8969.596368163448;9003.954980225057;9273.629054837866;9619.78564230356;602.9638221706712;609.1411110334495;616.9074638804216;644.7324360390479;644.8065580325883;4112.9100788291125;4479.440069991251;4582.381378513925;4765.833195838129;4831.975840120847;82838;84630;86554;106134;106804.39793403105;65444;77329;80147;81593.59203203947;88943;52825.735871321165;61810.4547386301;69417.65291173614;77760.61119694478;79100.60449697715;8188.754337369888;9930.106290433896;9936.615212349128;9951.502424878872;10009.449480278654;47231;49457.010859783324;50032;50350.74824626;51824.74087629538;53928.73035634954;63153;63599.682001589696;66170.67658646897;75325.62337188279;11170.385628790495;11842.585509507126;12818.884883994036;12856.678583035498;13376.866231337563;14341.92829035848;14741.631459213606;14781;14865.628359291104;15495.302711378241;9711.5144242789;10200.9196321472;13567.321633918067;13781.586552403443;14041.929790350983;13276.53532126371;13593.252371119288;13601.523946661819;14004.929975350466;14227.573172804832;12922.289274090015;13426.731465370443;13455.663608409868;13610.319484025957;13621.795673064713;8856.059330844773;10797.352158870493;10998.945005274922;11244.987951084442;11429.714257143305;9208.207358424155;9782.657606984007;9933.255005874444;11144.33134011962;11274.098072154044 16:35:35 INFO - PROCESS | 1923 | __end_tp_report 16:35:35 INFO - PROCESS | 1923 | __start_cc_report 16:35:35 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,374 16:35:35 INFO - PROCESS | 1923 | __end_cc_report 16:35:35 INFO - PROCESS | 1923 | __startTimestamp1482366935227__endTimestamp 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | ------- Summary: start ------- 16:35:35 INFO - PROCESS | 1923 | Number of tests: 6 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | [#0] dojo.html Cycles:165 Average:5605.20 Median:5791.91 stddev:663.53 (11.5%) stddev-sans-first:626.17 16:35:35 INFO - PROCESS | 1923 | Values: 2734.0 3326.6 3350.9 3384.4 3407.7 5043.2 5452.5 5517.1 5541.9 5582.4 5449.1 5494.4 5530.8 5542.0 5596.4 5003.7 5107.5 5140.5 5144.4 5207.8 5030.6 5201.8 5225.7 5252.9 5258.1 5073.9 5096.7 5107.8 5135.7 5183.6 4452.5 4865.5 5099.5 5143.3 5160.7 5056.0 5080.9 5096.6 5097.5 5128.2 4834.5 5183.0 5624.5 5624.7 5666.3 5566.5 5590.5 5974.0 6142.5 6149.7 6240.5 6250.7 6251.4 6262.1 6276.0 5724.3 5757.5 5759.2 5772.7 5820.1 6086.1 6164.2 6172.0 6181.3 6234.1 5014.5 6118.8 6122.6 6130.3 6145.3 6216.1 6230.0 6231.3 6235.8 6247.3 5695.4 5716.9 5793.0 5806.9 5824.5 5406.9 6139.8 6170.5 6210.0 6251.1 4770.7 4811.4 4840.4 4882.8 4928.8 4272.7 4295.8 4360.5 4382.1 4403.0 5769.2 5785.4 5791.9 5826.3 5875.3 6223.0 6243.0 6245.0 6254.9 6270.7 5591.1 5635.4 5643.1 5793.3 5808.2 5305.1 5319.9 5351.9 5352.5 5360.8 6214.2 6273.7 6283.9 6285.8 6299.5 5032.7 5066.6 5170.5 6046.3 6191.4 5899.9 6182.1 6194.5 6226.2 6337.5 6124.6 6160.6 6169.4 6172.6 6194.1 6180.2 6217.8 6240.8 6259.5 6277.0 5844.9 5850.4 5905.2 6040.2 6136.5 5945.0 5966.3 5978.0 5980.9 5985.5 5027.9 5598.5 5759.8 5827.8 5830.3 5972.8 6053.7 6058.5 6071.8 6089.6 4895.4 5879.9 5894.9 5921.2 5927.3 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | [#1] ext.html Cycles:180 Average:70496.20 Median:35075.17 stddev:156729.21 (446.8%) stddev-sans-first:157080.63 16:35:35 INFO - PROCESS | 1923 | Values: 446.5 465.9 484.7 486.8 508.0 38511.0 40292.6 40418.0 40513.0 40537.6 42146.4 42576.5 42731.7 42780.8 42786.4 8434.7 9331.1 9548.7 9561.6 9591.6 8607.0 11222.3 11484.9 11503.4 11661.9 9834.3 11409.3 11457.9 11473.0 11478.7 11412.8 11421.8 11496.1 11497.1 11529.3 11220.9 11293.7 11317.2 11384.5 11418.0 39895.0 40196.6 40243.2 40251.6 40327.8 3325.5 3496.8 3528.2 3548.2 3803.2 27465.9 27546.4 27734.6 28851.3 28885.9 3200.2 3201.4 3219.3 3223.5 3329.3 488569.6 489434.0 494128.0 495409.0 497022.5 54391.7 54676.7 54810.0 54846.7 56004.4 56754.0 56799.7 56998.1 57110.0 57297.4 14778.0 15238.4 15420.3 15427.8 15457.2 10771.8 11010.9 11797.0 11934.8 12033.0 16137.6 17405.7 17478.4 17480.7 17700.9 6333.9 6409.5 6487.0 6934.8 7409.4 873.9 874.6 1027.3 1049.8 1051.3 29451.7 30104.7 30144.2 30256.8 30257.4 1019.4 1023.4 1062.6 1064.7 1080.7 12515.8 14042.6 15539.3 15688.7 15991.8 649591.0 651049.0 656790.0 658075.7 658891.0 58923.0 59138.4 59473.7 59705.0 59888.7 1351.3 1366.0 1395.4 1401.9 1404.0 27852.4 28917.0 30019.0 30421.8 30623.8 47888.5 50204.2 51062.7 51102.0 51166.5 521740.0 607948.0 616849.0 617249.0 618032.9 24806.9 25452.6 25587.6 38306.8 38741.8 41652.0 41905.4 42570.8 42718.8 43011.8 35450.6 35568.6 35650.6 36444.8 36903.6 31624.9 33299.0 34412.1 35002.6 35173.0 34394.8 35147.7 35150.9 35347.8 35432.3 27988.0 30092.4 31611.7 31968.8 32128.4 18376.6 21769.9 24004.6 24124.4 24401.0 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | [#2] jquery.html Cycles:180 Average:31228.85 Median:4538.39 stddev:88270.31 (1945.0%) stddev-sans-first:88487.47 16:35:35 INFO - PROCESS | 1923 | Values: 344.7 369.9 383.5 387.3 402.7 18845.4 19716.5 19878.4 20214.9 20260.6 16232.6 17725.0 20253.1 20473.9 20729.4 4624.6 5607.6 5782.4 5828.7 5835.9 5581.9 5745.3 5745.4 5754.7 5762.4 5625.3 5739.3 5740.9 5748.5 5915.4 5105.6 6020.3 6080.1 6082.4 6089.0 5627.9 5786.7 5854.5 5859.9 5862.8 10455.9 12802.1 12960.9 13199.5 13236.6 1994.6 2011.9 2144.3 2160.7 2188.4 8324.8 9743.2 9922.9 9923.4 10432.3 1730.3 1799.6 1848.2 1881.1 1928.8 105802.5 107054.9 108816.5 111429.0 111855.0 9255.4 11174.8 11308.8 11425.4 11672.1 14257.4 14281.3 14285.6 14286.9 14324.3 8104.7 8265.4 8404.2 8409.5 8533.5 7471.4 8245.0 8260.5 8415.7 8466.6 3753.8 4507.3 4569.5 4577.0 4601.8 1875.9 1935.9 1942.6 1956.9 1957.7 738.9 804.4 808.9 875.7 964.2 15641.8 19597.4 20397.8 21255.4 21396.5 787.2 862.9 883.9 918.8 935.6 10754.4 11139.8 11172.3 11176.6 11191.9 522626.0 525389.0 531740.0 534664.0 535831.0 82012.4 85221.6 93419.5 121772.0 121896.0 899.5 953.3 1020.1 1025.6 1035.8 17904.6 19475.4 19491.0 19842.4 19886.4 49913.8 51381.5 52286.2 52823.7 53181.0 49307.0 69265.6 73616.6 74063.6 74660.3 7978.9 8001.2 8150.2 8187.9 8308.6 10275.7 12627.6 12731.2 12952.9 13161.5 8819.4 8866.6 10836.2 10953.3 10983.9 10676.8 10858.7 10885.7 11161.8 11220.3 8106.0 9071.5 10862.5 11018.6 11200.9 6931.8 7113.7 7212.6 7419.4 8403.5 9462.1 9973.0 10092.4 10905.4 11072.6 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | [#3] mootools.html Cycles:180 Average:5316.31 Median:5192.94 stddev:2879.13 (55.4%) stddev-sans-first:2871.12 16:35:35 INFO - PROCESS | 1923 | Values: 1267.5 1481.7 1491.3 1656.3 1747.2 7479.7 7671.4 7994.2 8426.2 8463.5 6895.4 7901.7 8206.3 8207.3 8308.2 2250.3 2309.4 2314.8 2317.7 2326.7 2303.7 2329.2 2355.4 2364.4 2369.0 2280.7 2283.6 2321.0 2321.6 2324.0 2264.4 2275.5 2288.8 2296.3 2298.3 2236.6 2270.6 2271.9 2273.6 2282.7 8304.4 8372.9 8376.3 8377.9 8394.2 5031.5 5151.2 5189.5 5196.4 5197.0 3243.6 3341.2 3492.2 3519.4 3526.2 1031.4 1031.8 1062.1 1080.8 1081.5 10267.6 10490.8 10548.4 10562.3 10564.2 4390.9 4486.8 4580.2 4587.4 4645.2 9131.6 9138.9 9155.9 9232.0 9259.2 5110.9 5120.3 5146.4 5162.0 5167.8 4154.1 4292.5 4612.6 4711.0 4719.4 3475.7 3734.8 3763.9 3858.6 3891.7 1113.6 1253.4 1258.8 1263.0 1270.1 532.4 789.3 902.7 905.1 943.7 4565.0 5215.4 5522.6 5600.6 5697.8 882.3 894.7 897.9 934.6 1015.5 3213.9 3782.8 3825.4 3887.5 3905.0 7947.7 9600.3 9827.9 9888.8 9948.0 9845.0 9879.9 9885.0 9885.6 9890.0 7403.8 7733.1 7739.6 7763.0 7908.2 4521.9 5610.4 5655.3 5702.3 5732.3 8609.6 8717.3 8721.3 8810.3 8867.6 8926.9 8952.6 9079.4 9115.4 9128.2 6472.4 6663.6 6837.4 7141.6 7508.6 7653.2 7776.7 7845.9 7866.8 7902.8 6965.2 7090.5 7110.1 7113.8 7172.7 5695.7 6832.3 6974.2 7049.7 7121.6 6362.3 6545.1 6599.4 6633.8 6637.6 3652.7 4768.0 4823.2 4844.2 4948.4 4741.8 4830.3 4847.5 4880.6 4962.9 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | [#4] prototype.html Cycles:180 Average:4569.70 Median:5101.19 stddev:1384.26 (27.1%) stddev-sans-first:1351.46 16:35:35 INFO - PROCESS | 1923 | Values: 352.0 358.2 358.8 362.1 368.8 5409.8 5477.3 5493.0 5494.8 5503.5 4996.7 5104.7 5374.5 5399.7 5427.5 3349.3 3756.4 3762.4 3853.9 3897.6 4013.2 4147.5 4199.8 4228.6 4235.9 3321.3 3480.4 3558.8 3834.6 3968.8 4011.4 4205.2 4212.3 4222.8 4233.7 2747.5 3048.3 3161.3 3681.9 3705.0 5209.9 5309.6 5328.6 5336.6 5366.0 4799.6 4837.4 4844.9 4862.3 4873.8 5553.8 5682.6 5697.1 5720.2 5738.9 3569.9 4057.0 4084.4 4099.8 4100.8 4685.8 5662.3 5837.1 5995.1 6005.6 3687.2 3821.4 4175.0 4193.2 4201.3 3895.3 4227.5 4265.1 4271.9 4297.9 4705.4 4833.8 4844.5 4850.0 4891.5 5703.8 5764.9 5799.8 5809.4 5826.5 1192.5 1338.9 1339.6 1347.5 1388.5 904.6 914.4 956.5 972.6 972.8 4658.6 5076.9 5097.6 5121.8 5191.5 5040.8 5421.3 5455.7 5504.4 5667.5 4533.5 4644.4 4767.9 4862.3 5045.7 2566.4 2570.5 2682.5 2830.1 2977.3 4948.5 5183.2 5794.6 5858.6 5913.6 4901.7 5348.3 5423.2 5759.7 5769.6 5185.3 5626.8 5787.5 5861.3 5933.0 4575.6 5200.8 5370.2 5625.8 5680.9 4946.9 4980.8 5114.6 5184.9 5271.0 4824.3 5189.7 5237.5 5787.6 5983.8 4145.6 4150.6 4152.4 4158.5 4165.3 5297.0 5866.9 5948.8 5950.7 5972.9 5571.4 5685.0 5711.9 5719.6 5769.2 4577.9 5066.5 5699.0 5702.5 5722.4 4997.8 5376.9 5417.0 5631.6 5632.6 4890.9 5637.4 5706.9 5769.9 5785.5 5164.8 5608.8 5630.3 5637.8 5665.9 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - PROCESS | 1923 | [#5] yui.html Cycles:180 Average:19293.99 Median:49744.51 stddev:27002.17 (54.3%) stddev-sans-first:27041.93 16:35:35 INFO - PROCESS | 1923 | Values: 728.3 741.7 770.9 799.6 815.6 13240.5 15229.8 16004.8 16052.2 16094.1 11777.8 11952.5 12322.2 14406.1 14452.0 675.6 705.3 832.6 843.7 873.6 689.6 843.6 847.4 851.1 857.5 875.7 904.5 907.2 907.7 908.8 898.7 901.0 901.8 901.9 903.2 656.4 660.2 665.7 666.3 668.6 14682.0 14846.6 15088.2 15277.9 15409.2 14163.3 14415.3 14607.9 14661.4 14742.2 11853.7 11871.9 11924.3 11959.6 12030.5 3660.2 3663.7 3725.7 3734.9 3860.7 96607.5 98831.0 99883.0 99923.5 103000.5 10938.2 10990.3 11086.7 11160.5 11335.2 22526.8 22703.8 22717.9 23033.3 23107.3 7885.9 7906.4 7971.1 8036.6 8040.6 3900.5 3961.6 3976.0 3982.3 3998.7 2947.8 3415.3 3514.1 3525.2 3610.3 673.7 684.2 687.9 695.3 697.0 532.6 626.3 630.1 646.9 684.0 8743.9 8969.6 9004.0 9273.6 9619.8 603.0 609.1 616.9 644.7 644.8 4112.9 4479.4 4582.4 4765.8 4832.0 82838.0 84630.0 86554.0 106134.0 106804.4 65444.0 77329.0 80147.0 81593.6 88943.0 52825.7 61810.5 69417.7 77760.6 79100.6 8188.8 9930.1 9936.6 9951.5 10009.4 47231.0 49457.0 50032.0 50350.7 51824.7 53928.7 63153.0 63599.7 66170.7 75325.6 11170.4 11842.6 12818.9 12856.7 13376.9 14341.9 14741.6 14781.0 14865.6 15495.3 9711.5 10200.9 13567.3 13781.6 14041.9 13276.5 13593.3 13601.5 14004.9 14227.6 12922.3 13426.7 13455.7 13610.3 13621.8 8856.1 10797.4 10998.9 11245.0 11429.7 9208.2 9782.7 9933.3 11144.3 11274.1 16:35:35 INFO - PROCESS | 1923 | -------- Summary: end -------- 16:35:35 INFO - PROCESS | 1923 | 16:35:35 INFO - TEST-INFO | 1923: exit 0 16:35:35 INFO - TEST-OK | dromaeo_css | took 1117169ms 16:35:35 INFO - TEST-START | kraken 16:35:35 INFO - operating with platform_type : mac_ 16:35:35 INFO - Initialising browser for kraken test... 16:35:35 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmphcueD2/profile http://localhost:54559/getInfo.html) 16:35:38 INFO - PROCESS | 1930 | 2016-12-21 16:35:38.644 plugin-container[1931:19912] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 16:35:38 INFO - PROCESS | 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:38 INFO - PROCESS | 1930 | 2016-12-21 16:35:38.646 plugin-container[1931:19912] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 16:35:38 INFO - PROCESS | 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:38 INFO - PROCESS | 1930 | 2016-12-21 16:35:38.646 plugin-container[1931:19912] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:38 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 16:35:38 INFO - PROCESS | 1930 | colorDepth:24 16:35:38 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 16:35:38 INFO - PROCESS | 1930 | __metrics 16:35:38 ERROR - PROCESS | 1930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 16:35:39 INFO - TEST-INFO | 1930: exit 0 16:35:39 INFO - Browser initialized. 16:35:39 INFO - Running cycle 1/1 for kraken test... 16:35:39 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmphcueD2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:41 INFO - PROCESS | 1932 | 2016-12-21 16:35:41.925 plugin-container[1933:20029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 16:35:41 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:41 INFO - PROCESS | 1932 | 2016-12-21 16:35:41.926 plugin-container[1933:20029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:35:41 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:41 INFO - PROCESS | 1932 | 2016-12-21 16:35:41.926 plugin-container[1933:20029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:51 INFO - PROCESS | 1932 | RSS: Main: 237064192 16:35:51 INFO - PROCESS | 1932 | 16:35:54 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54559/tests/kraken/driver.html?testName=audio-beat-detection) 16:35:55 INFO - PROCESS | 1932 | RSS: Main: 249577472 16:35:55 INFO - PROCESS | 1932 | 16:35:58 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54559/tests/kraken/driver.html?testName=audio-dft) 16:35:58 INFO - PROCESS | 1932 | RSS: Main: 257155072 16:35:58 INFO - PROCESS | 1932 | 16:36:02 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54559/tests/kraken/driver.html?testName=audio-fft) 16:36:02 INFO - PROCESS | 1932 | RSS: Main: 259813376 16:36:02 INFO - PROCESS | 1932 | 16:36:04 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54559/tests/kraken/driver.html?testName=audio-oscillator) 16:36:05 INFO - PROCESS | 1932 | RSS: Main: 262119424 16:36:05 INFO - PROCESS | 1932 | 16:36:07 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54559/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:36:08 INFO - PROCESS | 1932 | RSS: Main: 262389760 16:36:08 INFO - PROCESS | 1932 | 16:36:11 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54559/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:12 INFO - PROCESS | 1932 | RSS: Main: 267882496 16:36:12 INFO - PROCESS | 1932 | 16:36:15 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54559/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:15 INFO - PROCESS | 1932 | RSS: Main: 267624448 16:36:15 INFO - PROCESS | 1932 | 16:36:18 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54559/tests/kraken/driver.html?testName=json-parse-financial) 16:36:19 INFO - PROCESS | 1932 | RSS: Main: 267767808 16:36:19 INFO - PROCESS | 1932 | 16:36:21 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54559/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:21 INFO - PROCESS | 1932 | RSS: Main: 266899456 16:36:21 INFO - PROCESS | 1932 | 16:36:23 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:24 INFO - PROCESS | 1932 | RSS: Main: 266878976 16:36:24 INFO - PROCESS | 1932 | 16:36:26 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:27 INFO - PROCESS | 1932 | RSS: Main: 266936320 16:36:27 INFO - PROCESS | 1932 | 16:36:30 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:31 INFO - PROCESS | 1932 | RSS: Main: 267235328 16:36:31 INFO - PROCESS | 1932 | 16:36:34 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:34 INFO - PROCESS | 1932 | RSS: Main: 267083776 16:36:34 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:54559/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54559/tests/kraken/driver.html?testName=ai-astar) 16:36:36 INFO - PROCESS | 1932 | RSS: Main: 268263424 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | __start_tp_report 16:36:36 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 16:36:36 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 16:36:36 INFO - PROCESS | 1932 | |i|pagename|runs| 16:36:36 INFO - PROCESS | 1932 | |0;ai-astar;105;133;115;97;125;120;105;109;135;106 16:36:36 INFO - PROCESS | 1932 | |1;audio-beat-detection;146;114;119;141;142;154;97;116;172;145 16:36:36 INFO - PROCESS | 1932 | |2;audio-dft;206;211;213;202;198;198;190;194;195;194 16:36:36 INFO - PROCESS | 1932 | |3;audio-fft;94;75;83;78;85;63;83;52;74;71 16:36:36 INFO - PROCESS | 1932 | |4;audio-oscillator;92;74;116;103;86;70;83;71;72;89 16:36:36 INFO - PROCESS | 1932 | |5;imaging-gaussian-blur;113;113;134;141;115;173;151;116;112;136 16:36:36 INFO - PROCESS | 1932 | |6;imaging-darkroom;118;97;97;104;98;97;98;97;102;154 16:36:36 INFO - PROCESS | 1932 | |7;imaging-desaturate;114;146;152;95;93;91;96;92;91;100 16:36:36 INFO - PROCESS | 1932 | |8;json-parse-financial;56;58;56;58;59;59;58;59;60;60 16:36:36 INFO - PROCESS | 1932 | |9;json-stringify-tinderbox;70;70;83;70;115;106;105;109;73;73 16:36:36 INFO - PROCESS | 1932 | |10;stanford-crypto-aes;89;91;92;111;113;111;90;90;90;94 16:36:36 INFO - PROCESS | 1932 | |11;stanford-crypto-ccm;146;156;140;142;138;147;150;157;195;182 16:36:36 INFO - PROCESS | 1932 | |12;stanford-crypto-pbkdf2;176;177;175;176;176;174;174;177;175;235 16:36:36 INFO - PROCESS | 1932 | |13;stanford-crypto-sha256-iterative;67;68;66;69;68;75;81;88;67;57 16:36:36 INFO - PROCESS | 1932 | __end_tp_report 16:36:36 INFO - PROCESS | 1932 | __start_cc_report 16:36:36 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,959 16:36:36 INFO - PROCESS | 1932 | __end_cc_report 16:36:36 INFO - PROCESS | 1932 | __startTimestamp1482366996743__endTimestamp 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | ------- Summary: start ------- 16:36:36 INFO - PROCESS | 1932 | Number of tests: 14 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#0] ai-astar Cycles:10 Average:115.00 Median:122.50 stddev:12.87 (10.5%) stddev-sans-first:13.13 16:36:36 INFO - PROCESS | 1932 | Values: 105.0 133.0 115.0 97.0 125.0 120.0 105.0 109.0 135.0 106.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:145.50 stddev:22.45 (15.4%) stddev-sans-first:23.43 16:36:36 INFO - PROCESS | 1932 | Values: 146.0 114.0 119.0 141.0 142.0 154.0 97.0 116.0 172.0 145.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#2] audio-dft Cycles:10 Average:200.10 Median:200.00 stddev:7.71 (3.9%) stddev-sans-first:7.88 16:36:36 INFO - PROCESS | 1932 | Values: 206.0 211.0 213.0 202.0 198.0 198.0 190.0 194.0 195.0 194.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#3] audio-fft Cycles:10 Average:75.80 Median:80.50 stddev:11.93 (14.8%) stddev-sans-first:10.69 16:36:36 INFO - PROCESS | 1932 | Values: 94.0 75.0 83.0 78.0 85.0 63.0 83.0 52.0 74.0 71.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:78.50 stddev:15.14 (19.3%) stddev-sans-first:15.88 16:36:36 INFO - PROCESS | 1932 | Values: 92.0 74.0 116.0 103.0 86.0 70.0 83.0 71.0 72.0 89.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#5] imaging-gaussian-blur Cycles:10 Average:130.40 Median:135.00 stddev:20.51 (15.2%) stddev-sans-first:20.76 16:36:36 INFO - PROCESS | 1932 | Values: 113.0 113.0 134.0 141.0 115.0 173.0 151.0 116.0 112.0 136.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#6] imaging-darkroom Cycles:10 Average:106.20 Median:97.00 stddev:18.01 (18.6%) stddev-sans-first:18.59 16:36:36 INFO - PROCESS | 1932 | Values: 118.0 97.0 97.0 104.0 98.0 97.0 98.0 97.0 102.0 154.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#7] imaging-desaturate Cycles:10 Average:107.00 Median:91.50 stddev:23.19 (25.3%) stddev-sans-first:24.46 16:36:36 INFO - PROCESS | 1932 | Values: 114.0 146.0 152.0 95.0 93.0 91.0 96.0 92.0 91.0 100.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:59.00 stddev:1.42 (2.4%) stddev-sans-first:1.24 16:36:36 INFO - PROCESS | 1932 | Values: 56.0 58.0 56.0 58.0 59.0 59.0 58.0 59.0 60.0 60.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#9] json-stringify-tinderbox Cycles:10 Average:87.40 Median:70.00 stddev:18.93 (27.0%) stddev-sans-first:19.01 16:36:36 INFO - PROCESS | 1932 | Values: 70.0 70.0 83.0 70.0 115.0 106.0 105.0 109.0 73.0 73.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:90.00 stddev:10.16 (11.3%) stddev-sans-first:10.34 16:36:36 INFO - PROCESS | 1932 | Values: 89.0 91.0 92.0 111.0 113.0 111.0 90.0 90.0 90.0 94.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:153.00 stddev:18.82 (12.3%) stddev-sans-first:19.65 16:36:36 INFO - PROCESS | 1932 | Values: 146.0 156.0 140.0 142.0 138.0 147.0 150.0 157.0 195.0 182.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:176.00 stddev:18.83 (10.7%) stddev-sans-first:19.86 16:36:36 INFO - PROCESS | 1932 | Values: 176.0 177.0 175.0 176.0 176.0 174.0 174.0 177.0 175.0 235.0 16:36:36 INFO - PROCESS | 1932 | 16:36:36 INFO - PROCESS | 1932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:68.50 stddev:8.68 (12.7%) stddev-sans-first:9.11 16:36:36 INFO - PROCESS | 1932 | Values: 67.0 68.0 66.0 69.0 68.0 75.0 81.0 88.0 67.0 57.0 16:36:36 INFO - PROCESS | 1932 | -------- Summary: end -------- 16:36:36 INFO - PROCESS | 1932 | 16:36:37 INFO - TEST-INFO | 1932: exit 0 16:36:37 INFO - TEST-OK | kraken | took 61716ms 16:36:37 INFO - SUITE-END | took 1178s 16:36:37 INFO - Completed test suite (00:19:39) 16:36:37 INFO - javascript benchmark 16:36:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7103.424014585519, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2733.9863300683496, 3326.6340702522702, 3350.91095393997, 3384.373890830198, 3407.693307602316, 5043.243513472981, 5452.536534394571, 5517.144842549401, 5541.889162216763, 5582.358028826686, 5449.073657478229, 5494.423085576017, 5530.834074977747, 5542.0301447246675, 5596.412376700459, 5003.674761140514, 5107.514786095315, 5140.460251673577, 5144.408393034792, 5207.750139966419, 5030.622703297249, 5201.817936372227, 5225.712585807779, 5252.921206181892, 5258.13240815265, 5073.949260507385, 5096.694198348074, 5107.770150343244, 5135.743212839362, 5183.637145399824, 4452.465704115494, 4865.467377775989, 5099.5410413063, 5143.305653736735, 5160.664556803796, 5056.014077254937, 5080.923786143211, 5096.592272618182, 5097.46476619957, 5128.153854613985, 4834.492378300298, 5182.98931708317, 5624.521915637176, 5624.6625202487985, 5666.348369937468, 5566.526845218162, 5590.468905453975, 5974.014287642533, 6142.498775697809, 6149.661768602725, 6240.531960102965, 6250.6874656267455, 6251.4061164189325, 6262.060690896356, 6276.0, 5724.284464441945, 5757.510611597977, 5759.164921086419, 5772.653640781483, 5820.068788993783, 6086.147939288503, 6164.229471316085, 6172.0, 6181.258249010148, 6234.064890266496, 5014.498550144956, 6118.816435506942, 6122.571420000557, 6130.264368275835, 6145.324014358417, 6216.129741836146, 6230.00319948806, 6231.345708700612, 6235.844103897348, 6247.312795592481, 5695.401982791747, 5716.885662286731, 5792.986227410186, 5806.854828629318, 5824.504917082096, 5406.945930540644, 6139.815805525842, 6170.5372097092895, 6210.0, 6251.12484252205, 4770.737609431515, 4811.398575178103, 4840.419149701988, 4882.829100981448, 4928.753562321941, 4272.722273052242, 4295.806688699016, 4360.542143074995, 4382.145481631052, 4402.977985110118, 5769.249997500298, 5785.363610002896, 5791.913121303184, 5826.300843898674, 5875.265591801025, 6222.973209420486, 6242.968785156136, 6245.0, 6254.874902501924, 6270.7491700331475, 5591.10542313228, 5635.3519345275, 5643.097104463266, 5793.304803423616, 5808.186853840466, 5305.124654432053, 5319.9202011969855, 5351.919721204185, 5352.518273355417, 5360.758765855546, 6214.223222097238, 6273.749050038039, 6283.905741413791, 6285.842853928689, 6299.496040316763, 5032.6980381177245, 5066.620003499678, 5170.482951704799, 6046.27444706638, 6191.442770150708, 5899.941000589939, 6182.103594978793, 6194.504439644727, 6226.1905952225925, 6337.524685648687, 6124.564025909317, 6160.599561028519, 6169.444749972345, 6172.598781079216, 6194.0708893664505, 6180.227471566054, 6217.844553886189, 6240.750369985331, 6259.467945224706, 6276.964300890302, 5844.912326315194, 5850.3857095006115, 5905.173275741486, 6040.184575082308, 6136.478399336106, 5945.048792193401, 5966.3138739043825, 5977.983742763654, 5980.940190598212, 5985.491233245223, 5027.899442011066, 5598.468145526169, 5759.798407055733, 5827.76688932438, 5830.329512106162, 5972.820815375546, 6053.727582258897, 6058.4850287724275, 6071.787487437919, 6089.634621922699, 4895.388076490439, 5879.882402352015, 5894.852628684317, 5921.200637913998, 5927.2590926134235], "value": 5562.838290327879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.52891199784216, 465.8695565241732, 484.65105124310526, 486.8077109541729, 508.0448756338085, 38511.037224069376, 40292.59707402929, 40418.0, 40513.0, 40537.59462405375, 42146.36780448288, 42576.50982215623, 42731.71804845852, 42780.786096069474, 42786.35820462696, 8434.746957591251, 9331.113544213305, 9548.713538593853, 9561.61753529858, 9591.616335346578, 8607.0, 11222.326660400362, 11484.942575287112, 11503.367314797682, 11661.883381166164, 9834.26243031772, 11409.31544107352, 11457.911498407639, 11473.0, 11478.655640330804, 11412.771744565103, 11421.771564568662, 11496.137789665725, 11497.080233581335, 11529.250598711056, 11220.887791122066, 11293.71765705864, 11317.151213658926, 11384.544618215345, 11418.0, 39895.00262493432, 40196.598034019575, 40243.195136097114, 40251.59748402508, 40327.7983610083, 3325.5011748237716, 3496.7552271341015, 3528.2414280929465, 3548.1661809474754, 3803.2013277211818, 27465.862670686718, 27546.44907101847, 27734.5839812403, 28851.27871803201, 28885.85557072201, 3200.199950012497, 3201.375731732313, 3219.2917558137174, 3223.4520131577456, 3329.267561136546, 488569.5571522155, 489434.0, 494128.0, 495409.0, 497022.51488742686, 54391.728041359536, 54676.726616366665, 54810.0, 54846.72576637171, 56004.439955600734, 56754.0, 56799.71600142056, 56998.14502782462, 57110.0, 57297.427025730045, 14778.039427437398, 15238.390464381522, 15420.306086226146, 15427.768583471257, 15457.227138643248, 10771.83842242367, 11010.944945275383, 11796.99725523323, 11934.820977685342, 12033.0, 16137.59656008609, 17405.738913916302, 17478.388256411217, 17480.650386992187, 17700.91149544244, 6333.891568975411, 6409.487241020707, 6486.962086066207, 6934.826629334206, 7409.407247420193, 873.9388242823069, 874.6181033966318, 1027.2552399510296, 1049.7847941172042, 1051.2536099369381, 29451.705482945326, 30104.736766233822, 30144.24639384033, 30256.84871575672, 30257.394852103276, 1019.403648865422, 1023.3757407981126, 1062.585591619269, 1064.744461329291, 1080.6757972608186, 12515.812262816065, 14042.648933776532, 15539.30073146711, 15688.686226275411, 15991.760123598155, 649591.0, 651049.0, 656790.0, 658075.7096214489, 658891.0, 58923.0, 59138.40861591415, 59473.702631486565, 59705.0, 59888.70055649694, 1351.2703140304125, 1366.0369439545098, 1395.3511617097895, 1401.9299035048207, 1404.03121845926, 27852.44295114046, 28917.0, 30019.0, 30421.847890760404, 30623.846880765454, 47888.521114788404, 50204.24693629672, 51062.74468627633, 51102.0, 51166.48833511617, 521740.0, 607948.0, 616849.0, 617249.0, 618032.9098354479, 24806.875965620056, 25452.618210727225, 25587.6161857576, 38306.80846595749, 38741.83774486657, 41651.958701032716, 41905.37141942934, 42570.787146064074, 42718.78640606777, 43011.784941076345, 35450.645493545766, 35568.644313556535, 35650.64349356577, 36444.81777591095, 36903.63096369002, 31624.893128740383, 33299.0, 34412.13969650678, 35002.64997349994, 35173.0, 34394.82802585971, 35147.689018969446, 35150.94547163589, 35347.82326088353, 35432.29135417226, 27988.0, 30092.3981520364, 31611.683883160873, 31968.840155799073, 32128.357432850746, 18376.63246735031, 21769.891150544147, 24004.63993040141, 24124.396890077187, 24400.97164049313], "value": 19710.78106449951, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.67600455571767, 369.90567405311657, 383.53783690652756, 387.34151941699116, 402.65572935140455, 18845.434636960897, 19716.507087322825, 19878.403647890587, 20214.89892550537, 20260.59478810425, 16232.594185145377, 17725.0, 20253.088611012503, 20473.87393693346, 20729.378118656427, 4624.606908412789, 5607.551395888319, 5782.3639399666, 5828.708564571775, 5835.9124613130625, 5581.916271255935, 5745.2818397700285, 5745.396733343002, 5754.712264386785, 5762.366139724627, 5625.296837895263, 5739.311282646088, 5740.942590574104, 5748.511376533, 5915.438033386821, 5105.617078719111, 6020.307664618559, 6080.118382834508, 6082.422169893853, 6088.96955515224, 5627.8593035174035, 5786.68173250471, 5854.531637469035, 5859.8535036624, 5862.794802181903, 10455.947720261425, 12802.103852730312, 12960.935195324057, 13199.538016169485, 13236.602901912862, 1994.6210220058142, 2011.919523219084, 2144.270947877729, 2160.7083043789044, 2188.3763127508582, 8324.791880203044, 9743.220542356594, 9922.90077099227, 9923.404595724207, 10432.269741118125, 1730.264637529045, 1799.5501124718821, 1848.223746026672, 1881.1252767463059, 1928.816762407569, 105802.47098764534, 107054.92945070527, 108816.4559177207, 111429.0, 111854.99999999919, 9255.398399104171, 11174.832377514344, 11308.830367544493, 11425.371604561662, 11672.124590655734, 14257.429702811978, 14281.285935703174, 14285.642858928402, 14286.85713142876, 14324.283785810669, 8104.675812967415, 8265.421420500628, 8404.151086760903, 8409.495430274203, 8533.53065581399, 7471.4396420268695, 8244.958775206085, 8260.504369737835, 8415.747527574182, 8466.619002144918, 3753.7935413551972, 4507.301368287855, 4569.474510431343, 4576.977115114403, 4601.838935637236, 1875.859310551714, 1935.9032048397523, 1942.6211888681853, 1956.9315073972343, 1957.725918371429, 738.9408847292203, 804.4248362420897, 808.9312408445348, 875.7197696737111, 964.1756298364797, 15641.843581564266, 19597.412077637695, 20397.79602203959, 21255.36233912985, 21396.465088372915, 787.2245837849714, 862.943908645936, 883.88067610873, 918.8254231695956, 935.5930170375908, 10754.354738715701, 11139.777204455866, 11172.273802202993, 11176.552937882556, 11191.944040279746, 522626.0, 525389.0, 531740.0, 534664.0, 535831.0, 82012.44493884368, 85221.57389213014, 93419.53290233642, 121772.0, 121896.0, 899.4603238057114, 953.3136141977626, 1020.1379834040224, 1025.5897640943533, 1035.7876635289747, 17904.641907161524, 19475.415737527896, 19491.02544872779, 19842.404727858186, 19886.403407897786, 49913.750431249064, 51381.48618513917, 52286.18019159139, 52823.73588132035, 53181.0, 49307.0, 69265.61468770697, 73616.63191684007, 74063.6296818534, 74660.2533974675, 7978.92021079805, 8001.159878212704, 8150.225728555778, 8187.918120818954, 8308.626111825104, 10275.7431064221, 12627.558035468715, 12731.172473789175, 12952.935235323763, 13161.473541058633, 8819.38264321517, 8866.601002954754, 10836.187285953427, 10953.288036277936, 10983.890161098287, 10676.786464270515, 10858.674239772503, 10885.727856803644, 11161.776764464503, 11220.326780393203, 8105.959470202612, 9071.455712657262, 10862.511186996762, 11018.614348497764, 11200.887991119984, 6931.757388491378, 7113.715451381887, 7212.603306818175, 7419.443541734284, 8403.537805420761, 9462.14840664316, 9973.002699729968, 10092.444915529717, 10905.400202988912, 11072.557097716], "value": 8974.599932108016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.5183430296486, 1481.7184734900375, 1491.2618253964285, 1656.2877962476139, 1747.196289706734, 7479.700811967515, 7671.386289096873, 7994.160613135617, 8426.241638252564, 8463.492190468576, 6895.44836413087, 7901.723439679612, 8206.343492520598, 8207.343412526998, 8308.210719981607, 2250.313654335427, 2309.434188623781, 2314.8495347802423, 2317.6639387288833, 2326.6626339180807, 2303.6890019847356, 2329.1615018593293, 2355.387599224197, 2364.408897775556, 2368.9931778993946, 2280.726312842461, 2283.6117859963765, 2321.0483701682315, 2321.6401457773986, 2324.0, 2264.445210923326, 2275.4880151965676, 2288.8168946484407, 2296.299628613264, 2298.2645553422913, 2236.608593496148, 2270.5572413379396, 2271.9204827830945, 2273.59075366434, 2282.7374851891914, 8304.377171712145, 8372.874406883902, 8376.329893608558, 8377.874331885027, 8394.202550757669, 5031.471695471996, 5151.2273159026445, 5189.455107213764, 5196.428392876781, 5196.974015129938, 3243.5783348164823, 3341.18141055443, 3492.214251793326, 3519.436890097598, 3526.2066035142143, 1031.437866362834, 1031.7730099378125, 1062.0866055192605, 1080.8432777247333, 1081.5403453532217, 10267.640632577824, 10490.790184196274, 10548.419836909045, 10562.31344962575, 10564.207684423698, 4390.868273951786, 4486.75322857246, 4580.24425969718, 4587.4173410173435, 4645.233536466445, 9131.634734610674, 9138.862917056249, 9155.862662060075, 9231.95384023089, 9259.166674999282, 5110.897782044338, 5120.283160357553, 5146.433892271847, 5162.0, 5167.793288268427, 4154.148399578079, 4292.463442069741, 4612.60792832613, 4710.952890471051, 4719.433667959798, 3475.6792418880664, 3734.7572407793405, 3763.9435408468894, 3858.6334298241622, 3891.6886649068006, 1113.6158025481195, 1253.4422182129047, 1258.8111783232405, 1263.0463999680182, 1270.0538099116218, 532.424981020497, 789.2541548236861, 902.6750369866843, 905.1401168889449, 943.688582767685, 4564.954350456453, 5215.400228973716, 5522.586050408431, 5600.607957443021, 5697.800576979786, 882.3250213586483, 894.7181637784078, 897.8787863638456, 934.6121359635675, 1015.5376258188186, 3213.8714451421683, 3782.8486860525823, 3825.445310430028, 3887.455756194135, 3905.0, 7947.721829736047, 9600.279979001603, 9827.852582211273, 9888.752781180528, 9948.0, 9845.0, 9879.851802222973, 9885.0, 9885.604575817031, 9889.950550247346, 7403.8149046274275, 7733.072031356273, 7739.61301934901, 7762.961185194151, 7908.2091790821605, 4521.864344069683, 5610.410906854721, 5655.293088363955, 5702.258706368146, 5732.254806875247, 8609.569521524025, 8717.346199035224, 8721.345899057724, 8810.339224558056, 8867.556622168737, 8926.86609700842, 8952.552372381486, 9079.364444488823, 9115.407498512575, 9128.224100951493, 6472.385123413268, 6663.600183988976, 6837.35045170708, 7141.607211603204, 7508.624568771649, 7653.158152603321, 7776.688932442639, 7845.882311765214, 7866.7639970800965, 7902.762917112495, 6965.199002114619, 7090.539114957512, 7110.1112360954885, 7113.786586402416, 7172.677229524579, 5695.715214239354, 6832.282610326043, 6974.192196805674, 7049.682764275517, 7121.608311542915, 6362.331955144643, 6545.116409284686, 6599.406053455212, 6633.834154146185, 6637.6349300788925, 3652.7077833773765, 4767.976160119178, 4823.2282834746275, 4844.24914138295, 4948.38145231846, 4741.786619602057, 4830.304816815069, 4847.491013443538, 4880.633952453509, 4962.851114466572], "value": 4326.581296326581, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.97008254298385, 358.2351679164982, 358.8259694048387, 362.06948143271785, 368.75177524080976, 5409.837704868855, 5477.302036368728, 5493.02251189572, 5494.835154945348, 5503.53219976302, 4996.67521611095, 5104.744762761866, 5374.54887180462, 5399.676019438836, 5427.457254274571, 3349.296647703985, 3756.3801972674614, 3762.3792074307844, 3853.942190867125, 3897.649211570958, 4013.2374848778786, 4147.460830092099, 4199.811008504625, 4228.577142285778, 4235.903227902178, 3321.335732853427, 3480.3909315869746, 3558.7508874378805, 3834.5973672764376, 3968.8490337802004, 4011.3982902564558, 4205.222033923734, 4212.326027835532, 4222.78886055696, 4233.724807887493, 2747.5466548019554, 3048.2988912550018, 3161.2571045804216, 3681.9263614727824, 3704.9629503705155, 5209.9319639473815, 5309.601779866487, 5328.600354973354, 5336.636680136477, 5366.0, 4799.6400269979895, 4837.443693975203, 4844.854654360375, 4862.3192753014255, 4873.780679869415, 5553.750081246354, 5682.573806964453, 5697.088465845445, 5720.1991721159075, 5738.856528586777, 3569.94645080324, 4057.0263136846943, 4084.407760874686, 4099.754014759094, 4100.753954762724, 4685.765711714401, 5662.288148214326, 5837.059984510439, 5995.062419414499, 6005.579609427342, 3687.170386663033, 3821.3503704370323, 4174.977130603022, 4193.245215861174, 4201.264778663722, 3895.2599006188734, 4227.471566054243, 4265.0616864289805, 4271.9359209611885, 4297.88255053682, 4705.435347758291, 4833.830815921426, 4844.456660253325, 4850.0, 4891.51084891508, 5703.800366987219, 5764.913526297109, 5799.768009279666, 5809.419058094241, 5826.533877289807, 1192.4633914738401, 1338.9422356338578, 1339.591424615482, 1347.5148946379295, 1388.5279005138302, 904.5703290936752, 914.4467597103657, 956.531299663174, 972.6304004478394, 972.7713987212999, 4658.557437043475, 5076.873078173076, 5097.643164978416, 5121.820736274212, 5191.532762051434, 5040.84877453677, 5421.322334708161, 5455.672659640434, 5504.422035686275, 5667.489925906688, 4533.52397998212, 4644.419447569054, 4767.928481072787, 4862.343583616237, 5045.747712614355, 2566.3703548247, 2570.537303285389, 2682.45009773, 2830.1462542035, 2977.3003344214094, 4948.505149485023, 5183.170692689152, 5794.62334948227, 5858.648481091148, 5913.645181289136, 4901.730404827768, 5348.331458567679, 5423.240746295522, 5759.712014399263, 5769.567282453749, 5185.274061631375, 5626.751802389252, 5787.508061814793, 5861.296644402529, 5933.0, 4575.565321294472, 5200.791968321376, 5370.248165256945, 5625.818578098645, 5680.886382272249, 4946.851594452172, 4980.800767969241, 5114.565261952627, 5184.896302073862, 5270.997021131945, 4824.33560421654, 5189.714565698923, 5237.476252374732, 5787.594868359174, 5983.790567330123, 4145.564715704808, 4150.609545802069, 4152.43942067817, 4158.542560318302, 4165.312723400605, 5297.000764422836, 5866.911996320144, 5948.828677532756, 5950.732217050157, 5972.940270597239, 5571.350765248769, 5684.986956912843, 5711.914736200109, 5719.56918309725, 5769.249997500298, 4577.908441831079, 5066.544011039025, 5699.002674532023, 5702.547904401147, 5722.4277572242445, 4997.751910875727, 5376.924615076922, 5416.9729151353995, 5631.63394379364, 5632.5775566831835, 4890.902181956412, 5637.351704554028, 5706.857328566819, 5769.922827782079, 5785.508231800344, 5164.786275225244, 5608.766071464434, 5630.324361076697, 5637.774489020393, 5665.886682266414], "value": 4142.456994316574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.2862794461431, 741.7255615422293, 770.9036370453694, 799.588212070784, 815.5759005317235, 13240.53658121966, 15229.771553426694, 16004.839951600481, 16052.197390130475, 16094.114823684693, 11777.764444711122, 11952.521899124025, 12322.199057061307, 14406.063605865638, 14452.0, 675.5946432140701, 705.2700455029046, 832.646125396707, 843.6541018182531, 873.5588527793456, 689.6413864790306, 843.569779412501, 847.4068152293388, 851.1148405658139, 857.5326447086321, 875.6935072724527, 904.5025236120141, 907.2061945797427, 907.7004588485817, 908.7728067983004, 898.6674930275807, 900.9729708108702, 901.8016036471965, 901.9368644194909, 903.2277402820592, 656.3862788292913, 660.2110477978817, 665.6871270502904, 666.2770893580471, 668.6289109544201, 14682.0, 14846.554603361916, 15088.24558772057, 15277.923610381877, 15409.152496612682, 14163.291835408188, 14415.279236038261, 14607.853921460863, 14661.41354345825, 14742.189179595225, 11853.703657408547, 11871.881281187163, 11924.284542927453, 11959.641210763692, 12030.4586293617, 3660.158163622355, 3663.6519530644814, 3725.701943844486, 3734.8692795752017, 3860.6718428933573, 96607.51696241544, 98831.0, 99883.0, 99923.50038249762, 103000.48499757529, 10938.179636527293, 10990.285631433931, 11086.667399978014, 11160.497777599865, 11335.206535542598, 22526.774732252794, 22703.772962270166, 22717.88641056784, 23033.309000730005, 23107.306780796604, 7885.921140788633, 7906.407019473562, 7971.104745298294, 8036.557989310527, 8040.638171282307, 3900.4929359183125, 3961.643452089326, 3975.9662487753394, 3982.342913419311, 3998.7200895937585, 2947.8083924544835, 3415.282790613949, 3514.05120617437, 3525.2244506208594, 3610.332088563624, 673.6833688166554, 684.1687349869833, 687.8968154776824, 695.2942763095463, 696.9789258735976, 532.5925666864852, 626.3110578363763, 630.077765907233, 646.890028695123, 683.9829004274933, 8743.893268526743, 8969.596368163448, 9003.954980225057, 9273.629054837866, 9619.78564230356, 602.9638221706712, 609.1411110334495, 616.9074638804216, 644.7324360390479, 644.8065580325883, 4112.9100788291125, 4479.440069991251, 4582.381378513925, 4765.833195838129, 4831.975840120847, 82838.0, 84630.0, 86554.0, 106134.0, 106804.39793403105, 65444.0, 77329.0, 80147.0, 81593.59203203947, 88943.0, 52825.735871321165, 61810.4547386301, 69417.65291173614, 77760.61119694478, 79100.60449697715, 8188.754337369888, 9930.106290433896, 9936.615212349128, 9951.502424878872, 10009.449480278654, 47231.0, 49457.010859783324, 50032.0, 50350.74824626, 51824.74087629538, 53928.73035634954, 63153.0, 63599.682001589696, 66170.67658646897, 75325.62337188279, 11170.385628790495, 11842.585509507126, 12818.884883994036, 12856.678583035498, 13376.866231337563, 14341.92829035848, 14741.631459213606, 14781.0, 14865.628359291104, 15495.302711378241, 9711.5144242789, 10200.9196321472, 13567.321633918067, 13781.586552403443, 14041.929790350983, 13276.53532126371, 13593.252371119288, 13601.523946661819, 14004.929975350466, 14227.573172804832, 12922.289274090015, 13426.731465370443, 13455.663608409868, 13610.319484025957, 13621.795673064713, 8856.059330844773, 10797.352158870493, 10998.945005274922, 11244.987951084442, 11429.714257143305, 9208.207358424155, 9782.657606984007, 9933.255005874444, 11144.33134011962, 11274.098072154044], "value": 7283.526348948868, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1604.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 133.0, 115.0, 97.0, 125.0, 120.0, 105.0, 109.0, 135.0, 106.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 119.0, 141.0, 142.0, 154.0, 97.0, 116.0, 172.0, 145.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 211.0, 213.0, 202.0, 198.0, 198.0, 190.0, 194.0, 195.0, 194.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 75.0, 83.0, 78.0, 85.0, 63.0, 83.0, 52.0, 74.0, 71.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 74.0, 116.0, 103.0, 86.0, 70.0, 83.0, 71.0, 72.0, 89.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 134.0, 141.0, 115.0, 173.0, 151.0, 116.0, 112.0, 136.0], "value": 130.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 97.0, 97.0, 104.0, 98.0, 97.0, 98.0, 97.0, 102.0, 154.0], "value": 106.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 146.0, 152.0, 95.0, 93.0, 91.0, 96.0, 92.0, 91.0, 100.0], "value": 107.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 58.0, 59.0, 59.0, 58.0, 59.0, 60.0, 60.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 83.0, 70.0, 115.0, 106.0, 105.0, 109.0, 73.0, 73.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 92.0, 111.0, 113.0, 111.0, 90.0, 90.0, 90.0, 94.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 156.0, 140.0, 142.0, 138.0, 147.0, 150.0, 157.0, 195.0, 182.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 175.0, 176.0, 176.0, 174.0, 174.0, 177.0, 175.0, 235.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 69.0, 68.0, 75.0, 81.0, 88.0, 67.0, 57.0], "value": 70.6, "unit": "score"}]}]} 16:36:37 INFO - Return code: 0 16:36:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:38 ERROR - # TBPL SUCCESS # 16:36:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:36:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:36:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:36:38 INFO - 2016-12-21 16:36:38.067 screenresolution[1937:20417] starting screenresolution argv=screenresolution get 16:36:38 INFO - 2016-12-21 16:36:38.070 screenresolution[1937:20417] Display 0: 1600x1200x32@60 16:36:38 INFO - 2016-12-21 16:36:38.076 screenresolution[1938:20420] starting screenresolution argv=screenresolution list 16:36:38 INFO - Available Modes on Display 0 16:36:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:36:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:36:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:36:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:36:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:36:38 INFO - Intel Iris: 16:36:38 INFO - Chipset Model: Intel Iris 16:36:38 INFO - Type: GPU 16:36:38 INFO - Bus: Built-In 16:36:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:36:38 INFO - Vendor: Intel (0x8086) 16:36:38 INFO - Device ID: 0x0a2e 16:36:38 INFO - Revision ID: 0x0009 16:36:38 INFO - Displays: 16:36:38 INFO - Display: 16:36:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:36:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:36:38 INFO - Display Serial Number: 1600x1200 60 16:36:38 INFO - Main Display: Yes 16:36:38 INFO - Mirror: Off 16:36:38 INFO - Online: Yes 16:36:38 INFO - Rotation: Supported 16:36:38 INFO - Return code: 0 16:36:38 INFO - Running post-action listener: _resource_record_post_action 16:36:38 INFO - [mozharness: 2016-12-22 00:36:38.320841Z] Finished run-tests step (success) 16:36:38 INFO - Running post-run listener: _resource_record_post_run 16:36:38 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 1590784; Write bytes: 552761344; Read time: 14; Write time: 1699 16:36:38 INFO - TinderboxPrint: CPU usage
54.6% 16:36:38 INFO - TinderboxPrint: I/O read bytes / time
1,590,784 / 14 16:36:38 INFO - TinderboxPrint: I/O write bytes / time
552,761,344 / 1,699 16:36:38 INFO - TinderboxPrint: CPU idle
2,183.8 (45.4%) 16:36:38 INFO - TinderboxPrint: CPU system
972.1 (20.2%) 16:36:38 INFO - TinderboxPrint: CPU user
1,649.8 (34.3%) 16:36:38 INFO - TinderboxPrint: Swap in / out
251,731,968 / 0 16:36:38 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250585600; Write bytes: 250744832; Read time: 18129; Write time: 547 16:36:38 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1553920; Write bytes: 300521472; Read time: 14; Write time: 1140 16:36:38 INFO - Running post-run listener: _upload_blobber_files 16:36:38 INFO - Blob upload gear active. 16:36:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:38 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 16:36:38 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:36:38 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:36:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:36:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:36:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:40 INFO - (blobuploader) - INFO - Done attempting. 16:36:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:36:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:36:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:41 INFO - (blobuploader) - INFO - Done attempting. 16:36:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:36:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:36:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:42 INFO - (blobuploader) - INFO - Done attempting. 16:36:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:45 INFO - (blobuploader) - INFO - Done attempting. 16:36:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:45 INFO - Return code: 0 16:36:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/394d2d1140e687d4782e26df9735d6a579affa31d50b084711ddc8f30abd5130c27dfd830fc0c16956e5a8724bd55ba90e8b18506720fccf1888f238918c238d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/228bfb9499ede2016c30fc78e22084413941d0015d26de64734c7535754f7e2781b501b2d2d1b77fb1f05c5ba284ee03470c6ffd03883481a34fd9cff9a6afb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c56e2dc1460fede706dce20b3d8f17dfc60d5eff220bfd1b92ad4321f9094dacae0744d8af29508fbf42d7570ac9a7e439f7352e7ae6b66b9abc70ee5a97a469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/689819cf295bfd586dae76b4432fdd5ba43a18ca45b43ea79808b6dca97af1bc26d20aa9890984bd028472de54240903c57c6d3ee5c0481aa455780bca70a2b4"} 16:36:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:45 INFO - Contents: 16:36:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/394d2d1140e687d4782e26df9735d6a579affa31d50b084711ddc8f30abd5130c27dfd830fc0c16956e5a8724bd55ba90e8b18506720fccf1888f238918c238d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/228bfb9499ede2016c30fc78e22084413941d0015d26de64734c7535754f7e2781b501b2d2d1b77fb1f05c5ba284ee03470c6ffd03883481a34fd9cff9a6afb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c56e2dc1460fede706dce20b3d8f17dfc60d5eff220bfd1b92ad4321f9094dacae0744d8af29508fbf42d7570ac9a7e439f7352e7ae6b66b9abc70ee5a97a469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/689819cf295bfd586dae76b4432fdd5ba43a18ca45b43ea79808b6dca97af1bc26d20aa9890984bd028472de54240903c57c6d3ee5c0481aa455780bca70a2b4"} 16:36:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:45 INFO - Copying logs to upload dir... 16:36:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.509423 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-21 16:36:45.222740) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 16:36:45.226175) ========= 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.ud023hzEJN/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.Cy8OWzHmUq/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/Graphics/sha512/394d2d1140e687d4782e26df9735d6a579affa31d50b084711ddc8f30abd5130c27dfd830fc0c16956e5a8724bd55ba90e8b18506720fccf1888f238918c238d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/228bfb9499ede2016c30fc78e22084413941d0015d26de64734c7535754f7e2781b501b2d2d1b77fb1f05c5ba284ee03470c6ffd03883481a34fd9cff9a6afb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c56e2dc1460fede706dce20b3d8f17dfc60d5eff220bfd1b92ad4321f9094dacae0744d8af29508fbf42d7570ac9a7e439f7352e7ae6b66b9abc70ee5a97a469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/689819cf295bfd586dae76b4432fdd5ba43a18ca45b43ea79808b6dca97af1bc26d20aa9890984bd028472de54240903c57c6d3ee5c0481aa455780bca70a2b4"} build_url:https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010849 build_url: 'https://queue.taskcluster.net/v1/task/a0eFXmTRT86iFH6lh_Ixbg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/394d2d1140e687d4782e26df9735d6a579affa31d50b084711ddc8f30abd5130c27dfd830fc0c16956e5a8724bd55ba90e8b18506720fccf1888f238918c238d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/228bfb9499ede2016c30fc78e22084413941d0015d26de64734c7535754f7e2781b501b2d2d1b77fb1f05c5ba284ee03470c6ffd03883481a34fd9cff9a6afb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c56e2dc1460fede706dce20b3d8f17dfc60d5eff220bfd1b92ad4321f9094dacae0744d8af29508fbf42d7570ac9a7e439f7352e7ae6b66b9abc70ee5a97a469", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/689819cf295bfd586dae76b4432fdd5ba43a18ca45b43ea79808b6dca97af1bc26d20aa9890984bd028472de54240903c57c6d3ee5c0481aa455780bca70a2b4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 16:36:45.264708) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 16:36:45.265179) ========= 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.ud023hzEJN/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.Cy8OWzHmUq/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.005260 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 16:36:45.313601) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 16:36:45.313942) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 16:36:45.314279) ========= ========= Total master_lag: 0.49 =========