builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0006 starttime: 1481740154.06 results: success (0) buildid: 20161214073201 builduid: 5085b9116316411081b9c22c1d054fba revision: 7604973adccb02cd3023259404aee502ff9da87f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.062506) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.062980) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.063257) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.088179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.088531) ========= 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.MujjCp9wSb/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.VoQtaJh3fo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-14 10:29:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.0M=0.001s 2016-12-14 10:29:14 (13.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.383021 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.495381) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.495710) ========= 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.MujjCp9wSb/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.VoQtaJh3fo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.019771 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:14.544702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-14 10:29:14.545067) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 7604973adccb02cd3023259404aee502ff9da87f --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 7604973adccb02cd3023259404aee502ff9da87f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MujjCp9wSb/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.VoQtaJh3fo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-14 10:29:14,620 truncating revision to first 12 chars 2016-12-14 10:29:14,620 Setting DEBUG logging. 2016-12-14 10:29:14,620 attempt 1/10 2016-12-14 10:29:14,620 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/7604973adccb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 10:29:16,497 unpacking tar archive at: graphics-7604973adccb/testing/mozharness/ program finished with exit code 0 elapsedTime=2.112480 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-14 10:29:16.681147) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:16.681481) ========= script_repo_revision: 7604973adccb02cd3023259404aee502ff9da87f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:16.681882) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:16.682153) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 10:29:16.702300) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-12-14 10:29:16.702695) ========= /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.MujjCp9wSb/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.VoQtaJh3fo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:29:16 INFO - MultiFileLogger online at 20161214 10:29:16 in /builds/slave/test 10:29:16 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 10:29:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:29:16 INFO - {'append_to_log': False, 10:29:16 INFO - 'base_work_dir': '/builds/slave/test', 10:29:16 INFO - 'blob_upload_branch': 'Graphics', 10:29:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:29:16 INFO - 'branch': 'Graphics', 10:29:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:29:16 INFO - 'config_files': ('talos/mac_config.py',), 10:29:16 INFO - 'default_actions': ('clobber', 10:29:16 INFO - 'read-buildbot-config', 10:29:16 INFO - 'download-and-extract', 10:29:16 INFO - 'populate-webroot', 10:29:16 INFO - 'create-virtualenv', 10:29:16 INFO - 'install', 10:29:16 INFO - 'run-tests'), 10:29:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:29:16 INFO - 'download_minidump_stackwalk': True, 10:29:16 INFO - 'download_symbols': 'ondemand', 10:29:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:29:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:29:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:29:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:29:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:29:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:29:16 INFO - 'installer_path': 'installer.exe', 10:29:16 INFO - 'log_level': 'info', 10:29:16 INFO - 'log_name': 'talos', 10:29:16 INFO - 'log_to_console': True, 10:29:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:29:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:29:16 INFO - 'opt_config_files': (), 10:29:16 INFO - 'pip_index': False, 10:29:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:29:16 INFO - 'cmd': ('bash', 10:29:16 INFO - '-c', 10:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:29:16 INFO - 'enabled': True, 10:29:16 INFO - 'halt_on_failure': False, 10:29:16 INFO - 'name': 'check_screen_resolution'},), 10:29:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:29:16 INFO - 'cmd': ('bash', 10:29:16 INFO - '-c', 10:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:29:16 INFO - 'enabled': True, 10:29:16 INFO - 'halt_on_failure': False, 10:29:16 INFO - 'name': 'check_screen_resolution'},), 10:29:16 INFO - 'run_cmd_checks_enabled': True, 10:29:16 INFO - 'sps_profile': False, 10:29:16 INFO - 'sps_profile_interval': 0, 10:29:16 INFO - 'suite': 'dromaeojs-e10s', 10:29:16 INFO - 'system_bits': '32', 10:29:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:29:16 INFO - 'title': 't-yosemite-r7-0006', 10:29:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:29:16 INFO - 'use_talos_json': True, 10:29:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:29:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:29:16 INFO - 'work_dir': 'build'} 10:29:16 INFO - [mozharness: 2016-12-14 18:29:16.902396Z] Running clobber step. 10:29:16 INFO - Running pre-action listener: _resource_record_pre_action 10:29:16 INFO - Running main action method: clobber 10:29:16 INFO - rmtree: /builds/slave/test/build 10:29:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:29:18 INFO - Running post-action listener: _resource_record_post_action 10:29:18 INFO - [mozharness: 2016-12-14 18:29:18.544882Z] Finished clobber step (success) 10:29:18 INFO - [mozharness: 2016-12-14 18:29:18.545005Z] Running read-buildbot-config step. 10:29:18 INFO - Running pre-action listener: _resource_record_pre_action 10:29:18 INFO - Running main action method: read_buildbot_config 10:29:18 INFO - Using buildbot properties: 10:29:18 INFO - { 10:29:18 INFO - "project": "", 10:29:18 INFO - "product": "firefox", 10:29:18 INFO - "script_repo_revision": "default", 10:29:18 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 10:29:18 INFO - "repository": "", 10:29:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 10:29:18 INFO - "buildid": "20161214073201", 10:29:18 INFO - "pgo_build": "False", 10:29:18 INFO - "basedir": "/builds/slave/test", 10:29:18 INFO - "buildnumber": 2, 10:29:18 INFO - "slavename": "t-yosemite-r7-0006", 10:29:18 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:29:18 INFO - "platform": "yosemite_r7", 10:29:18 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 10:29:18 INFO - "branch": "graphics", 10:29:18 INFO - "repo_path": "projects/graphics", 10:29:18 INFO - "revision": "7604973adccb02cd3023259404aee502ff9da87f", 10:29:18 INFO - "stage_platform": "macosx64", 10:29:18 INFO - "builduid": "5085b9116316411081b9c22c1d054fba", 10:29:18 INFO - "slavebuilddir": "test" 10:29:18 INFO - } 10:29:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 10:29:18 INFO - Running post-action listener: _resource_record_post_action 10:29:18 INFO - [mozharness: 2016-12-14 18:29:18.548311Z] Finished read-buildbot-config step (success) 10:29:18 INFO - [mozharness: 2016-12-14 18:29:18.548458Z] Running download-and-extract step. 10:29:18 INFO - Running pre-action listener: _resource_record_pre_action 10:29:18 INFO - Running main action method: download_and_extract 10:29:18 INFO - mkdir: /builds/slave/test/build/tests 10:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:18 INFO - trying https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 10:29:18 INFO - Downloading https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/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 10:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/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 10:29:20 INFO - Downloaded 1199 bytes. 10:29:20 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:29:20 INFO - Using the following test package requirements: 10:29:20 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:29:20 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 10:29:20 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 10:29:20 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'jsshell-mac.zip'], 10:29:20 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 10:29:20 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:29:20 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 10:29:20 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 10:29:20 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 10:29:20 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:29:20 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 10:29:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:29:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:29:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:29:20 INFO - Fetch https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:29:21 INFO - Expected file size: 16276108 10:29:21 INFO - Obtained file size: 16276108 10:29:27 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 10:29:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:29:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:29:27 INFO - Fetch https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:29:28 INFO - Expected file size: 16276108 10:29:28 INFO - Obtained file size: 16276108 10:29:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 10:29:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:29:34 INFO - Fetch https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 10:29:35 INFO - Expected file size: 14409734 10:29:35 INFO - Obtained file size: 14409734 10:29: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')]} 10:29:35 INFO - trying https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:29:35 INFO - Downloading https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 10:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/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 10:29:37 INFO - Downloaded 69760668 bytes. 10:29:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:29:37 INFO - mkdir: /builds/slave/test/properties 10:29:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:37 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:37 INFO - Contents: 10:29:37 INFO - build_url:https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:29:38 INFO - Running post-action listener: _resource_record_post_action 10:29:38 INFO - Running post-action listener: set_extra_try_arguments 10:29:38 INFO - [mozharness: 2016-12-14 18:29:38.590312Z] Finished download-and-extract step (success) 10:29:38 INFO - [mozharness: 2016-12-14 18:29:38.590413Z] Running populate-webroot step. 10:29:38 INFO - Running pre-action listener: _resource_record_pre_action 10:29:38 INFO - Running main action method: populate_webroot 10:29:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:38 INFO - '--tppagecycles', 10:29:38 INFO - '7'], 10:29:38 INFO - 'tests': ['tsvgm']}}, 10:29:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['damp', 'tps']}, 10:29:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'tests': ['damp', 'tps']}, 10:29:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['dromaeo_dom']}, 10:29:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['basic_compositor_video']}, 10:29:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:29:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['a11yr', 10:29:38 INFO - 'ts_paint', 10:29:38 INFO - 'tpaint', 10:29:38 INFO - 'sessionrestore', 10:29:38 INFO - 'sessionrestore_no_auto_restore', 10:29:38 INFO - 'tabpaint']}, 10:29:38 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:38 INFO - 'ts_paint', 10:29:38 INFO - 'tpaint', 10:29:38 INFO - 'sessionrestore', 10:29:38 INFO - 'sessionrestore_no_auto_restore', 10:29:38 INFO - 'tabpaint']}, 10:29:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:38 INFO - 'ts_paint', 10:29:38 INFO - 'tpaint', 10:29:38 INFO - 'sessionrestore', 10:29:38 INFO - 'sessionrestore_no_auto_restore', 10:29:38 INFO - 'tabpaint']}, 10:29:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:38 INFO - 'ts_paint', 10:29:38 INFO - 'tpaint', 10:29:38 INFO - 'sessionrestore', 10:29:38 INFO - 'sessionrestore_no_auto_restore', 10:29:38 INFO - 'tabpaint']}, 10:29:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['a11yr', 10:29:38 INFO - 'ts_paint', 10:29:38 INFO - 'tpaint', 10:29:38 INFO - 'sessionrestore', 10:29:38 INFO - 'sessionrestore_no_auto_restore', 10:29:38 INFO - 'tabpaint']}, 10:29:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['a11yr', 10:29:38 INFO - 'ts_paint', 10:29:38 INFO - 'tpaint', 10:29:38 INFO - 'sessionrestore', 10:29:38 INFO - 'sessionrestore_no_auto_restore', 10:29:38 INFO - 'tabpaint']}, 10:29:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['tsvgx', 10:29:38 INFO - 'tsvgr_opacity', 10:29:38 INFO - 'tart', 10:29:38 INFO - 'tscrollx', 10:29:38 INFO - 'cart', 10:29:38 INFO - 'tsvg_static']}, 10:29:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:38 INFO - 'tsvgr_opacity', 10:29:38 INFO - 'tart', 10:29:38 INFO - 'tscrollx', 10:29:38 INFO - 'cart', 10:29:38 INFO - 'tsvg_static']}, 10:29:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'talos_options': ['--disable-e10s'], 10:29:38 INFO - 'tests': ['tp5o']}, 10:29:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:29:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'talos_options': ['--disable-e10s', 10:29:38 INFO - '--xperf_path', 10:29:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:38 INFO - 'tests': ['tp5n']}, 10:29:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:29:38 INFO - 'talos_options': ['--xperf_path', 10:29:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:38 INFO - 'tests': ['tp5n']}}, 10:29:38 INFO - 'talos.zip': {'path': '', 10:29:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:38 INFO - Running post-action listener: _resource_record_post_action 10:29:38 INFO - [mozharness: 2016-12-14 18:29:38.608750Z] Finished populate-webroot step (success) 10:29:38 INFO - [mozharness: 2016-12-14 18:29:38.608880Z] Running create-virtualenv step. 10:29:38 INFO - Running pre-action listener: _resource_record_pre_action 10:29:38 INFO - Running main action method: create_virtualenv 10:29:38 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:29:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:38 INFO - Using real prefix '/tools/python27' 10:29:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:39 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:42 INFO - Installing pip.................done. 10:29:42 INFO - Return code: 0 10:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:29:42 INFO - Reading from file tmpfile_stdout 10:29:42 INFO - Output received: 10:29:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:29:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:42 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:42 INFO - 'HOME': '/Users/cltbld', 10:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:42 INFO - 'LOGNAME': 'cltbld', 10:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:42 INFO - 'MOZ_NO_REMOTE': '1', 10:29:42 INFO - 'NO_EM_RESTART': '1', 10:29:42 INFO - 'PAGER': '/bin/cat', 10:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:42 INFO - 'PWD': '/builds/slave/test', 10:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:42 INFO - 'SHELL': '/bin/bash', 10:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:42 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:42 INFO - 'USER': 'cltbld', 10:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:42 INFO - 'XPC_FLAGS': '0x0', 10:29:42 INFO - 'XPC_SERVICE_NAME': '0', 10:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:29:42 INFO - Cleaning up... 10:29:42 INFO - Return code: 0 10:29:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:42 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:42 INFO - 'HOME': '/Users/cltbld', 10:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:42 INFO - 'LOGNAME': 'cltbld', 10:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:42 INFO - 'MOZ_NO_REMOTE': '1', 10:29:42 INFO - 'NO_EM_RESTART': '1', 10:29:42 INFO - 'PAGER': '/bin/cat', 10:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:42 INFO - 'PWD': '/builds/slave/test', 10:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:42 INFO - 'SHELL': '/bin/bash', 10:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:42 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:42 INFO - 'USER': 'cltbld', 10:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:42 INFO - 'XPC_FLAGS': '0x0', 10:29:42 INFO - 'XPC_SERVICE_NAME': '0', 10:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:43 INFO - Downloading/unpacking psutil>=3.1.1 10:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:29:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:46 INFO - Installing collected packages: psutil 10:29:46 INFO - Running setup.py install for psutil 10:29:46 INFO - building 'psutil._psutil_osx' extension 10:29:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:29:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:29:47 INFO - building 'psutil._psutil_posix' extension 10:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:29:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:29:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:29:47 INFO - ^ 10:29:47 INFO - 1 warning generated. 10:29:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:29:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:47 INFO - Successfully installed psutil 10:29:47 INFO - Cleaning up... 10:29:47 INFO - Return code: 0 10:29:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:47 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:29:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:47 INFO - 'HOME': '/Users/cltbld', 10:29:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:47 INFO - 'LOGNAME': 'cltbld', 10:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:47 INFO - 'MOZ_NO_REMOTE': '1', 10:29:47 INFO - 'NO_EM_RESTART': '1', 10:29:47 INFO - 'PAGER': '/bin/cat', 10:29:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:47 INFO - 'PWD': '/builds/slave/test', 10:29:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:47 INFO - 'SHELL': '/bin/bash', 10:29:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:47 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:47 INFO - 'USER': 'cltbld', 10:29:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:47 INFO - 'XPC_FLAGS': '0x0', 10:29:47 INFO - 'XPC_SERVICE_NAME': '0', 10:29:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:29:50 INFO - Installing collected packages: mozsystemmonitor 10:29:50 INFO - Running setup.py install for mozsystemmonitor 10:29:50 INFO - Successfully installed mozsystemmonitor 10:29:50 INFO - Cleaning up... 10:29:50 INFO - Return code: 0 10:29:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:50 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:50 INFO - 'HOME': '/Users/cltbld', 10:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:50 INFO - 'LOGNAME': 'cltbld', 10:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:50 INFO - 'MOZ_NO_REMOTE': '1', 10:29:50 INFO - 'NO_EM_RESTART': '1', 10:29:50 INFO - 'PAGER': '/bin/cat', 10:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:50 INFO - 'PWD': '/builds/slave/test', 10:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:50 INFO - 'SHELL': '/bin/bash', 10:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:50 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:50 INFO - 'USER': 'cltbld', 10:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:50 INFO - 'XPC_FLAGS': '0x0', 10:29:50 INFO - 'XPC_SERVICE_NAME': '0', 10:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:50 INFO - Downloading/unpacking jsonschema==2.5.1 10:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:29:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:53 INFO - Downloading functools32-3.2.3-2.tar.gz 10:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:29:54 INFO - warning: no files found matching '*.txt' 10:29:54 INFO - no previously-included directories found matching 'build' 10:29:54 INFO - no previously-included directories found matching 'dist' 10:29:54 INFO - no previously-included directories found matching '.git*' 10:29:54 INFO - Installing collected packages: jsonschema, functools32 10:29:54 INFO - Running setup.py install for functools32 10:29:54 INFO - warning: no files found matching '*.txt' 10:29:54 INFO - no previously-included directories found matching 'build' 10:29:54 INFO - no previously-included directories found matching 'dist' 10:29:54 INFO - no previously-included directories found matching '.git*' 10:29:54 INFO - Successfully installed jsonschema functools32 10:29:54 INFO - Cleaning up... 10:29:54 INFO - Return code: 0 10:29:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:54 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:54 INFO - 'HOME': '/Users/cltbld', 10:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:54 INFO - 'LOGNAME': 'cltbld', 10:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:54 INFO - 'MOZ_NO_REMOTE': '1', 10:29:54 INFO - 'NO_EM_RESTART': '1', 10:29:54 INFO - 'PAGER': '/bin/cat', 10:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:54 INFO - 'PWD': '/builds/slave/test', 10:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:54 INFO - 'SHELL': '/bin/bash', 10:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:54 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:54 INFO - 'USER': 'cltbld', 10:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:54 INFO - 'XPC_FLAGS': '0x0', 10:29:54 INFO - 'XPC_SERVICE_NAME': '0', 10:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:29:54 INFO - Cleaning up... 10:29:54 INFO - Return code: 0 10:29:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:54 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:54 INFO - 'HOME': '/Users/cltbld', 10:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:54 INFO - 'LOGNAME': 'cltbld', 10:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:54 INFO - 'MOZ_NO_REMOTE': '1', 10:29:54 INFO - 'NO_EM_RESTART': '1', 10:29:54 INFO - 'PAGER': '/bin/cat', 10:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:54 INFO - 'PWD': '/builds/slave/test', 10:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:54 INFO - 'SHELL': '/bin/bash', 10:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:54 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:54 INFO - 'USER': 'cltbld', 10:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:54 INFO - 'XPC_FLAGS': '0x0', 10:29:54 INFO - 'XPC_SERVICE_NAME': '0', 10:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:54 INFO - Downloading/unpacking blobuploader==1.2.4 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:57 INFO - Downloading blobuploader-1.2.4.tar.gz 10:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:29:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:58 INFO - Downloading docopt-0.6.1.tar.gz 10:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:29:58 INFO - Installing collected packages: blobuploader, requests, docopt 10:29:58 INFO - Running setup.py install for blobuploader 10:29:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:29:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:29:58 INFO - Running setup.py install for requests 10:29:59 INFO - Running setup.py install for docopt 10:29:59 INFO - Successfully installed blobuploader requests docopt 10:29:59 INFO - Cleaning up... 10:29:59 INFO - Return code: 0 10:29:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:29:59 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:59 INFO - 'HOME': '/Users/cltbld', 10:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:59 INFO - 'LOGNAME': 'cltbld', 10:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:59 INFO - 'MOZ_NO_REMOTE': '1', 10:29:59 INFO - 'NO_EM_RESTART': '1', 10:29:59 INFO - 'PAGER': '/bin/cat', 10:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:59 INFO - 'PWD': '/builds/slave/test', 10:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:59 INFO - 'SHELL': '/bin/bash', 10:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:29:59 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:59 INFO - 'USER': 'cltbld', 10:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:59 INFO - 'XPC_FLAGS': '0x0', 10:29:59 INFO - 'XPC_SERVICE_NAME': '0', 10:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:59 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-NY6pTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:59 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-aPaBX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-HZp8wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-HnQifK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TbDRoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-GZaS8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-byOBqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-vAPszu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-oKSfrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lbQHpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9ngnkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:00 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9e2ugm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:01 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-XNa1Vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:01 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-2Xlk_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:01 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-mLKaDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:01 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-1N5kFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:01 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-01al81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:30:01 INFO - Running setup.py install for manifestparser 10:30:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:30:01 INFO - Running setup.py install for mozcrash 10:30:01 INFO - Running setup.py install for mozdebug 10:30:02 INFO - Running setup.py install for mozdevice 10:30:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozfile 10:30:02 INFO - Running setup.py install for mozhttpd 10:30:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozinfo 10:30:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozInstall 10:30:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:30:02 INFO - Running setup.py install for mozleak 10:30:02 INFO - Running setup.py install for mozlog 10:30:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for moznetwork 10:30:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for mozprocess 10:30:03 INFO - Running setup.py install for mozprofile 10:30:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for mozrunner 10:30:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:30:03 INFO - Running setup.py install for mozscreenshot 10:30:03 INFO - Running setup.py install for moztest 10:30:04 INFO - Running setup.py install for mozversion 10:30:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:30:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:30:04 INFO - Cleaning up... 10:30:04 INFO - Return code: 0 10:30:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:04 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:04 INFO - 'HOME': '/Users/cltbld', 10:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:04 INFO - 'LOGNAME': 'cltbld', 10:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:04 INFO - 'MOZ_NO_REMOTE': '1', 10:30:04 INFO - 'NO_EM_RESTART': '1', 10:30:04 INFO - 'PAGER': '/bin/cat', 10:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:04 INFO - 'PWD': '/builds/slave/test', 10:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:04 INFO - 'SHELL': '/bin/bash', 10:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:30:04 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:04 INFO - 'USER': 'cltbld', 10:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:04 INFO - 'XPC_FLAGS': '0x0', 10:30:04 INFO - 'XPC_SERVICE_NAME': '0', 10:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:04 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-KBekqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:04 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-_v1t42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:04 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Vc0k2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:04 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5IVcKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:04 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-K3l4G9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Kmb9y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-CIn9NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-xc_15A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0RLRxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-k8qlS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ObROjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-oDKuNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:05 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)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-W9i6FM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:05 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-2tstdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:06 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-4MxdW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:06 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-M5AWZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:06 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)) 10:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:06 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-UFw1iZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:08 INFO - Downloading blessings-1.6.tar.gz 10:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:30:09 INFO - Installing collected packages: blessings 10:30:09 INFO - Running setup.py install for blessings 10:30:09 INFO - Successfully installed blessings 10:30:09 INFO - Cleaning up... 10:30:09 INFO - Return code: 0 10:30:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:09 INFO - Reading from file tmpfile_stdout 10:30:09 INFO - Current package versions: 10:30:09 INFO - blessings == 1.6 10:30:09 INFO - blobuploader == 1.2.4 10:30:09 INFO - docopt == 0.6.1 10:30:09 INFO - functools32 == 3.2.3-2 10:30:09 INFO - jsonschema == 2.5.1 10:30:09 INFO - manifestparser == 1.1 10:30:09 INFO - mozInstall == 1.12 10:30:09 INFO - mozcrash == 1.0 10:30:09 INFO - mozdebug == 0.1 10:30:09 INFO - mozdevice == 0.49 10:30:09 INFO - mozfile == 1.2 10:30:09 INFO - mozhttpd == 0.7 10:30:09 INFO - mozinfo == 0.9 10:30:09 INFO - mozleak == 0.1 10:30:09 INFO - mozlog == 3.4 10:30:09 INFO - moznetwork == 0.27 10:30:09 INFO - mozprocess == 0.23 10:30:09 INFO - mozprofile == 0.28 10:30:09 INFO - mozrunner == 6.13 10:30:09 INFO - mozscreenshot == 0.1 10:30:09 INFO - mozsystemmonitor == 0.3 10:30:09 INFO - moztest == 0.7 10:30:09 INFO - mozversion == 1.4 10:30:09 INFO - psutil == 3.1.1 10:30:09 INFO - requests == 1.2.3 10:30:09 INFO - wsgiref == 0.1.2 10:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:09 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 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:09 INFO - 'HOME': '/Users/cltbld', 10:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:09 INFO - 'LOGNAME': 'cltbld', 10:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:09 INFO - 'MOZ_NO_REMOTE': '1', 10:30:09 INFO - 'NO_EM_RESTART': '1', 10:30:09 INFO - 'PAGER': '/bin/cat', 10:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:09 INFO - 'PWD': '/builds/slave/test', 10:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:09 INFO - 'SHELL': '/bin/bash', 10:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:30:09 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:09 INFO - 'USER': 'cltbld', 10:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:09 INFO - 'XPC_FLAGS': '0x0', 10:30:09 INFO - 'XPC_SERVICE_NAME': '0', 10:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:30:09 INFO - Cleaning up... 10:30:09 INFO - Return code: 0 10:30:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e14af7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, '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.MujjCp9wSb/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.VoQtaJh3fo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:09 INFO - 'HOME': '/Users/cltbld', 10:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:09 INFO - 'LOGNAME': 'cltbld', 10:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:09 INFO - 'MOZ_NO_REMOTE': '1', 10:30:09 INFO - 'NO_EM_RESTART': '1', 10:30:09 INFO - 'PAGER': '/bin/cat', 10:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:09 INFO - 'PWD': '/builds/slave/test', 10:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:09 INFO - 'SHELL': '/bin/bash', 10:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:30:09 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:09 INFO - 'USER': 'cltbld', 10:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:09 INFO - 'XPC_FLAGS': '0x0', 10:30:09 INFO - 'XPC_SERVICE_NAME': '0', 10:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:30:10 INFO - Cleaning up... 10:30:10 INFO - Return code: 0 10:30:10 INFO - Running post-action listener: _resource_record_post_action 10:30:10 INFO - Running post-action listener: _start_resource_monitoring 10:30:10 INFO - Starting resource monitoring. 10:30:10 INFO - [mozharness: 2016-12-14 18:30:10.105722Z] Finished create-virtualenv step (success) 10:30:10 INFO - [mozharness: 2016-12-14 18:30:10.106582Z] Running install step. 10:30:10 INFO - Running pre-action listener: _resource_record_pre_action 10:30:10 INFO - Running main action method: install 10:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:10 INFO - Reading from file tmpfile_stdout 10:30:10 INFO - Detecting whether we're running mozinstall >=1.0... 10:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:30:10 INFO - Reading from file tmpfile_stdout 10:30:10 INFO - Output received: 10:30:10 INFO - Usage: mozinstall [options] installer 10:30:10 INFO - Options: 10:30:10 INFO - -h, --help show this help message and exit 10:30:10 INFO - -d DEST, --destination=DEST 10:30:10 INFO - Directory to install application into. [default: 10:30:10 INFO - "/builds/slave/test"] 10:30:10 INFO - --app=APP Application being installed. [default: firefox] 10:30:10 INFO - mkdir: /builds/slave/test/build/application 10:30:10 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'] 10:30:10 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 10:30:32 INFO - Reading from file tmpfile_stdout 10:30:32 INFO - Output received: 10:30:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:30:32 INFO - Running post-action listener: _resource_record_post_action 10:30:32 INFO - [mozharness: 2016-12-14 18:30:32.086847Z] Finished install step (success) 10:30:32 INFO - [mozharness: 2016-12-14 18:30:32.087065Z] Running run-tests step. 10:30:32 INFO - Running pre-action listener: _resource_record_pre_action 10:30:32 INFO - Running main action method: run_tests 10:30:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:32 INFO - 2016-12-14 10:30:32.101 screenresolution[2467:32822] starting screenresolution argv=screenresolution get 10:30:32 INFO - 2016-12-14 10:30:32.104 screenresolution[2467:32822] Display 0: 1600x1200x32@60 10:30:32 INFO - 2016-12-14 10:30:32.110 screenresolution[2468:32824] starting screenresolution argv=screenresolution list 10:30:32 INFO - Available Modes on Display 0 10:30:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:32 INFO - Intel Iris: 10:30:32 INFO - Chipset Model: Intel Iris 10:30:32 INFO - Type: GPU 10:30:32 INFO - Bus: Built-In 10:30:32 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:32 INFO - Vendor: Intel (0x8086) 10:30:32 INFO - Device ID: 0x0a2e 10:30:32 INFO - Revision ID: 0x0009 10:30:32 INFO - Displays: 10:30:32 INFO - Display: 10:30:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:32 INFO - Display Serial Number: 1600x1200 60 10:30:32 INFO - Main Display: Yes 10:30:32 INFO - Mirror: Off 10:30:32 INFO - Online: Yes 10:30:32 INFO - Rotation: Supported 10:30:32 INFO - Return code: 0 10:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:30:32 INFO - Python 2.7.3 10:30:32 INFO - Return code: 0 10:30:32 INFO - grabbing minidump binary from tooltool 10:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:32 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 0x10639ed30>, '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 0x106205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106396b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:30:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:30:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:30:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:30:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:30:32 INFO - Return code: 0 10:30:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:30:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:30:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:30:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:30:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:30:32 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/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 10:30:32 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/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --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 10:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MujjCp9wSb/Render', 10:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:32 INFO - 'HOME': '/Users/cltbld', 10:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:32 INFO - 'LOGNAME': 'cltbld', 10:30:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:30:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:32 INFO - 'MOZ_NO_REMOTE': '1', 10:30:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:30:32 INFO - 'NO_EM_RESTART': '1', 10:30:32 INFO - 'PAGER': '/bin/cat', 10:30:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:32 INFO - 'PWD': '/builds/slave/test', 10:30:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:32 INFO - 'SHELL': '/bin/bash', 10:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VoQtaJh3fo/Listeners', 10:30:32 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 10:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:32 INFO - 'USER': 'cltbld', 10:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:32 INFO - 'XPC_FLAGS': '0x0', 10:30:32 INFO - 'XPC_SERVICE_NAME': '0', 10:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:32 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/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 10:30:32 INFO - mozversion application_buildid: 20161214073201 10:30:32 INFO - mozversion application_changeset: 7604973adccb02cd3023259404aee502ff9da87f 10:30:32 INFO - mozversion application_display_name: Nightly 10:30:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:30:32 INFO - mozversion application_name: Firefox 10:30:32 INFO - mozversion application_remotingname: firefox 10:30:32 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 10:30:32 INFO - mozversion application_vendor: Mozilla 10:30:32 INFO - mozversion application_version: 53.0a1 10:30:32 INFO - mozversion platform_buildid: 20161214073201 10:30:32 INFO - mozversion platform_changeset: 7604973adccb02cd3023259404aee502ff9da87f 10:30:32 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 10:30:32 INFO - mozversion platform_version: 53.0a1 10:30:32 INFO - using testdate: 1481740232 10:30:32 INFO - actual date: 1481740232 10:30:32 INFO - starting webserver on 'localhost:62141' 10:30:32 INFO - SUITE-START | Running 2 tests 10:30:32 INFO - TEST-START | dromaeo_css 10:30:32 INFO - operating with platform_type : mac_ 10:30:32 INFO - Initialising browser for dromaeo_css test... 10:30:32 INFO - TEST-INFO | started process 2478 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpQEk3Bo/profile http://localhost:62141/getInfo.html) 10:30:35 INFO - PROCESS | 2478 | __metrics Screen width/height:1600/1200 10:30:35 INFO - PROCESS | 2478 | colorDepth:24 10:30:35 INFO - PROCESS | 2478 | Browser inner width/height: 1024/689 10:30:35 INFO - PROCESS | 2478 | __metrics 10:30:35 INFO - PROCESS | 2478 | 2016-12-14 10:30:35.736 plugin-container[2480:32975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 10:30:35 INFO - PROCESS | 2478 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:35 INFO - PROCESS | 2478 | 2016-12-14 10:30:35.739 plugin-container[2480:32975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x901f, name = 'com.apple.CFPasteboardClient' 10:30:35 INFO - PROCESS | 2478 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:35 INFO - PROCESS | 2478 | 2016-12-14 10:30:35.739 plugin-container[2480:32975] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:30:35 ERROR - PROCESS | 2478 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 10:30:36 INFO - TEST-INFO | 2478: exit 0 10:30:36 INFO - Browser initialized. 10:30:36 INFO - Running cycle 1/1 for dromaeo_css test... 10:30:36 INFO - TEST-INFO | started process 2481 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpQEk3Bo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:38 INFO - PROCESS | 2481 | 2016-12-14 10:30:38.880 plugin-container[2482:33097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 10:30:38 INFO - PROCESS | 2481 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:38 INFO - PROCESS | 2481 | 2016-12-14 10:30:38.880 plugin-container[2482:33097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 10:30:38 INFO - PROCESS | 2481 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:30:38 INFO - PROCESS | 2481 | 2016-12-14 10:30:38.880 plugin-container[2482:33097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:30:48 INFO - PROCESS | 2481 | RSS: Main: 237256704 10:30:48 INFO - PROCESS | 2481 | 10:31:38 INFO - PROCESS | 2481 | 1481740298246 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] 10:33:50 INFO - PROCESS | 2481 | Cycle 1(1): loaded http://localhost:62141/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62141/tests/dromaeo/cssquery-ext.html) 10:33:53 INFO - PROCESS | 2481 | RSS: Main: 253476864 10:33:53 INFO - PROCESS | 2481 | 10:36:55 INFO - PROCESS | 2481 | Cycle 1(1): loaded http://localhost:62141/tests/dromaeo/cssquery-ext.html (next: http://localhost:62141/tests/dromaeo/cssquery-jquery.html) 10:36:56 INFO - PROCESS | 2481 | RSS: Main: 264511488 10:36:56 INFO - PROCESS | 2481 | 10:39:58 INFO - PROCESS | 2481 | Cycle 1(1): loaded http://localhost:62141/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62141/tests/dromaeo/cssquery-mootools.html) 10:39:59 INFO - PROCESS | 2481 | RSS: Main: 269778944 10:39:59 INFO - PROCESS | 2481 | 10:43:01 INFO - PROCESS | 2481 | Cycle 1(1): loaded http://localhost:62141/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62141/tests/dromaeo/cssquery-prototype.html) 10:43:01 INFO - PROCESS | 2481 | RSS: Main: 268505088 10:43:01 INFO - PROCESS | 2481 | 10:46:04 INFO - PROCESS | 2481 | Cycle 1(1): loaded http://localhost:62141/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62141/tests/dromaeo/cssquery-yui.html) 10:46:05 INFO - PROCESS | 2481 | RSS: Main: 273195008 10:46:05 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | Cycle 1(1): loaded http://localhost:62141/tests/dromaeo/cssquery-yui.html (next: http://localhost:62141/tests/dromaeo/cssquery-dojo.html) 10:49:08 INFO - PROCESS | 2481 | RSS: Main: 275611648 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | __start_tp_report 10:49:08 INFO - PROCESS | 2481 | _x_x_mozilla_page_load 10:49:08 INFO - PROCESS | 2481 | _x_x_mozilla_page_load_details 10:49:08 INFO - PROCESS | 2481 | |i|pagename|runs| 10:49:08 INFO - PROCESS | 2481 | |0;dojo.html;3304.8347582620872;3339.833008349582;3357.479590663447;3390.728741700664;3393.9830300848507;5049.586115887548;5239.187925871496;5697.572682048843;5704.115862041385;5795.971020144899;5648.265725455695;5692.5161361284345;5741.598088133832;5749.223854779603;5793.797217097403;5225.372955245357;5258.211268309746;5274.894502109955;5289.841304760864;5294.814681486148;4010.598940105995;4412.24991751403;5278.762455689484;5287.973560132214;5327.041132596131;2839.2901774556362;4088.7342322749073;4492.348609451627;5090.516400941923;5397.865053373658;4139.81370838313;4422.668299877522;5161.5870730341485;5163.586913046947;5228.189630607262;4183.707140500166;4710.670253082286;4731.574158325733;5226.425093239741;5296.311479507639;5514.944850551483;5702;5737.540996720252;5743.913841292384;5794.130880367937;6119.563485741598;6177;6211.192544969125;6220.751169953196;6246.219222597176;6164.907526387063;6178.474829639486;6229.875402491924;6244.594101383395;6248.4064013918605;5920.911186332209;5924.874273887947;5938.346781853993;5941.168236446858;5942.168096466498;6306.2747784004505;6308.684565771693;6313.147725057151;6337.081123237105;6369.681515924278;5967.254093238345;6112.74665817949;6112.908306375408;6120.873812997669;6135.9079613805825;5637.26715526987;5815.302163740378;6159.4456498914415;6159.4456498915315;6236.220472440945;6178.104174894615;6179.073139029181;6206.751729930843;6214.533909956771;6215.81352559424;6111.7436766921655;6119.082137679384;6130.489860652556;6155.84610384744;6174.289956655042;4942.4316203637045;4957.3059771632;4965.205567109245;4967.40391153064;4988.551030407209;4437.0460351024685;4448.822047118144;4451.221036318631;4473.418455600751;4495.325701144789;5718.714064296769;5741.540676745851;5779.393163717833;5931.13998470228;5933.139694744238;6150.323464418911;6156.4151405617295;6177.752889884354;6185.659788711664;6215.34738852423;5934.317553481319;5946.167536544887;5962.821115366546;5972.522198224132;5992.550558708114;5421.593380496479;5445.128779395278;5460.235567020621;5473.972630136824;5487.725613719298;6189.938100619026;6250.49996000328;6262.561620686691;6264.968675156595;6322.146510221061;6183.876322473615;6190.133381326528;6203.286622038343;6203.689815509297;6226.066090086342;6241.937580624317;6345.650989195638;6361.427471527585;6380.947143721234;6385.29761726219;6117.188044311166;6117.541184411098;6140.87237156979;6180.690965451619;6198.380161983766;5005.749712514433;6130.673316708229;6187.566870319034;6207.255129384503;6226.657533835501;6043.09353596964;6115.908261375994;6147.065992170631;6152.077188421594;6176.444120029039;6101.572889897665;6126.559057237658;6134.134531820433;6158.9221886170635;6165.969170154121;4774.832880849153;4791.520847915181;5481.205225242397;5818.010938140442;6036.84907877289;4918.581920536794;5145.305383773292;6139.216166136355;6142.928535732134;6200.720967556561;5900.43945825146;5979.701014949148;5993.730282137401;6039.758409663565;6054.39456054391 10:49:08 INFO - PROCESS | 2481 | |1;ext.html;437.45536806675693;467.14978738695544;481.0307230929677;492.7930269286899;530.2391068816247;29863.954761583347;40815;40816.59183408165;40885.38671919923;41034.58965410345;41701;41714.957126071866;42192.57807421925;42260.57739422605;42458.36312455316;8486.915130848705;8681.565921703921;9202.309826762987;9470.857937130948;11090.500927458243;11230.15773816963;11498.770024599504;11503.252288601256;11503.42482875857;11555.480003399867;9903.55434005468;11261.774764504746;11493.195476316661;11531.365774882379;11548.653540393801;11353.20527563071;11380.203385763;11393.829092563617;11472.196946213768;11522.308661480254;11251.268667536666;11296.887031129665;11327.320360778298;11329.206955513117;11331.886681133248;40129.39805902914;40475.190496190204;40740.98147546335;40780.59219407797;40862.79568602168;3083.167544762924;3350.71518920892;3461.6365281645317;3870.3033453978273;3994.201159768058;24524;29906.542056074646;31046.84476577625;31120.688793112007;31169.532457013163;3168.2237851726245;3315.950260746091;3333.666633336647;3363.209645733251;3372.8819491317686;489627.99999999645;494843;494925;495897;497202.51398743136;63309.41726456876;63321.366786332466;63681.36318636847;63724;64296;64222.678886606205;64545;64663.353366466676;64691;64869;15990.040597564184;16179.110148941685;16189;16196.109213993108;16232.75650865238;11408.315501069963;11660.88339116598;12383.876161238271;12600.873991260154;12626.558070467674;14987.70024599502;17532.5616859577;18122.546936326435;18145.818541814413;18221.817781822276;5576.800987787645;6178.443940045418;6341.524385671001;6370.394812492806;6445.387688169616;736.7347754808264;850.3877208409934;996.1831298335295;1009.4599389326675;1031.6838697124854;26385.208443746717;29345.559816602767;30328.393432131674;30601.84699076535;30930.53604195939;845.6067928413318;992.8482959766636;1035.7100011996654;1133.4106264742288;1149.4540093455541;13453.327333633279;15662.843371566367;15775.605609859846;15810;16186.595335116715;603909;605665;607704;608506;608696;45162;60163.398366016656;60400.697996510615;60496.39503604908;61414.77170456566;1210.7578484303174;1337.9732405352033;1379.7792353223638;1404.3329418526118;1411.181514721459;29040;29989.55015674723;30545.389092217585;30624.846875765477;30857.22856928505;49141;53150.468495314555;53575.73212133914;53595;53671.73164134155;550884;551306;553359;554341;555230;36072;40937.795311023256;41427.7928610355;41943.1611367777;42051.78974105232;39996;46439;47755.76122119367;47894.76052619715;49594.752026239636;39545.60454395419;39969.80015099906;39984.600153998086;40037;41666.79166604148;39622.60377396189;39637;40294.58968936191;40841.79579102086;40887.38668919909;37888.24223515459;38379.2324153521;38639.6136038636;38849.2230155401;39060;32273.35453290968;33955.98132056042;34760.84094626425;35293.52945293805;35465.82267088648;25820.870895645403;25838.612420813326;26005.609915851655;26207.21378358652;26531.734682652925 10:49:08 INFO - PROCESS | 2481 | |2;jquery.html;371.5522795031985;376.76828750318555;382.70914105279985;387.84098519606965;404.17346526353623;17789.733154002683;20157.294494692687;20239.898800506013;20420.693689594667;20501.974901254933;19782.604347913035;19891.005449727527;19932.50168745782;20099.59800803983;20393.184272629078;5323.361196656388;5528.917066244009;5536.501714845663;5771.278590176228;5802.796902108427;5677;5785.334686511042;5785.681787501686;5785.98745219587;5807.680577568232;5530.751116199761;5671.262735844334;5732.971335143318;5802.709864506779;5872.647641141524;5842.795502157425;5861.648301101947;5872.823815285548;5897.174395584621;5900.734466948998;5507.614466987313;5695.7152142392715;5758.3665796762325;5766.365699773021;5776.537876969833;12844.743105137939;12845.486180552767;12887.67780805478;13003.349832508336;13047.86952130476;2036.378904434147;2253.5718213539376;2403.375122468153;2651.535981203301;2921.9269518262;9455.243580513543;9716.29964756165;9737.513124343825;10038.598456061749;10180.745481363025;1777.9644407111784;1783.545195975018;1816.5731053202487;1818.1454716283458;1857.4799056264267;97819.53270700945;107776.4611176947;115378;116508;116994;9643.758906027406;11212.383318917375;11322.433878306052;11355.148363872742;11588.420578971018;14292.213928234047;14384.640383990274;14393.92803035978;14402.783958240634;14428.495002674856;8001.959745233083;8327;8385.203405676451;8469.957650211833;8507.489550626982;8186.508809471451;8192.918070819216;8199.508029518249;8320.334373250125;8339.332853371716;3557.288542291552;3814.599467055975;4319.632831209319;4393.703857362086;4610.9308360374625;1629.9022058676517;1880.2009146112848;1943.6491638311238;1949.4054313434256;1949.8635095543175;827.3422629009951;855.6962278391206;890.3945317184248;955.6798472511647;959.9040095990345;19335.709964350546;20180.69728954067;20226.494337641678;20270.797292027186;20543.075561599762;909.7452713240303;938.7559234599054;944.4380593546966;944.631593678466;1013.8631284776608;8217.54803485814;10144.239182061376;10706.892931070746;10760.838587421194;10766.30019048775;565641;579555;582247.1775282193;588733;598672;114469;124171;124865;125899;127231.3638431802;993.2153598657125;997.9342760485724;1029.953652085658;1032.9690109296732;1037.2946396450486;13192.406341714837;17522.561935951195;18158.5460363492;18325.315096815088;18435.986020768985;35471;52706.73646631742;53493.46506534885;54023.189652156034;54120.72939635276;72310.27689723036;78453.2154678446;78716.21283787089;78918;80279.59860200662;7376.4467664926415;8002.879956800536;8035.870494318893;8246.422750407413;11717.004054655108;12675.492980281055;12854.293013884326;13360.131591446525;13466;13524.98562607828;11385.259958102697;11441.141914356624;11580.6525804226;11796.76406471883;11867.525298987945;11384;11571.768564628828;11571.884281157081;11622;11802.76394472123;11659.533618655498;11745.412729363668;11753.236039657395;11990;12043.09676774228;12155.635330940086;12290.139690221598;12392.617801832244;12401.93799030999;12509.687257818627;11615.6515304541;11743.295402275891;11866.228695134789;11888.524459021542;12031.518739250681 10:49:08 INFO - PROCESS | 2481 | |3;mootools.html;1309.8277042047366;1515.5907904865683;1699.6855581717384;1826.844718198953;1878.1172848761087;7525.096988361404;7805.882911756314;8010.839783204339;8470.534120623362;8478.406511544194;8033.959830200841;8325.500469971807;8331.12523185066;8411.285040771541;8422.91577084229;2299.367673889677;2326.2323433267065;2328.6972693549815;2334.743178250391;2338.187904374115;2329.1731435340466;2333.311673056444;2353.776391242829;2371.5256948610263;2375.6199008158696;2025.1696804310236;2276.781921671906;2323.546908352872;2327.1156960355124;2338.251759436981;2286.977130228693;2303.97696023041;2304.446932736148;2313.90744370225;2321.7330007049236;2319.153508969231;2322.280093171122;2346.1319311854554;2352.6118190498546;2359.7994170495526;8321.542315172663;8341.374396920195;8406.579671016425;8427.283680887133;8449.746507604781;4963.6277279204205;5048.217526283432;5108.208227724708;5112.565431938289;5121.923171152435;3310.7652334674;3405.676460736226;3501.8249087545773;3586.497890295361;3612.277544491086;1012.3166862367946;1036.8496567997597;1070.8500809886623;1095.3920574081383;1099.6371197504805;8827.77930551735;9431.104045115771;10055.743032120985;10805.729856753644;10876.129909607213;3826.330392181357;3867.5358956925347;4449.488308844459;4506.211413002711;4678.7660616969015;7700.576468294297;8755.77419161318;9285.257179425766;9338.626454941726;9361.34470587066;5179.766910489037;5231.712255825966;5290.947090529123;5294.047071527162;5313.415524292324;3744.5693745219105;4444.31113177458;4657.813687452464;4713.717176969393;4762.78567464465;3747.3254814133716;3813.84744610218;3846.0961674006585;3872.361060425055;3881.1073453105378;1274.2991354754847;1290.7353992431529;1290.8450985881755;1296.3129541343103;1300.2393599744255;786.5988345943496;888.8266787976412;900.6712549919363;906.496894223699;915.2494954137543;5551.694656793834;5607.5513958884;5619.241402410704;5765.1640512125505;5770.942290577124;426.51590444844703;441.59593971516347;441.8718571614196;442.1003258369203;905.1355955062899;3089.2894634233803;3657.762245454037;3674.540682414698;3778.9433158502643;3855.1711382052995;9395.718128456021;9937.205023598095;9952.850707239397;9965.40207587547;9972.30193886421;9878.160356369644;9902.30683852138;9927.801443971224;10069.993000700018;10081.19350451962;7830.2952734254195;7870.331021863206;7980.281774640311;7984.6406911687955;7986.203104301486;4263.722858014219;5482.479164479368;5579.944200558105;5620.662760234399;5628.577856660685;9206.861897071683;9208.447493150432;9281.303902207226;9296.349255552046;9424.764380890258;8969.730908072768;9312.72061838146;9379.812403752023;9386.108319709616;9416.529173541432;6334.303226645157;6535.3464653534265;6982.1272340957385;7078.575285482888;7227.891581626174;7238.384737297178;7275.854482910418;7930.286274235115;7984.762361833925;7997.920020799717;7058.188308344606;7194.676239569128;7199.280071992759;7244.492885497964;7317.853642927005;6228.844278893064;6928.411085057826;6986.510944233854;7021.894671579828;7035.929640703527;6423.4218920295525;6595.868082638224;6656.467482601477;6732.495062870206;6740.460763138839;4829.2997515359375;4854.126257273725;4878.146324393288;4918.360613120271;4940;4338.349247612883;4780.45024822136;4788.449328327287;4838.927416088691;4931.50684931504 10:49:08 INFO - PROCESS | 2481 | |4;prototype.html;330.64125423915056;364.4387643029734;368.81559220389806;372.5897478045597;385.918957019026;5016.39475367882;5360.383555891074;5454.627237151842;5566.912407847058;5582.8604284892845;4524.638028957684;5109.744512774356;5160.948390516094;5236.528712415882;5448.809291674792;3455.8444869980785;3673.797941113251;3736.4955730976376;4236.322188449849;4284.100338928829;3477.261369315342;3560.857565697369;3852.4012534798026;4283.91432171358;4316.6402583186355;4053.905445529705;4056.71602987791;4062.614051665087;4073.9388909166387;4074.97962510187;4056.3307054336;4057.736247143926;4061.593840615959;4064.857729979466;4083.509978802533;3520.6655367740022;3525.8413371398346;3535.8939231823088;3541.7874927504176;3561.7684850484893;5109.645943824889;5157.4584668610005;5240.694094598402;5242.461338596432;5343.492368225013;4126.319157339036;4600.516945720685;4638.420197475316;4640.67515273931;4645.281245938991;5682.232898558725;5705.6291341063115;5709.77160913563;5714.771409143629;5759.798407055733;3276.459384201604;3670.6512881276503;3860.227954409101;3895.7662540247675;3934.449177115206;6021.909671354933;6032.487238584726;6038;6051.606645568024;6086.300075491286;3619.9819000904827;3915.5497117831246;3935.5080614923136;3946.2896678598127;3962.9485296321673;3678.4666223397458;3849.8845034649007;3920.137569734654;3983.083890705154;4000.4799376081505;3881.5250204922513;4631.58315751584;4638.5593368629925;4649.279361698942;4655.650826187982;4937.851864444073;5291.894162116736;5601.683604352974;5629.943700562942;5807.593468457168;1175.2184797109826;1214.518287689018;1225.332193954297;1269.6127681057367;1283.4352884730874;840.9873851892227;842.3808500751942;873.5719497446206;877.9736607901773;882.4661080046608;4481.551844815493;5085.694858308513;5116.334876466036;5126.564561922668;5145.819896303611;4666.906661866744;5176.870578235575;5587.916181257285;5632.127020311858;5683.772649094072;4196.916061678749;4509.368688383629;4546.909061818745;4605.40129783126;5133.897322053613;2714.687810901732;2719.3745438549245;2769.9363973839218;2823.4870964841307;2939.720726531019;4902.950970490321;5449.591280653967;5462.289902312674;5551.528120109836;5775.306963164448;5514.972425137848;5784.681842498619;5824.912626310609;5836.951037352659;5840.737166827503;5064.468230835783;5638.083051762421;5757.309122905194;5850.548176575926;5894.38108998549;4618.930716039329;5011.822221777952;5064.567998641631;5437.782488700408;5606.579506536945;4651.441826980784;5282.577393808563;5898.498627616701;5914.497267732118;5917.201177840936;5310.274160897801;6192.349803270592;6246.656433896179;6258.968705156445;6273.874522509433;3805.4672345871045;3957.228340473695;4008.238434697398;4008.8396464141106;4024.75851448914;4501.977490112528;4887.413510378778;5470.890582188414;5926.140709597171;5975.34271230173;5108.48915108501;5121.385433747974;5126.6923984561045;5214.843554693365;5709.628874123168;5295.338082739658;5801.883962320646;5821.359650438364;5826.563007774519;5848.53211743051;5164.499807704782;5346.492083252085;5617.606767526234;5672.461116193955;5696.344920334049;5124.333836601218;5440.110321120418;5844.47399734026;5850.285068981142;5877.4122587740885;5002.574781143498;5660.745266462936;5676.318841739017;5682.5453963681975;5733.512651424676 10:49:08 INFO - PROCESS | 2481 | |5;yui.html;730.5872182217047;730.777112980541;784.5528049012062;814.2590242878981;836.7698882807225;15054.698906021873;15636.765448518281;15681.921590392047;15728.685426291468;15733.60665983351;14310.21293828839;14466.782998255008;14511.92744036281;14524.273786310669;14528.491502797404;697.2817997462622;739.6227923758895;866.9869951950726;887.0863011098579;912.2519533982137;906.2432868554764;916.1159481100736;924.5238702068407;924.6902287733584;925.4401087342162;945.4185675809362;979.9612410844492;980.5146452505984;980.7499087732618;980.8332583460829;876.023234093987;944.5418971798638;952.9850708994899;959.539421077883;961.5721004153155;716.1048689138577;725.9818504537376;728.854229154166;730.5470608222882;733.9944276341446;11755.882441175565;14495.637609059753;14634.487792927197;15013.69972600542;15240.771388429182;14058;14374.921880858978;14390;14416.423343066264;14474.710505789826;11353.262037967506;11450.484728187252;11452.312861228353;11525.711857203552;11843.467043982955;3686.4286035664513;3713.1459764254105;3737;3767.472553842464;3857.903552411184;109243.09636525348;112565;113059;113222.43388783168;115498;10396.584136634601;10529.473526323653;10552.366857988545;10740.677779666623;10783.81378048414;21304.893475532524;21585.892070539547;21640.701557906577;21659.458513537287;21678.891605542187;7182.174049984319;7692.73075442368;7712.421568382394;7786.805329866798;7869.842603147905;3629.2378600494194;4257.3613957905945;4331.198728235287;4382.780860956939;4386.8903277417685;3161.7944833585743;3221.838908054635;3447.9827600861836;3583.33708263972;3596.604373518911;561.0434209672499;611.6722505678746;658.1049772309655;665.2416245480157;671.9731210751613;558.6787597131665;563.2818156849967;625.1997443272528;657.6875983907605;672.6334147889412;7670.961645191738;8878.060907395899;9298.907010929805;9490.620375184915;9549.379290346105;608.7459832744561;676.4689718570996;681.7183694654118;696.1367903799253;709.0888208651858;3614.331348700499;4374.212641724456;4397.174458221368;4404.2512772828695;4773.403324584427;62417;80550;90212;105165.37086572744;106518;74704.0118395269;77168;79020;80815.7877631836;83399;67285.66357168183;67812.3218767806;69603;74568.62715686495;76379.61810190913;9629.32594718363;10042.535325312756;10077.395356278646;10109.747256318651;10121.240906931862;45747.31379029251;50743.46282685925;51281.743591281804;52908.73545632247;52944.73527632337;65951.01073483806;69256;71668;73110;73841;11771.411429428323;12637.620871373874;12856.100072994801;12916.8062479061;13444.260565668983;14470.131792092508;14695;14777.335019924345;14872.776908346166;15023.774643380139;13427.26150061756;13640.65898352549;13696.93151534236;13763.79354309666;13935.721285574435;13341.399637016164;13544.59366219015;13781.931090344484;13994.580162595139;14002.859971400156;12978.106040622648;13220.735585288048;13332.333383330988;13496.730065398833;13613.319334033456;10997.864444235123;11195.944020279847;11219.831702524632;11272.041876440595;11446.942765286454;7528.234609589014;9427.434353938786;10281.485925703835;11189.507595938887;11540.538378464767 10:49:08 INFO - PROCESS | 2481 | __end_tp_report 10:49:08 INFO - PROCESS | 2481 | __start_cc_report 10:49:08 INFO - PROCESS | 2481 | _x_x_mozilla_cycle_collect,389 10:49:08 INFO - PROCESS | 2481 | __end_cc_report 10:49:08 INFO - PROCESS | 2481 | __startTimestamp1481741348650__endTimestamp 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | ------- Summary: start ------- 10:49:08 INFO - PROCESS | 2481 | Number of tests: 6 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | [#0] dojo.html Cycles:165 Average:5655.68 Median:5962.82 stddev:740.12 (12.4%) stddev-sans-first:719.04 10:49:08 INFO - PROCESS | 2481 | Values: 3304.8 3339.8 3357.5 3390.7 3394.0 5049.6 5239.2 5697.6 5704.1 5796.0 5648.3 5692.5 5741.6 5749.2 5793.8 5225.4 5258.2 5274.9 5289.8 5294.8 4010.6 4412.2 5278.8 5288.0 5327.0 2839.3 4088.7 4492.3 5090.5 5397.9 4139.8 4422.7 5161.6 5163.6 5228.2 4183.7 4710.7 4731.6 5226.4 5296.3 5514.9 5702.0 5737.5 5743.9 5794.1 6119.6 6177.0 6211.2 6220.8 6246.2 6164.9 6178.5 6229.9 6244.6 6248.4 5920.9 5924.9 5938.3 5941.2 5942.2 6306.3 6308.7 6313.1 6337.1 6369.7 5967.3 6112.7 6112.9 6120.9 6135.9 5637.3 5815.3 6159.4 6159.4 6236.2 6178.1 6179.1 6206.8 6214.5 6215.8 6111.7 6119.1 6130.5 6155.8 6174.3 4942.4 4957.3 4965.2 4967.4 4988.6 4437.0 4448.8 4451.2 4473.4 4495.3 5718.7 5741.5 5779.4 5931.1 5933.1 6150.3 6156.4 6177.8 6185.7 6215.3 5934.3 5946.2 5962.8 5972.5 5992.6 5421.6 5445.1 5460.2 5474.0 5487.7 6189.9 6250.5 6262.6 6265.0 6322.1 6183.9 6190.1 6203.3 6203.7 6226.1 6241.9 6345.7 6361.4 6380.9 6385.3 6117.2 6117.5 6140.9 6180.7 6198.4 5005.7 6130.7 6187.6 6207.3 6226.7 6043.1 6115.9 6147.1 6152.1 6176.4 6101.6 6126.6 6134.1 6158.9 6166.0 4774.8 4791.5 5481.2 5818.0 6036.8 4918.6 5145.3 6139.2 6142.9 6200.7 5900.4 5979.7 5993.7 6039.8 6054.4 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | [#1] ext.html Cycles:180 Average:69511.42 Median:39302.80 stddev:147484.86 (375.3%) stddev-sans-first:147807.41 10:49:08 INFO - PROCESS | 2481 | Values: 437.5 467.1 481.0 492.8 530.2 29864.0 40815.0 40816.6 40885.4 41034.6 41701.0 41715.0 42192.6 42260.6 42458.4 8486.9 8681.6 9202.3 9470.9 11090.5 11230.2 11498.8 11503.3 11503.4 11555.5 9903.6 11261.8 11493.2 11531.4 11548.7 11353.2 11380.2 11393.8 11472.2 11522.3 11251.3 11296.9 11327.3 11329.2 11331.9 40129.4 40475.2 40741.0 40780.6 40862.8 3083.2 3350.7 3461.6 3870.3 3994.2 24524.0 29906.5 31046.8 31120.7 31169.5 3168.2 3316.0 3333.7 3363.2 3372.9 489628.0 494843.0 494925.0 495897.0 497202.5 63309.4 63321.4 63681.4 63724.0 64296.0 64222.7 64545.0 64663.4 64691.0 64869.0 15990.0 16179.1 16189.0 16196.1 16232.8 11408.3 11660.9 12383.9 12600.9 12626.6 14987.7 17532.6 18122.5 18145.8 18221.8 5576.8 6178.4 6341.5 6370.4 6445.4 736.7 850.4 996.2 1009.5 1031.7 26385.2 29345.6 30328.4 30601.8 30930.5 845.6 992.8 1035.7 1133.4 1149.5 13453.3 15662.8 15775.6 15810.0 16186.6 603909.0 605665.0 607704.0 608506.0 608696.0 45162.0 60163.4 60400.7 60496.4 61414.8 1210.8 1338.0 1379.8 1404.3 1411.2 29040.0 29989.6 30545.4 30624.8 30857.2 49141.0 53150.5 53575.7 53595.0 53671.7 550884.0 551306.0 553359.0 554341.0 555230.0 36072.0 40937.8 41427.8 41943.2 42051.8 39996.0 46439.0 47755.8 47894.8 49594.8 39545.6 39969.8 39984.6 40037.0 41666.8 39622.6 39637.0 40294.6 40841.8 40887.4 37888.2 38379.2 38639.6 38849.2 39060.0 32273.4 33956.0 34760.8 35293.5 35465.8 25820.9 25838.6 26005.6 26207.2 26531.7 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | [#2] jquery.html Cycles:180 Average:33891.38 Median:11214.78 stddev:97207.38 (866.8%) stddev-sans-first:97447.49 10:49:08 INFO - PROCESS | 2481 | Values: 371.6 376.8 382.7 387.8 404.2 17789.7 20157.3 20239.9 20420.7 20502.0 19782.6 19891.0 19932.5 20099.6 20393.2 5323.4 5528.9 5536.5 5771.3 5802.8 5677.0 5785.3 5785.7 5786.0 5807.7 5530.8 5671.3 5733.0 5802.7 5872.6 5842.8 5861.6 5872.8 5897.2 5900.7 5507.6 5695.7 5758.4 5766.4 5776.5 12844.7 12845.5 12887.7 13003.3 13047.9 2036.4 2253.6 2403.4 2651.5 2921.9 9455.2 9716.3 9737.5 10038.6 10180.7 1778.0 1783.5 1816.6 1818.1 1857.5 97819.5 107776.5 115378.0 116508.0 116994.0 9643.8 11212.4 11322.4 11355.1 11588.4 14292.2 14384.6 14393.9 14402.8 14428.5 8002.0 8327.0 8385.2 8470.0 8507.5 8186.5 8192.9 8199.5 8320.3 8339.3 3557.3 3814.6 4319.6 4393.7 4610.9 1629.9 1880.2 1943.6 1949.4 1949.9 827.3 855.7 890.4 955.7 959.9 19335.7 20180.7 20226.5 20270.8 20543.1 909.7 938.8 944.4 944.6 1013.9 8217.5 10144.2 10706.9 10760.8 10766.3 565641.0 579555.0 582247.2 588733.0 598672.0 114469.0 124171.0 124865.0 125899.0 127231.4 993.2 997.9 1030.0 1033.0 1037.3 13192.4 17522.6 18158.5 18325.3 18436.0 35471.0 52706.7 53493.5 54023.2 54120.7 72310.3 78453.2 78716.2 78918.0 80279.6 7376.4 8002.9 8035.9 8246.4 11717.0 12675.5 12854.3 13360.1 13466.0 13525.0 11385.3 11441.1 11580.7 11796.8 11867.5 11384.0 11571.8 11571.9 11622.0 11802.8 11659.5 11745.4 11753.2 11990.0 12043.1 12155.6 12290.1 12392.6 12401.9 12509.7 11615.7 11743.3 11866.2 11888.5 12031.5 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | [#3] mootools.html Cycles:180 Average:5353.72 Median:5205.74 stddev:2920.71 (56.1%) stddev-sans-first:2913.09 10:49:08 INFO - PROCESS | 2481 | Values: 1309.8 1515.6 1699.7 1826.8 1878.1 7525.1 7805.9 8010.8 8470.5 8478.4 8034.0 8325.5 8331.1 8411.3 8422.9 2299.4 2326.2 2328.7 2334.7 2338.2 2329.2 2333.3 2353.8 2371.5 2375.6 2025.2 2276.8 2323.5 2327.1 2338.3 2287.0 2304.0 2304.4 2313.9 2321.7 2319.2 2322.3 2346.1 2352.6 2359.8 8321.5 8341.4 8406.6 8427.3 8449.7 4963.6 5048.2 5108.2 5112.6 5121.9 3310.8 3405.7 3501.8 3586.5 3612.3 1012.3 1036.8 1070.9 1095.4 1099.6 8827.8 9431.1 10055.7 10805.7 10876.1 3826.3 3867.5 4449.5 4506.2 4678.8 7700.6 8755.8 9285.3 9338.6 9361.3 5179.8 5231.7 5290.9 5294.0 5313.4 3744.6 4444.3 4657.8 4713.7 4762.8 3747.3 3813.8 3846.1 3872.4 3881.1 1274.3 1290.7 1290.8 1296.3 1300.2 786.6 888.8 900.7 906.5 915.2 5551.7 5607.6 5619.2 5765.2 5770.9 426.5 441.6 441.9 442.1 905.1 3089.3 3657.8 3674.5 3778.9 3855.2 9395.7 9937.2 9952.9 9965.4 9972.3 9878.2 9902.3 9927.8 10070.0 10081.2 7830.3 7870.3 7980.3 7984.6 7986.2 4263.7 5482.5 5579.9 5620.7 5628.6 9206.9 9208.4 9281.3 9296.3 9424.8 8969.7 9312.7 9379.8 9386.1 9416.5 6334.3 6535.3 6982.1 7078.6 7227.9 7238.4 7275.9 7930.3 7984.8 7997.9 7058.2 7194.7 7199.3 7244.5 7317.9 6228.8 6928.4 6986.5 7021.9 7035.9 6423.4 6595.9 6656.5 6732.5 6740.5 4829.3 4854.1 4878.1 4918.4 4940.0 4338.3 4780.5 4788.4 4838.9 4931.5 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | [#4] prototype.html Cycles:180 Average:4544.62 Median:5109.70 stddev:1409.73 (27.6%) stddev-sans-first:1377.75 10:49:08 INFO - PROCESS | 2481 | Values: 330.6 364.4 368.8 372.6 385.9 5016.4 5360.4 5454.6 5566.9 5582.9 4524.6 5109.7 5160.9 5236.5 5448.8 3455.8 3673.8 3736.5 4236.3 4284.1 3477.3 3560.9 3852.4 4283.9 4316.6 4053.9 4056.7 4062.6 4073.9 4075.0 4056.3 4057.7 4061.6 4064.9 4083.5 3520.7 3525.8 3535.9 3541.8 3561.8 5109.6 5157.5 5240.7 5242.5 5343.5 4126.3 4600.5 4638.4 4640.7 4645.3 5682.2 5705.6 5709.8 5714.8 5759.8 3276.5 3670.7 3860.2 3895.8 3934.4 6021.9 6032.5 6038.0 6051.6 6086.3 3620.0 3915.5 3935.5 3946.3 3962.9 3678.5 3849.9 3920.1 3983.1 4000.5 3881.5 4631.6 4638.6 4649.3 4655.7 4937.9 5291.9 5601.7 5629.9 5807.6 1175.2 1214.5 1225.3 1269.6 1283.4 841.0 842.4 873.6 878.0 882.5 4481.6 5085.7 5116.3 5126.6 5145.8 4666.9 5176.9 5587.9 5632.1 5683.8 4196.9 4509.4 4546.9 4605.4 5133.9 2714.7 2719.4 2769.9 2823.5 2939.7 4903.0 5449.6 5462.3 5551.5 5775.3 5515.0 5784.7 5824.9 5837.0 5840.7 5064.5 5638.1 5757.3 5850.5 5894.4 4618.9 5011.8 5064.6 5437.8 5606.6 4651.4 5282.6 5898.5 5914.5 5917.2 5310.3 6192.3 6246.7 6259.0 6273.9 3805.5 3957.2 4008.2 4008.8 4024.8 4502.0 4887.4 5470.9 5926.1 5975.3 5108.5 5121.4 5126.7 5214.8 5709.6 5295.3 5801.9 5821.4 5826.6 5848.5 5164.5 5346.5 5617.6 5672.5 5696.3 5124.3 5440.1 5844.5 5850.3 5877.4 5002.6 5660.7 5676.3 5682.5 5733.5 10:49:08 INFO - PROCESS | 2481 | 10:49:08 INFO - PROCESS | 2481 | [#5] yui.html Cycles:180 Average:19846.52 Median:4057.63 stddev:28303.61 (697.5%) stddev-sans-first:28346.61 10:49:08 INFO - PROCESS | 2481 | Values: 730.6 730.8 784.6 814.3 836.8 15054.7 15636.8 15681.9 15728.7 15733.6 14310.2 14466.8 14511.9 14524.3 14528.5 697.3 739.6 867.0 887.1 912.3 906.2 916.1 924.5 924.7 925.4 945.4 980.0 980.5 980.7 980.8 876.0 944.5 953.0 959.5 961.6 716.1 726.0 728.9 730.5 734.0 11755.9 14495.6 14634.5 15013.7 15240.8 14058.0 14374.9 14390.0 14416.4 14474.7 11353.3 11450.5 11452.3 11525.7 11843.5 3686.4 3713.1 3737.0 3767.5 3857.9 109243.1 112565.0 113059.0 113222.4 115498.0 10396.6 10529.5 10552.4 10740.7 10783.8 21304.9 21585.9 21640.7 21659.5 21678.9 7182.2 7692.7 7712.4 7786.8 7869.8 3629.2 4257.4 4331.2 4382.8 4386.9 3161.8 3221.8 3448.0 3583.3 3596.6 561.0 611.7 658.1 665.2 672.0 558.7 563.3 625.2 657.7 672.6 7671.0 8878.1 9298.9 9490.6 9549.4 608.7 676.5 681.7 696.1 709.1 3614.3 4374.2 4397.2 4404.3 4773.4 62417.0 80550.0 90212.0 105165.4 106518.0 74704.0 77168.0 79020.0 80815.8 83399.0 67285.7 67812.3 69603.0 74568.6 76379.6 9629.3 10042.5 10077.4 10109.7 10121.2 45747.3 50743.5 51281.7 52908.7 52944.7 65951.0 69256.0 71668.0 73110.0 73841.0 11771.4 12637.6 12856.1 12916.8 13444.3 14470.1 14695.0 14777.3 14872.8 15023.8 13427.3 13640.7 13696.9 13763.8 13935.7 13341.4 13544.6 13781.9 13994.6 14002.9 12978.1 13220.7 13332.3 13496.7 13613.3 10997.9 11195.9 11219.8 11272.0 11446.9 7528.2 9427.4 10281.5 11189.5 11540.5 10:49:08 INFO - PROCESS | 2481 | -------- Summary: end -------- 10:49:08 INFO - PROCESS | 2481 | 10:49:09 INFO - TEST-INFO | 2481: exit 0 10:49:09 INFO - TEST-OK | dromaeo_css | took 1116586ms 10:49:09 INFO - TEST-START | kraken 10:49:09 INFO - operating with platform_type : mac_ 10:49:09 INFO - Initialising browser for kraken test... 10:49:09 INFO - TEST-INFO | started process 2485 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpj6Mexq/profile http://localhost:62141/getInfo.html) 10:49:12 INFO - PROCESS | 2485 | 2016-12-14 10:49:12.146 plugin-container[2486:35105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 10:49:12 INFO - PROCESS | 2485 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:12 INFO - PROCESS | 2485 | 2016-12-14 10:49:12.148 plugin-container[2486:35105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 10:49:12 INFO - PROCESS | 2485 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:12 INFO - PROCESS | 2485 | 2016-12-14 10:49:12.148 plugin-container[2486:35105] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:49:12 INFO - PROCESS | 2485 | __metrics Screen width/height:1600/1200 10:49:12 INFO - PROCESS | 2485 | colorDepth:24 10:49:12 INFO - PROCESS | 2485 | Browser inner width/height: 1024/689 10:49:12 INFO - PROCESS | 2485 | __metrics 10:49:12 ERROR - PROCESS | 2485 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 10:49:12 INFO - TEST-INFO | 2485: exit 0 10:49:12 INFO - Browser initialized. 10:49:12 INFO - Running cycle 1/1 for kraken test... 10:49:12 INFO - TEST-INFO | started process 2487 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpj6Mexq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:15 INFO - PROCESS | 2487 | 2016-12-14 10:49:15.259 plugin-container[2488:35228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8733, name = 'com.apple.tsm.portname' 10:49:15 INFO - PROCESS | 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:15 INFO - PROCESS | 2487 | 2016-12-14 10:49:15.260 plugin-container[2488:35228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 10:49:15 INFO - PROCESS | 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:49:15 INFO - PROCESS | 2487 | 2016-12-14 10:49:15.260 plugin-container[2488:35228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:49:24 INFO - PROCESS | 2487 | RSS: Main: 236974080 10:49:24 INFO - PROCESS | 2487 | 10:49:28 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62141/tests/kraken/driver.html?testName=audio-beat-detection) 10:49:29 INFO - PROCESS | 2487 | RSS: Main: 249679872 10:49:29 INFO - PROCESS | 2487 | 10:49:31 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62141/tests/kraken/driver.html?testName=audio-dft) 10:49:32 INFO - PROCESS | 2487 | RSS: Main: 257196032 10:49:32 INFO - PROCESS | 2487 | 10:49:35 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62141/tests/kraken/driver.html?testName=audio-fft) 10:49:36 INFO - PROCESS | 2487 | RSS: Main: 259899392 10:49:36 INFO - PROCESS | 2487 | 10:49:38 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62141/tests/kraken/driver.html?testName=audio-oscillator) 10:49:39 INFO - PROCESS | 2487 | RSS: Main: 261079040 10:49:39 INFO - PROCESS | 2487 | 10:49:41 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62141/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:49:41 INFO - PROCESS | 2487 | RSS: Main: 261435392 10:49:41 INFO - PROCESS | 2487 | 10:49:44 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62141/tests/kraken/driver.html?testName=imaging-darkroom) 10:49:45 INFO - PROCESS | 2487 | RSS: Main: 267673600 10:49:45 INFO - PROCESS | 2487 | 10:49:48 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62141/tests/kraken/driver.html?testName=imaging-desaturate) 10:49:49 INFO - PROCESS | 2487 | RSS: Main: 267862016 10:49:49 INFO - PROCESS | 2487 | 10:49:52 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62141/tests/kraken/driver.html?testName=json-parse-financial) 10:49:52 INFO - PROCESS | 2487 | RSS: Main: 267878400 10:49:52 INFO - PROCESS | 2487 | 10:49:54 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62141/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:49:55 INFO - PROCESS | 2487 | RSS: Main: 267120640 10:49:55 INFO - PROCESS | 2487 | 10:49:57 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:49:57 INFO - PROCESS | 2487 | RSS: Main: 267280384 10:49:57 INFO - PROCESS | 2487 | 10:50:00 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:50:00 INFO - PROCESS | 2487 | RSS: Main: 267182080 10:50:00 INFO - PROCESS | 2487 | 10:50:03 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:50:04 INFO - PROCESS | 2487 | RSS: Main: 267702272 10:50:04 INFO - PROCESS | 2487 | 10:50:07 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:50:08 INFO - PROCESS | 2487 | RSS: Main: 267214848 10:50:08 INFO - PROCESS | 2487 | 10:50:09 INFO - PROCESS | 2487 | Cycle 1(1): loaded http://localhost:62141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62141/tests/kraken/driver.html?testName=ai-astar) 10:50:10 INFO - PROCESS | 2487 | RSS: Main: 268460032 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | __start_tp_report 10:50:10 INFO - PROCESS | 2487 | _x_x_mozilla_page_load 10:50:10 INFO - PROCESS | 2487 | _x_x_mozilla_page_load_details 10:50:10 INFO - PROCESS | 2487 | |i|pagename|runs| 10:50:10 INFO - PROCESS | 2487 | |0;ai-astar;105;99;171;178;122;108;115;115;116;115 10:50:10 INFO - PROCESS | 2487 | |1;audio-beat-detection;150;117;115;151;119;126;136;123;185;151 10:50:10 INFO - PROCESS | 2487 | |2;audio-dft;210;265;288;224;196;197;194;199;194;201 10:50:10 INFO - PROCESS | 2487 | |3;audio-fft;81;57;59;94;72;72;52;52;65;56 10:50:10 INFO - PROCESS | 2487 | |4;audio-oscillator;91;71;85;69;70;69;82;73;70;83 10:50:10 INFO - PROCESS | 2487 | |5;imaging-gaussian-blur;133;127;129;150;108;113;100;111;108;111 10:50:10 INFO - PROCESS | 2487 | |6;imaging-darkroom;99;98;132;117;97;99;133;172;98;98 10:50:10 INFO - PROCESS | 2487 | |7;imaging-desaturate;90;97;88;88;95;92;106;150;79;149 10:50:10 INFO - PROCESS | 2487 | |8;json-parse-financial;57;57;55;59;57;59;57;58;58;57 10:50:10 INFO - PROCESS | 2487 | |9;json-stringify-tinderbox;67;81;79;70;70;71;74;73;76;73 10:50:10 INFO - PROCESS | 2487 | |10;stanford-crypto-aes;95;88;93;89;87;89;90;88;110;112 10:50:10 INFO - PROCESS | 2487 | |11;stanford-crypto-ccm;175;153;146;154;163;162;148;149;195;150 10:50:10 INFO - PROCESS | 2487 | |12;stanford-crypto-pbkdf2;224;246;186;180;172;177;179;173;176;160 10:50:10 INFO - PROCESS | 2487 | |13;stanford-crypto-sha256-iterative;64;91;96;90;92;60;75;87;91;67 10:50:10 INFO - PROCESS | 2487 | __end_tp_report 10:50:10 INFO - PROCESS | 2487 | __start_cc_report 10:50:10 INFO - PROCESS | 2487 | _x_x_mozilla_cycle_collect,935 10:50:10 INFO - PROCESS | 2487 | __end_cc_report 10:50:10 INFO - PROCESS | 2487 | __startTimestamp1481741410205__endTimestamp 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | ------- Summary: start ------- 10:50:10 INFO - PROCESS | 2487 | Number of tests: 14 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#0] ai-astar Cycles:10 Average:124.40 Median:119.00 stddev:27.24 (22.9%) stddev-sans-first:27.97 10:50:10 INFO - PROCESS | 2487 | Values: 105.0 99.0 171.0 178.0 122.0 108.0 115.0 115.0 116.0 115.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:143.00 stddev:22.09 (15.4%) stddev-sans-first:22.94 10:50:10 INFO - PROCESS | 2487 | Values: 150.0 117.0 115.0 151.0 119.0 126.0 136.0 123.0 185.0 151.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#2] audio-dft Cycles:10 Average:216.80 Median:205.50 stddev:33.20 (16.2%) stddev-sans-first:35.13 10:50:10 INFO - PROCESS | 2487 | Values: 210.0 265.0 288.0 224.0 196.0 197.0 194.0 199.0 194.0 201.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#3] audio-fft Cycles:10 Average:66.00 Median:68.50 stddev:13.76 (20.1%) stddev-sans-first:13.48 10:50:10 INFO - PROCESS | 2487 | Values: 81.0 57.0 59.0 94.0 72.0 72.0 52.0 52.0 65.0 56.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:77.50 stddev:8.12 (10.5%) stddev-sans-first:6.65 10:50:10 INFO - PROCESS | 2487 | Values: 91.0 71.0 85.0 69.0 70.0 69.0 82.0 73.0 70.0 83.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#5] imaging-gaussian-blur Cycles:10 Average:119.00 Median:120.00 stddev:15.23 (12.7%) stddev-sans-first:15.29 10:50:10 INFO - PROCESS | 2487 | Values: 133.0 127.0 129.0 150.0 108.0 113.0 100.0 111.0 108.0 111.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#6] imaging-darkroom Cycles:10 Average:114.30 Median:98.00 stddev:24.86 (25.4%) stddev-sans-first:25.75 10:50:10 INFO - PROCESS | 2487 | Values: 99.0 98.0 132.0 117.0 97.0 99.0 133.0 172.0 98.0 98.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#7] imaging-desaturate Cycles:10 Average:103.40 Median:89.00 stddev:25.27 (28.4%) stddev-sans-first:26.34 10:50:10 INFO - PROCESS | 2487 | Values: 90.0 97.0 88.0 88.0 95.0 92.0 106.0 150.0 79.0 149.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.50 stddev:1.17 (2.0%) stddev-sans-first:1.24 10:50:10 INFO - PROCESS | 2487 | Values: 57.0 57.0 55.0 59.0 57.0 59.0 57.0 58.0 58.0 57.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:73.50 stddev:4.30 (5.9%) stddev-sans-first:3.89 10:50:10 INFO - PROCESS | 2487 | Values: 67.0 81.0 79.0 70.0 70.0 71.0 74.0 73.0 76.0 73.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:89.00 stddev:9.24 (10.4%) stddev-sans-first:9.80 10:50:10 INFO - PROCESS | 2487 | Values: 95.0 88.0 93.0 89.0 87.0 89.0 90.0 88.0 110.0 112.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#11] stanford-crypto-ccm Cycles:10 Average:159.50 Median:158.00 stddev:15.30 (9.7%) stddev-sans-first:15.16 10:50:10 INFO - PROCESS | 2487 | Values: 175.0 153.0 146.0 154.0 163.0 162.0 148.0 149.0 195.0 150.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:179.50 stddev:26.53 (14.8%) stddev-sans-first:24.59 10:50:10 INFO - PROCESS | 2487 | Values: 224.0 246.0 186.0 180.0 172.0 177.0 179.0 173.0 176.0 160.0 10:50:10 INFO - PROCESS | 2487 | 10:50:10 INFO - PROCESS | 2487 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.30 Median:90.50 stddev:13.43 (14.8%) stddev-sans-first:12.71 10:50:10 INFO - PROCESS | 2487 | Values: 64.0 91.0 96.0 90.0 92.0 60.0 75.0 87.0 91.0 67.0 10:50:10 INFO - PROCESS | 2487 | -------- Summary: end -------- 10:50:10 INFO - PROCESS | 2487 | 10:50:11 INFO - TEST-INFO | 2487: exit 0 10:50:11 INFO - TEST-OK | kraken | took 61790ms 10:50:11 INFO - SUITE-END | took 1178s 10:50:11 INFO - Completed test suite (00:19:38) 10:50:11 INFO - javascript benchmark 10:50:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7217.057713165671, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.8347582620872, 3339.833008349582, 3357.479590663447, 3390.728741700664, 3393.9830300848507, 5049.586115887548, 5239.187925871496, 5697.572682048843, 5704.115862041385, 5795.971020144899, 5648.265725455695, 5692.5161361284345, 5741.598088133832, 5749.223854779603, 5793.797217097403, 5225.372955245357, 5258.211268309746, 5274.894502109955, 5289.841304760864, 5294.814681486148, 4010.598940105995, 4412.24991751403, 5278.762455689484, 5287.973560132214, 5327.041132596131, 2839.2901774556362, 4088.7342322749073, 4492.348609451627, 5090.516400941923, 5397.865053373658, 4139.81370838313, 4422.668299877522, 5161.5870730341485, 5163.586913046947, 5228.189630607262, 4183.707140500166, 4710.670253082286, 4731.574158325733, 5226.425093239741, 5296.311479507639, 5514.944850551483, 5702.0, 5737.540996720252, 5743.913841292384, 5794.130880367937, 6119.563485741598, 6177.0, 6211.192544969125, 6220.751169953196, 6246.219222597176, 6164.907526387063, 6178.474829639486, 6229.875402491924, 6244.594101383395, 6248.4064013918605, 5920.911186332209, 5924.874273887947, 5938.346781853993, 5941.168236446858, 5942.168096466498, 6306.2747784004505, 6308.684565771693, 6313.147725057151, 6337.081123237105, 6369.681515924278, 5967.254093238345, 6112.74665817949, 6112.908306375408, 6120.873812997669, 6135.9079613805825, 5637.26715526987, 5815.302163740378, 6159.4456498914415, 6159.4456498915315, 6236.220472440945, 6178.104174894615, 6179.073139029181, 6206.751729930843, 6214.533909956771, 6215.81352559424, 6111.7436766921655, 6119.082137679384, 6130.489860652556, 6155.84610384744, 6174.289956655042, 4942.4316203637045, 4957.3059771632, 4965.205567109245, 4967.40391153064, 4988.551030407209, 4437.0460351024685, 4448.822047118144, 4451.221036318631, 4473.418455600751, 4495.325701144789, 5718.714064296769, 5741.540676745851, 5779.393163717833, 5931.13998470228, 5933.139694744238, 6150.323464418911, 6156.4151405617295, 6177.752889884354, 6185.659788711664, 6215.34738852423, 5934.317553481319, 5946.167536544887, 5962.821115366546, 5972.522198224132, 5992.550558708114, 5421.593380496479, 5445.128779395278, 5460.235567020621, 5473.972630136824, 5487.725613719298, 6189.938100619026, 6250.49996000328, 6262.561620686691, 6264.968675156595, 6322.146510221061, 6183.876322473615, 6190.133381326528, 6203.286622038343, 6203.689815509297, 6226.066090086342, 6241.937580624317, 6345.650989195638, 6361.427471527585, 6380.947143721234, 6385.29761726219, 6117.188044311166, 6117.541184411098, 6140.87237156979, 6180.690965451619, 6198.380161983766, 5005.749712514433, 6130.673316708229, 6187.566870319034, 6207.255129384503, 6226.657533835501, 6043.09353596964, 6115.908261375994, 6147.065992170631, 6152.077188421594, 6176.444120029039, 6101.572889897665, 6126.559057237658, 6134.134531820433, 6158.9221886170635, 6165.969170154121, 4774.832880849153, 4791.520847915181, 5481.205225242397, 5818.010938140442, 6036.84907877289, 4918.581920536794, 5145.305383773292, 6139.216166136355, 6142.928535732134, 6200.720967556561, 5900.43945825146, 5979.701014949148, 5993.730282137401, 6039.758409663565, 6054.39456054391], "value": 5607.315638861376, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.45536806675693, 467.14978738695544, 481.0307230929677, 492.7930269286899, 530.2391068816247, 29863.954761583347, 40815.0, 40816.59183408165, 40885.38671919923, 41034.58965410345, 41701.0, 41714.957126071866, 42192.57807421925, 42260.57739422605, 42458.36312455316, 8486.915130848705, 8681.565921703921, 9202.309826762987, 9470.857937130948, 11090.500927458243, 11230.15773816963, 11498.770024599504, 11503.252288601256, 11503.42482875857, 11555.480003399867, 9903.55434005468, 11261.774764504746, 11493.195476316661, 11531.365774882379, 11548.653540393801, 11353.20527563071, 11380.203385763, 11393.829092563617, 11472.196946213768, 11522.308661480254, 11251.268667536666, 11296.887031129665, 11327.320360778298, 11329.206955513117, 11331.886681133248, 40129.39805902914, 40475.190496190204, 40740.98147546335, 40780.59219407797, 40862.79568602168, 3083.167544762924, 3350.71518920892, 3461.6365281645317, 3870.3033453978273, 3994.201159768058, 24524.0, 29906.542056074646, 31046.84476577625, 31120.688793112007, 31169.532457013163, 3168.2237851726245, 3315.950260746091, 3333.666633336647, 3363.209645733251, 3372.8819491317686, 489627.99999999645, 494843.0, 494925.0, 495897.0, 497202.51398743136, 63309.41726456876, 63321.366786332466, 63681.36318636847, 63724.0, 64296.0, 64222.678886606205, 64545.0, 64663.353366466676, 64691.0, 64869.0, 15990.040597564184, 16179.110148941685, 16189.0, 16196.109213993108, 16232.75650865238, 11408.315501069963, 11660.88339116598, 12383.876161238271, 12600.873991260154, 12626.558070467674, 14987.70024599502, 17532.5616859577, 18122.546936326435, 18145.818541814413, 18221.817781822276, 5576.800987787645, 6178.443940045418, 6341.524385671001, 6370.394812492806, 6445.387688169616, 736.7347754808264, 850.3877208409934, 996.1831298335295, 1009.4599389326675, 1031.6838697124854, 26385.208443746717, 29345.559816602767, 30328.393432131674, 30601.84699076535, 30930.53604195939, 845.6067928413318, 992.8482959766636, 1035.7100011996654, 1133.4106264742288, 1149.4540093455541, 13453.327333633279, 15662.843371566367, 15775.605609859846, 15810.0, 16186.595335116715, 603909.0, 605665.0, 607704.0, 608506.0, 608696.0, 45162.0, 60163.398366016656, 60400.697996510615, 60496.39503604908, 61414.77170456566, 1210.7578484303174, 1337.9732405352033, 1379.7792353223638, 1404.3329418526118, 1411.181514721459, 29040.0, 29989.55015674723, 30545.389092217585, 30624.846875765477, 30857.22856928505, 49141.0, 53150.468495314555, 53575.73212133914, 53595.0, 53671.73164134155, 550884.0, 551306.0, 553359.0, 554341.0, 555230.0, 36072.0, 40937.795311023256, 41427.7928610355, 41943.1611367777, 42051.78974105232, 39996.0, 46439.0, 47755.76122119367, 47894.76052619715, 49594.752026239636, 39545.60454395419, 39969.80015099906, 39984.600153998086, 40037.0, 41666.79166604148, 39622.60377396189, 39637.0, 40294.58968936191, 40841.79579102086, 40887.38668919909, 37888.24223515459, 38379.2324153521, 38639.6136038636, 38849.2230155401, 39060.0, 32273.35453290968, 33955.98132056042, 34760.84094626425, 35293.52945293805, 35465.82267088648, 25820.870895645403, 25838.612420813326, 26005.609915851655, 26207.21378358652, 26531.734682652925], "value": 20395.411633943884, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.5522795031985, 376.76828750318555, 382.70914105279985, 387.84098519606965, 404.17346526353623, 17789.733154002683, 20157.294494692687, 20239.898800506013, 20420.693689594667, 20501.974901254933, 19782.604347913035, 19891.005449727527, 19932.50168745782, 20099.59800803983, 20393.184272629078, 5323.361196656388, 5528.917066244009, 5536.501714845663, 5771.278590176228, 5802.796902108427, 5677.0, 5785.334686511042, 5785.681787501686, 5785.98745219587, 5807.680577568232, 5530.751116199761, 5671.262735844334, 5732.971335143318, 5802.709864506779, 5872.647641141524, 5842.795502157425, 5861.648301101947, 5872.823815285548, 5897.174395584621, 5900.734466948998, 5507.614466987313, 5695.7152142392715, 5758.3665796762325, 5766.365699773021, 5776.537876969833, 12844.743105137939, 12845.486180552767, 12887.67780805478, 13003.349832508336, 13047.86952130476, 2036.378904434147, 2253.5718213539376, 2403.375122468153, 2651.535981203301, 2921.9269518262, 9455.243580513543, 9716.29964756165, 9737.513124343825, 10038.598456061749, 10180.745481363025, 1777.9644407111784, 1783.545195975018, 1816.5731053202487, 1818.1454716283458, 1857.4799056264267, 97819.53270700945, 107776.4611176947, 115378.0, 116508.0, 116994.0, 9643.758906027406, 11212.383318917375, 11322.433878306052, 11355.148363872742, 11588.420578971018, 14292.213928234047, 14384.640383990274, 14393.92803035978, 14402.783958240634, 14428.495002674856, 8001.959745233083, 8327.0, 8385.203405676451, 8469.957650211833, 8507.489550626982, 8186.508809471451, 8192.918070819216, 8199.508029518249, 8320.334373250125, 8339.332853371716, 3557.288542291552, 3814.599467055975, 4319.632831209319, 4393.703857362086, 4610.9308360374625, 1629.9022058676517, 1880.2009146112848, 1943.6491638311238, 1949.4054313434256, 1949.8635095543175, 827.3422629009951, 855.6962278391206, 890.3945317184248, 955.6798472511647, 959.9040095990345, 19335.709964350546, 20180.69728954067, 20226.494337641678, 20270.797292027186, 20543.075561599762, 909.7452713240303, 938.7559234599054, 944.4380593546966, 944.631593678466, 1013.8631284776608, 8217.54803485814, 10144.239182061376, 10706.892931070746, 10760.838587421194, 10766.30019048775, 565641.0, 579555.0, 582247.1775282193, 588733.0, 598672.0, 114469.0, 124171.0, 124865.0, 125899.0, 127231.3638431802, 993.2153598657125, 997.9342760485724, 1029.953652085658, 1032.9690109296732, 1037.2946396450486, 13192.406341714837, 17522.561935951195, 18158.5460363492, 18325.315096815088, 18435.986020768985, 35471.0, 52706.73646631742, 53493.46506534885, 54023.189652156034, 54120.72939635276, 72310.27689723036, 78453.2154678446, 78716.21283787089, 78918.0, 80279.59860200662, 7376.4467664926415, 8002.879956800536, 8035.870494318893, 8246.422750407413, 11717.004054655108, 12675.492980281055, 12854.293013884326, 13360.131591446525, 13466.0, 13524.98562607828, 11385.259958102697, 11441.141914356624, 11580.6525804226, 11796.76406471883, 11867.525298987945, 11384.0, 11571.768564628828, 11571.884281157081, 11622.0, 11802.76394472123, 11659.533618655498, 11745.412729363668, 11753.236039657395, 11990.0, 12043.09676774228, 12155.635330940086, 12290.139690221598, 12392.617801832244, 12401.93799030999, 12509.687257818627, 11615.6515304541, 11743.295402275891, 11866.228695134789, 11888.524459021542, 12031.518739250681], "value": 9411.946324679566, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.8277042047366, 1515.5907904865683, 1699.6855581717384, 1826.844718198953, 1878.1172848761087, 7525.096988361404, 7805.882911756314, 8010.839783204339, 8470.534120623362, 8478.406511544194, 8033.959830200841, 8325.500469971807, 8331.12523185066, 8411.285040771541, 8422.91577084229, 2299.367673889677, 2326.2323433267065, 2328.6972693549815, 2334.743178250391, 2338.187904374115, 2329.1731435340466, 2333.311673056444, 2353.776391242829, 2371.5256948610263, 2375.6199008158696, 2025.1696804310236, 2276.781921671906, 2323.546908352872, 2327.1156960355124, 2338.251759436981, 2286.977130228693, 2303.97696023041, 2304.446932736148, 2313.90744370225, 2321.7330007049236, 2319.153508969231, 2322.280093171122, 2346.1319311854554, 2352.6118190498546, 2359.7994170495526, 8321.542315172663, 8341.374396920195, 8406.579671016425, 8427.283680887133, 8449.746507604781, 4963.6277279204205, 5048.217526283432, 5108.208227724708, 5112.565431938289, 5121.923171152435, 3310.7652334674, 3405.676460736226, 3501.8249087545773, 3586.497890295361, 3612.277544491086, 1012.3166862367946, 1036.8496567997597, 1070.8500809886623, 1095.3920574081383, 1099.6371197504805, 8827.77930551735, 9431.104045115771, 10055.743032120985, 10805.729856753644, 10876.129909607213, 3826.330392181357, 3867.5358956925347, 4449.488308844459, 4506.211413002711, 4678.7660616969015, 7700.576468294297, 8755.77419161318, 9285.257179425766, 9338.626454941726, 9361.34470587066, 5179.766910489037, 5231.712255825966, 5290.947090529123, 5294.047071527162, 5313.415524292324, 3744.5693745219105, 4444.31113177458, 4657.813687452464, 4713.717176969393, 4762.78567464465, 3747.3254814133716, 3813.84744610218, 3846.0961674006585, 3872.361060425055, 3881.1073453105378, 1274.2991354754847, 1290.7353992431529, 1290.8450985881755, 1296.3129541343103, 1300.2393599744255, 786.5988345943496, 888.8266787976412, 900.6712549919363, 906.496894223699, 915.2494954137543, 5551.694656793834, 5607.5513958884, 5619.241402410704, 5765.1640512125505, 5770.942290577124, 426.51590444844703, 441.59593971516347, 441.8718571614196, 442.1003258369203, 905.1355955062899, 3089.2894634233803, 3657.762245454037, 3674.540682414698, 3778.9433158502643, 3855.1711382052995, 9395.718128456021, 9937.205023598095, 9952.850707239397, 9965.40207587547, 9972.30193886421, 9878.160356369644, 9902.30683852138, 9927.801443971224, 10069.993000700018, 10081.19350451962, 7830.2952734254195, 7870.331021863206, 7980.281774640311, 7984.6406911687955, 7986.203104301486, 4263.722858014219, 5482.479164479368, 5579.944200558105, 5620.662760234399, 5628.577856660685, 9206.861897071683, 9208.447493150432, 9281.303902207226, 9296.349255552046, 9424.764380890258, 8969.730908072768, 9312.72061838146, 9379.812403752023, 9386.108319709616, 9416.529173541432, 6334.303226645157, 6535.3464653534265, 6982.1272340957385, 7078.575285482888, 7227.891581626174, 7238.384737297178, 7275.854482910418, 7930.286274235115, 7984.762361833925, 7997.920020799717, 7058.188308344606, 7194.676239569128, 7199.280071992759, 7244.492885497964, 7317.853642927005, 6228.844278893064, 6928.411085057826, 6986.510944233854, 7021.894671579828, 7035.929640703527, 6423.4218920295525, 6595.868082638224, 6656.467482601477, 6732.495062870206, 6740.460763138839, 4829.2997515359375, 4854.126257273725, 4878.146324393288, 4918.360613120271, 4940.0, 4338.349247612883, 4780.45024822136, 4788.449328327287, 4838.927416088691, 4931.50684931504], "value": 4309.72267244725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.64125423915056, 364.4387643029734, 368.81559220389806, 372.5897478045597, 385.918957019026, 5016.39475367882, 5360.383555891074, 5454.627237151842, 5566.912407847058, 5582.8604284892845, 4524.638028957684, 5109.744512774356, 5160.948390516094, 5236.528712415882, 5448.809291674792, 3455.8444869980785, 3673.797941113251, 3736.4955730976376, 4236.322188449849, 4284.100338928829, 3477.261369315342, 3560.857565697369, 3852.4012534798026, 4283.91432171358, 4316.6402583186355, 4053.905445529705, 4056.71602987791, 4062.614051665087, 4073.9388909166387, 4074.97962510187, 4056.3307054336, 4057.736247143926, 4061.593840615959, 4064.857729979466, 4083.509978802533, 3520.6655367740022, 3525.8413371398346, 3535.8939231823088, 3541.7874927504176, 3561.7684850484893, 5109.645943824889, 5157.4584668610005, 5240.694094598402, 5242.461338596432, 5343.492368225013, 4126.319157339036, 4600.516945720685, 4638.420197475316, 4640.67515273931, 4645.281245938991, 5682.232898558725, 5705.6291341063115, 5709.77160913563, 5714.771409143629, 5759.798407055733, 3276.459384201604, 3670.6512881276503, 3860.227954409101, 3895.7662540247675, 3934.449177115206, 6021.909671354933, 6032.487238584726, 6038.0, 6051.606645568024, 6086.300075491286, 3619.9819000904827, 3915.5497117831246, 3935.5080614923136, 3946.2896678598127, 3962.9485296321673, 3678.4666223397458, 3849.8845034649007, 3920.137569734654, 3983.083890705154, 4000.4799376081505, 3881.5250204922513, 4631.58315751584, 4638.5593368629925, 4649.279361698942, 4655.650826187982, 4937.851864444073, 5291.894162116736, 5601.683604352974, 5629.943700562942, 5807.593468457168, 1175.2184797109826, 1214.518287689018, 1225.332193954297, 1269.6127681057367, 1283.4352884730874, 840.9873851892227, 842.3808500751942, 873.5719497446206, 877.9736607901773, 882.4661080046608, 4481.551844815493, 5085.694858308513, 5116.334876466036, 5126.564561922668, 5145.819896303611, 4666.906661866744, 5176.870578235575, 5587.916181257285, 5632.127020311858, 5683.772649094072, 4196.916061678749, 4509.368688383629, 4546.909061818745, 4605.40129783126, 5133.897322053613, 2714.687810901732, 2719.3745438549245, 2769.9363973839218, 2823.4870964841307, 2939.720726531019, 4902.950970490321, 5449.591280653967, 5462.289902312674, 5551.528120109836, 5775.306963164448, 5514.972425137848, 5784.681842498619, 5824.912626310609, 5836.951037352659, 5840.737166827503, 5064.468230835783, 5638.083051762421, 5757.309122905194, 5850.548176575926, 5894.38108998549, 4618.930716039329, 5011.822221777952, 5064.567998641631, 5437.782488700408, 5606.579506536945, 4651.441826980784, 5282.577393808563, 5898.498627616701, 5914.497267732118, 5917.201177840936, 5310.274160897801, 6192.349803270592, 6246.656433896179, 6258.968705156445, 6273.874522509433, 3805.4672345871045, 3957.228340473695, 4008.238434697398, 4008.8396464141106, 4024.75851448914, 4501.977490112528, 4887.413510378778, 5470.890582188414, 5926.140709597171, 5975.34271230173, 5108.48915108501, 5121.385433747974, 5126.6923984561045, 5214.843554693365, 5709.628874123168, 5295.338082739658, 5801.883962320646, 5821.359650438364, 5826.563007774519, 5848.53211743051, 5164.499807704782, 5346.492083252085, 5617.606767526234, 5672.461116193955, 5696.344920334049, 5124.333836601218, 5440.110321120418, 5844.47399734026, 5850.285068981142, 5877.4122587740885, 5002.574781143498, 5660.745266462936, 5676.318841739017, 5682.5453963681975, 5733.512651424676], "value": 4106.303977492326, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.5872182217047, 730.777112980541, 784.5528049012062, 814.2590242878981, 836.7698882807225, 15054.698906021873, 15636.765448518281, 15681.921590392047, 15728.685426291468, 15733.60665983351, 14310.21293828839, 14466.782998255008, 14511.92744036281, 14524.273786310669, 14528.491502797404, 697.2817997462622, 739.6227923758895, 866.9869951950726, 887.0863011098579, 912.2519533982137, 906.2432868554764, 916.1159481100736, 924.5238702068407, 924.6902287733584, 925.4401087342162, 945.4185675809362, 979.9612410844492, 980.5146452505984, 980.7499087732618, 980.8332583460829, 876.023234093987, 944.5418971798638, 952.9850708994899, 959.539421077883, 961.5721004153155, 716.1048689138577, 725.9818504537376, 728.854229154166, 730.5470608222882, 733.9944276341446, 11755.882441175565, 14495.637609059753, 14634.487792927197, 15013.69972600542, 15240.771388429182, 14058.0, 14374.921880858978, 14390.0, 14416.423343066264, 14474.710505789826, 11353.262037967506, 11450.484728187252, 11452.312861228353, 11525.711857203552, 11843.467043982955, 3686.4286035664513, 3713.1459764254105, 3737.0, 3767.472553842464, 3857.903552411184, 109243.09636525348, 112565.0, 113059.0, 113222.43388783168, 115498.0, 10396.584136634601, 10529.473526323653, 10552.366857988545, 10740.677779666623, 10783.81378048414, 21304.893475532524, 21585.892070539547, 21640.701557906577, 21659.458513537287, 21678.891605542187, 7182.174049984319, 7692.73075442368, 7712.421568382394, 7786.805329866798, 7869.842603147905, 3629.2378600494194, 4257.3613957905945, 4331.198728235287, 4382.780860956939, 4386.8903277417685, 3161.7944833585743, 3221.838908054635, 3447.9827600861836, 3583.33708263972, 3596.604373518911, 561.0434209672499, 611.6722505678746, 658.1049772309655, 665.2416245480157, 671.9731210751613, 558.6787597131665, 563.2818156849967, 625.1997443272528, 657.6875983907605, 672.6334147889412, 7670.961645191738, 8878.060907395899, 9298.907010929805, 9490.620375184915, 9549.379290346105, 608.7459832744561, 676.4689718570996, 681.7183694654118, 696.1367903799253, 709.0888208651858, 3614.331348700499, 4374.212641724456, 4397.174458221368, 4404.2512772828695, 4773.403324584427, 62417.0, 80550.0, 90212.0, 105165.37086572744, 106518.0, 74704.0118395269, 77168.0, 79020.0, 80815.7877631836, 83399.0, 67285.66357168183, 67812.3218767806, 69603.0, 74568.62715686495, 76379.61810190913, 9629.32594718363, 10042.535325312756, 10077.395356278646, 10109.747256318651, 10121.240906931862, 45747.31379029251, 50743.46282685925, 51281.743591281804, 52908.73545632247, 52944.73527632337, 65951.01073483806, 69256.0, 71668.0, 73110.0, 73841.0, 11771.411429428323, 12637.620871373874, 12856.100072994801, 12916.8062479061, 13444.260565668983, 14470.131792092508, 14695.0, 14777.335019924345, 14872.776908346166, 15023.774643380139, 13427.26150061756, 13640.65898352549, 13696.93151534236, 13763.79354309666, 13935.721285574435, 13341.399637016164, 13544.59366219015, 13781.931090344484, 13994.580162595139, 14002.859971400156, 12978.106040622648, 13220.735585288048, 13332.333383330988, 13496.730065398833, 13613.319334033456, 10997.864444235123, 11195.944020279847, 11219.831702524632, 11272.041876440595, 11446.942765286454, 7528.234609589014, 9427.434353938786, 10281.485925703835, 11189.507595938887, 11540.538378464767], "value": 7417.282594310783, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 99.0, 171.0, 178.0, 122.0, 108.0, 115.0, 115.0, 116.0, 115.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 115.0, 151.0, 119.0, 126.0, 136.0, 123.0, 185.0, 151.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 265.0, 288.0, 224.0, 196.0, 197.0, 194.0, 199.0, 194.0, 201.0], "value": 216.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 59.0, 94.0, 72.0, 72.0, 52.0, 52.0, 65.0, 56.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 85.0, 69.0, 70.0, 69.0, 82.0, 73.0, 70.0, 83.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 127.0, 129.0, 150.0, 108.0, 113.0, 100.0, 111.0, 108.0, 111.0], "value": 119.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 132.0, 117.0, 97.0, 99.0, 133.0, 172.0, 98.0, 98.0], "value": 114.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 97.0, 88.0, 88.0, 95.0, 92.0, 106.0, 150.0, 79.0, 149.0], "value": 103.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 59.0, 57.0, 59.0, 57.0, 58.0, 58.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 81.0, 79.0, 70.0, 70.0, 71.0, 74.0, 73.0, 76.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 88.0, 93.0, 89.0, 87.0, 89.0, 90.0, 88.0, 110.0, 112.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 153.0, 146.0, 154.0, 163.0, 162.0, 148.0, 149.0, 195.0, 150.0], "value": 159.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 246.0, 186.0, 180.0, 172.0, 177.0, 179.0, 173.0, 176.0, 160.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 91.0, 96.0, 90.0, 92.0, 60.0, 75.0, 87.0, 91.0, 67.0], "value": 81.3, "unit": "score"}]}]} 10:50:11 INFO - Return code: 0 10:50:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:50:11 ERROR - # TBPL SUCCESS # 10:50:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:50:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:50:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:50:11 INFO - 2016-12-14 10:50:11.570 screenresolution[2492:35610] starting screenresolution argv=screenresolution get 10:50:11 INFO - 2016-12-14 10:50:11.573 screenresolution[2492:35610] Display 0: 1600x1200x32@60 10:50:11 INFO - 2016-12-14 10:50:11.580 screenresolution[2493:35613] starting screenresolution argv=screenresolution list 10:50:11 INFO - Available Modes on Display 0 10:50:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:50:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:50:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:50:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:50:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:50:11 INFO - Intel Iris: 10:50:11 INFO - Chipset Model: Intel Iris 10:50:11 INFO - Type: GPU 10:50:11 INFO - Bus: Built-In 10:50:11 INFO - VRAM (Dynamic, Max): 1536 MB 10:50:11 INFO - Vendor: Intel (0x8086) 10:50:11 INFO - Device ID: 0x0a2e 10:50:11 INFO - Revision ID: 0x0009 10:50:11 INFO - Displays: 10:50:11 INFO - Display: 10:50:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:50:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:50:11 INFO - Display Serial Number: 1600x1200 60 10:50:11 INFO - Main Display: Yes 10:50:11 INFO - Mirror: Off 10:50:11 INFO - Online: Yes 10:50:11 INFO - Rotation: Supported 10:50:11 INFO - Return code: 0 10:50:11 INFO - Running post-action listener: _resource_record_post_action 10:50:11 INFO - [mozharness: 2016-12-14 18:50:11.817083Z] Finished run-tests step (success) 10:50:11 INFO - Running post-run listener: _resource_record_post_run 10:50:11 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 0; Write bytes: 555136000; Read time: 0; Write time: 1810 10:50:11 INFO - TinderboxPrint: CPU usage
54.6% 10:50:11 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 10:50:11 INFO - TinderboxPrint: I/O write bytes / time
555,136,000 / 1,810 10:50:11 INFO - TinderboxPrint: CPU idle
2,180.3 (45.4%) 10:50:11 INFO - TinderboxPrint: CPU system
976.2 (20.3%) 10:50:11 INFO - TinderboxPrint: CPU user
1,649.3 (34.3%) 10:50:11 INFO - TinderboxPrint: Swap in / out
249,073,664 / 0 10:50:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228704768; Write bytes: 222692864; Read time: 16485; Write time: 442 10:50:11 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 0; Write bytes: 303148544; Read time: 0; Write time: 1313 10:50:12 INFO - Running post-run listener: _upload_blobber_files 10:50:12 INFO - Blob upload gear active. 10:50:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:50:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:50:12 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'] 10:50:12 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 10:50:12 INFO - (blobuploader) - INFO - Open directory for files ... 10:50:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:50:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:50:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:13 INFO - (blobuploader) - INFO - Done attempting. 10:50:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:50:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:50:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:14 INFO - (blobuploader) - INFO - Done attempting. 10:50:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:50:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:50:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:17 INFO - (blobuploader) - INFO - Done attempting. 10:50:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:50:17 INFO - Return code: 0 10:50:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:50:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:50:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/73fa526ec544cc65b4c4d24feed1b1f23fb5754c174811f57e34da61b45b5ff1f7e73e180106080ed184cd0de2e0b119247c83a3e4cd6417a8bb80366cd66386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc863d98087d3bf7d60a529bbde4d10611b295672e375faab77ca4271b8775e61de1979728bba0179a5b2ee45c346fcec39aa4b75f52280aa15da711164e86ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85529930b1a3642352502802ce35cd2fdd8d580f592cdcb6396d11d7bfb6329d9b7c21769f59a3a5653bd4705ddc1757d6e0c840653a30700bd2153b00f41b4c"} 10:50:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:50:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:50:17 INFO - Contents: 10:50:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/73fa526ec544cc65b4c4d24feed1b1f23fb5754c174811f57e34da61b45b5ff1f7e73e180106080ed184cd0de2e0b119247c83a3e4cd6417a8bb80366cd66386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc863d98087d3bf7d60a529bbde4d10611b295672e375faab77ca4271b8775e61de1979728bba0179a5b2ee45c346fcec39aa4b75f52280aa15da711164e86ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85529930b1a3642352502802ce35cd2fdd8d580f592cdcb6396d11d7bfb6329d9b7c21769f59a3a5653bd4705ddc1757d6e0c840653a30700bd2153b00f41b4c"} 10:50:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:50:17 INFO - Copying logs to upload dir... 10:50:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:50:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.472561 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-12-14 10:50:17.223005) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 10:50:17.226688) ========= 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.MujjCp9wSb/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.VoQtaJh3fo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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/73fa526ec544cc65b4c4d24feed1b1f23fb5754c174811f57e34da61b45b5ff1f7e73e180106080ed184cd0de2e0b119247c83a3e4cd6417a8bb80366cd66386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc863d98087d3bf7d60a529bbde4d10611b295672e375faab77ca4271b8775e61de1979728bba0179a5b2ee45c346fcec39aa4b75f52280aa15da711164e86ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85529930b1a3642352502802ce35cd2fdd8d580f592cdcb6396d11d7bfb6329d9b7c21769f59a3a5653bd4705ddc1757d6e0c840653a30700bd2153b00f41b4c"} build_url:https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011480 build_url: 'https://queue.taskcluster.net/v1/task/EC8e4uh3Q3mrfe7Ez0y36g/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/73fa526ec544cc65b4c4d24feed1b1f23fb5754c174811f57e34da61b45b5ff1f7e73e180106080ed184cd0de2e0b119247c83a3e4cd6417a8bb80366cd66386", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bc863d98087d3bf7d60a529bbde4d10611b295672e375faab77ca4271b8775e61de1979728bba0179a5b2ee45c346fcec39aa4b75f52280aa15da711164e86ae", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/85529930b1a3642352502802ce35cd2fdd8d580f592cdcb6396d11d7bfb6329d9b7c21769f59a3a5653bd4705ddc1757d6e0c840653a30700bd2153b00f41b4c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 10:50:17.262385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 10:50:17.262725) ========= 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.MujjCp9wSb/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.VoQtaJh3fo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005376 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 10:50:17.311936) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 10:50:17.312263) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 10:50:17.312599) ========= ========= Total master_lag: 0.19 =========