builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0201 starttime: 1482264487.92 results: success (0) buildid: 20161220094753 builduid: a4e3aa4b78634420b404ae07f72a87c4 revision: fe6d772d34c2d6ef137210a797d04d0f62da7927 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:07.922868) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:07.923338) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:07.923613) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:08.068410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:08.068712) ========= 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.udGWxKCKVD/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.3C4uO6YRRd/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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-20 12:08:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-12-20 12:08:08 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141927 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:08.251837) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:08.252167) ========= 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.udGWxKCKVD/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.3C4uO6YRRd/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.025354 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:08.347252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 12:08:08.347574) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev fe6d772d34c2d6ef137210a797d04d0f62da7927 --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 fe6d772d34c2d6ef137210a797d04d0f62da7927 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.udGWxKCKVD/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.3C4uO6YRRd/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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-20 12:08:08,453 truncating revision to first 12 chars 2016-12-20 12:08:08,453 Setting DEBUG logging. 2016-12-20 12:08:08,453 attempt 1/10 2016-12-20 12:08:08,453 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/fe6d772d34c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 12:08:09,353 unpacking tar archive at: graphics-fe6d772d34c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.176237 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 12:08:09.558840) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:09.559155) ========= script_repo_revision: fe6d772d34c2d6ef137210a797d04d0f62da7927 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:09.559912) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:09.560193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 12:08:09.581756) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-20 12:08:09.582060) ========= /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.udGWxKCKVD/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.3C4uO6YRRd/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:08:09 INFO - MultiFileLogger online at 20161220 12:08:09 in /builds/slave/test 12:08:09 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 12:08:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:09 INFO - {'append_to_log': False, 12:08:09 INFO - 'base_work_dir': '/builds/slave/test', 12:08:09 INFO - 'blob_upload_branch': 'Graphics', 12:08:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:09 INFO - 'branch': 'Graphics', 12:08:09 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:09 INFO - 'config_files': ('talos/mac_config.py',), 12:08:09 INFO - 'default_actions': ('clobber', 12:08:09 INFO - 'read-buildbot-config', 12:08:09 INFO - 'download-and-extract', 12:08:09 INFO - 'populate-webroot', 12:08:09 INFO - 'create-virtualenv', 12:08:09 INFO - 'install', 12:08:09 INFO - 'run-tests'), 12:08:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:09 INFO - 'download_minidump_stackwalk': True, 12:08:09 INFO - 'download_symbols': 'ondemand', 12:08:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:09 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:09 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:09 INFO - 'installer_path': 'installer.exe', 12:08:09 INFO - 'log_level': 'info', 12:08:09 INFO - 'log_name': 'talos', 12:08:09 INFO - 'log_to_console': True, 12:08:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:09 INFO - 'opt_config_files': (), 12:08:09 INFO - 'pip_index': False, 12:08:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:09 INFO - 'cmd': ('bash', 12:08:09 INFO - '-c', 12:08:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:09 INFO - 'enabled': True, 12:08:09 INFO - 'halt_on_failure': False, 12:08:09 INFO - 'name': 'check_screen_resolution'},), 12:08:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:09 INFO - 'cmd': ('bash', 12:08:09 INFO - '-c', 12:08:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:09 INFO - 'enabled': True, 12:08:09 INFO - 'halt_on_failure': False, 12:08:09 INFO - 'name': 'check_screen_resolution'},), 12:08:09 INFO - 'run_cmd_checks_enabled': True, 12:08:09 INFO - 'sps_profile': False, 12:08:09 INFO - 'sps_profile_interval': 0, 12:08:09 INFO - 'suite': 'dromaeojs-e10s', 12:08:09 INFO - 'system_bits': '32', 12:08:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:09 INFO - 'title': 't-yosemite-r7-0201', 12:08:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:09 INFO - 'use_talos_json': True, 12:08:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:09 INFO - 'work_dir': 'build'} 12:08:09 INFO - [mozharness: 2016-12-20 20:08:09.788391Z] Running clobber step. 12:08:09 INFO - Running pre-action listener: _resource_record_pre_action 12:08:09 INFO - Running main action method: clobber 12:08:09 INFO - rmtree: /builds/slave/test/build 12:08:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:12 INFO - Running post-action listener: _resource_record_post_action 12:08:12 INFO - [mozharness: 2016-12-20 20:08:12.456268Z] Finished clobber step (success) 12:08:12 INFO - [mozharness: 2016-12-20 20:08:12.456438Z] Running read-buildbot-config step. 12:08:12 INFO - Running pre-action listener: _resource_record_pre_action 12:08:12 INFO - Running main action method: read_buildbot_config 12:08:12 INFO - Using buildbot properties: 12:08:12 INFO - { 12:08:12 INFO - "project": "", 12:08:12 INFO - "product": "firefox", 12:08:12 INFO - "script_repo_revision": "default", 12:08:12 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 12:08:12 INFO - "repository": "", 12:08:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 12:08:12 INFO - "buildid": "20161220094753", 12:08:12 INFO - "pgo_build": "False", 12:08:12 INFO - "basedir": "/builds/slave/test", 12:08:12 INFO - "buildnumber": 3, 12:08:12 INFO - "slavename": "t-yosemite-r7-0201", 12:08:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:08:12 INFO - "platform": "yosemite_r7", 12:08:12 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 12:08:12 INFO - "branch": "graphics", 12:08:12 INFO - "repo_path": "projects/graphics", 12:08:12 INFO - "revision": "fe6d772d34c2d6ef137210a797d04d0f62da7927", 12:08:12 INFO - "stage_platform": "macosx64", 12:08:12 INFO - "builduid": "a4e3aa4b78634420b404ae07f72a87c4", 12:08:12 INFO - "slavebuilddir": "test" 12:08:12 INFO - } 12:08:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:08:12 INFO - Running post-action listener: _resource_record_post_action 12:08:12 INFO - [mozharness: 2016-12-20 20:08:12.461955Z] Finished read-buildbot-config step (success) 12:08:12 INFO - [mozharness: 2016-12-20 20:08:12.462154Z] Running download-and-extract step. 12:08:12 INFO - Running pre-action listener: _resource_record_pre_action 12:08:12 INFO - Running main action method: download_and_extract 12:08:12 INFO - mkdir: /builds/slave/test/build/tests 12:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:12 INFO - trying https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:08:12 INFO - Downloading https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 12:08:13 INFO - Downloaded 1199 bytes. 12:08:13 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:08:13 INFO - Using the following test package requirements: 12:08:13 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:08:13 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:08:13 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:08:13 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'jsshell-mac.zip'], 12:08:13 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:08:13 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:08:13 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:08:13 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:08:13 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:08:13 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:08:13 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:08:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:08:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:08:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:13 INFO - Fetch https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:08:14 INFO - Expected file size: 16276035 12:08:14 INFO - Obtained file size: 16276035 12:08:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:08:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:20 INFO - Fetch https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:08:21 INFO - Expected file size: 16276035 12:08:21 INFO - Obtained file size: 16276035 12:08:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:08:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:08:28 INFO - Fetch https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:08:29 INFO - Expected file size: 14409734 12:08:29 INFO - Obtained file size: 14409734 12:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:29 INFO - trying https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:08:29 INFO - Downloading https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 12:08:31 INFO - Downloaded 69661709 bytes. 12:08:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:08:31 INFO - mkdir: /builds/slave/test/properties 12:08:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:31 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:31 INFO - Contents: 12:08:31 INFO - build_url:https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:08:32 INFO - Running post-action listener: _resource_record_post_action 12:08:32 INFO - Running post-action listener: set_extra_try_arguments 12:08:32 INFO - [mozharness: 2016-12-20 20:08:32.170494Z] Finished download-and-extract step (success) 12:08:32 INFO - [mozharness: 2016-12-20 20:08:32.170636Z] Running populate-webroot step. 12:08:32 INFO - Running pre-action listener: _resource_record_pre_action 12:08:32 INFO - Running main action method: populate_webroot 12:08:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:32 INFO - '--tppagecycles', 12:08:32 INFO - '7'], 12:08:32 INFO - 'tests': ['tsvgm']}}, 12:08:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:08:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['damp', 'tps']}, 12:08:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'tests': ['damp', 'tps']}, 12:08:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['dromaeo_dom']}, 12:08:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['basic_compositor_video']}, 12:08:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:08:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['a11yr', 12:08:32 INFO - 'ts_paint', 12:08:32 INFO - 'tpaint', 12:08:32 INFO - 'sessionrestore', 12:08:32 INFO - 'sessionrestore_no_auto_restore', 12:08:32 INFO - 'tabpaint']}, 12:08:32 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:32 INFO - 'ts_paint', 12:08:32 INFO - 'tpaint', 12:08:32 INFO - 'sessionrestore', 12:08:32 INFO - 'sessionrestore_no_auto_restore', 12:08:32 INFO - 'tabpaint']}, 12:08:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:32 INFO - 'ts_paint', 12:08:32 INFO - 'tpaint', 12:08:32 INFO - 'sessionrestore', 12:08:32 INFO - 'sessionrestore_no_auto_restore', 12:08:32 INFO - 'tabpaint']}, 12:08:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:32 INFO - 'ts_paint', 12:08:32 INFO - 'tpaint', 12:08:32 INFO - 'sessionrestore', 12:08:32 INFO - 'sessionrestore_no_auto_restore', 12:08:32 INFO - 'tabpaint']}, 12:08:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['a11yr', 12:08:32 INFO - 'ts_paint', 12:08:32 INFO - 'tpaint', 12:08:32 INFO - 'sessionrestore', 12:08:32 INFO - 'sessionrestore_no_auto_restore', 12:08:32 INFO - 'tabpaint']}, 12:08:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['a11yr', 12:08:32 INFO - 'ts_paint', 12:08:32 INFO - 'tpaint', 12:08:32 INFO - 'sessionrestore', 12:08:32 INFO - 'sessionrestore_no_auto_restore', 12:08:32 INFO - 'tabpaint']}, 12:08:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['tsvgx', 12:08:32 INFO - 'tsvgr_opacity', 12:08:32 INFO - 'tart', 12:08:32 INFO - 'tscrollx', 12:08:32 INFO - 'cart', 12:08:32 INFO - 'tsvg_static']}, 12:08:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:32 INFO - 'tsvgr_opacity', 12:08:32 INFO - 'tart', 12:08:32 INFO - 'tscrollx', 12:08:32 INFO - 'cart', 12:08:32 INFO - 'tsvg_static']}, 12:08:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'talos_options': ['--disable-e10s'], 12:08:32 INFO - 'tests': ['tp5o']}, 12:08:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:08:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'talos_options': ['--disable-e10s', 12:08:32 INFO - '--xperf_path', 12:08:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:32 INFO - 'tests': ['tp5n']}, 12:08:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:08:32 INFO - 'talos_options': ['--xperf_path', 12:08:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:32 INFO - 'tests': ['tp5n']}}, 12:08:32 INFO - 'talos.zip': {'path': '', 12:08:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:32 INFO - Running post-action listener: _resource_record_post_action 12:08:32 INFO - [mozharness: 2016-12-20 20:08:32.189129Z] Finished populate-webroot step (success) 12:08:32 INFO - [mozharness: 2016-12-20 20:08:32.189261Z] Running create-virtualenv step. 12:08:32 INFO - Running pre-action listener: _resource_record_pre_action 12:08:32 INFO - Running main action method: create_virtualenv 12:08:32 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:08:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:32 INFO - Using real prefix '/tools/python27' 12:08:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:33 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:35 INFO - Installing pip.................done. 12:08:35 INFO - Return code: 0 12:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:08:36 INFO - Reading from file tmpfile_stdout 12:08:36 INFO - Output received: 12:08:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:08:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:36 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:36 INFO - 'HOME': '/Users/cltbld', 12:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:36 INFO - 'LOGNAME': 'cltbld', 12:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:36 INFO - 'MOZ_NO_REMOTE': '1', 12:08:36 INFO - 'NO_EM_RESTART': '1', 12:08:36 INFO - 'PAGER': '/bin/cat', 12:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:36 INFO - 'PWD': '/builds/slave/test', 12:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:36 INFO - 'SHELL': '/bin/bash', 12:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:36 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:36 INFO - 'USER': 'cltbld', 12:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:36 INFO - 'XPC_FLAGS': '0x0', 12:08:36 INFO - 'XPC_SERVICE_NAME': '0', 12:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:08:36 INFO - Cleaning up... 12:08:36 INFO - Return code: 0 12:08:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:36 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:36 INFO - 'HOME': '/Users/cltbld', 12:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:36 INFO - 'LOGNAME': 'cltbld', 12:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:36 INFO - 'MOZ_NO_REMOTE': '1', 12:08:36 INFO - 'NO_EM_RESTART': '1', 12:08:36 INFO - 'PAGER': '/bin/cat', 12:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:36 INFO - 'PWD': '/builds/slave/test', 12:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:36 INFO - 'SHELL': '/bin/bash', 12:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:36 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:36 INFO - 'USER': 'cltbld', 12:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:36 INFO - 'XPC_FLAGS': '0x0', 12:08:36 INFO - 'XPC_SERVICE_NAME': '0', 12:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:36 INFO - Downloading/unpacking psutil>=3.1.1 12:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:08:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:40 INFO - Installing collected packages: psutil 12:08:40 INFO - Running setup.py install for psutil 12:08:40 INFO - building 'psutil._psutil_osx' extension 12:08:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:08:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:08:41 INFO - building 'psutil._psutil_posix' extension 12:08:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:08:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:08:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:08:41 INFO - ^ 12:08:41 INFO - 1 warning generated. 12:08:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:08:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:41 INFO - Successfully installed psutil 12:08:41 INFO - Cleaning up... 12:08:41 INFO - Return code: 0 12:08:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:41 INFO - 'HOME': '/Users/cltbld', 12:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:41 INFO - 'LOGNAME': 'cltbld', 12:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:41 INFO - 'MOZ_NO_REMOTE': '1', 12:08:41 INFO - 'NO_EM_RESTART': '1', 12:08:41 INFO - 'PAGER': '/bin/cat', 12:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:41 INFO - 'PWD': '/builds/slave/test', 12:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:41 INFO - 'SHELL': '/bin/bash', 12:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:41 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:41 INFO - 'USER': 'cltbld', 12:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:41 INFO - 'XPC_FLAGS': '0x0', 12:08:41 INFO - 'XPC_SERVICE_NAME': '0', 12:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:08:44 INFO - Installing collected packages: mozsystemmonitor 12:08:44 INFO - Running setup.py install for mozsystemmonitor 12:08:44 INFO - Successfully installed mozsystemmonitor 12:08:44 INFO - Cleaning up... 12:08:44 INFO - Return code: 0 12:08:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:44 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:44 INFO - 'HOME': '/Users/cltbld', 12:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:44 INFO - 'LOGNAME': 'cltbld', 12:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:44 INFO - 'MOZ_NO_REMOTE': '1', 12:08:44 INFO - 'NO_EM_RESTART': '1', 12:08:44 INFO - 'PAGER': '/bin/cat', 12:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:45 INFO - 'PWD': '/builds/slave/test', 12:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:45 INFO - 'SHELL': '/bin/bash', 12:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:45 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:45 INFO - 'USER': 'cltbld', 12:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:45 INFO - 'XPC_FLAGS': '0x0', 12:08:45 INFO - 'XPC_SERVICE_NAME': '0', 12:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:45 INFO - Downloading/unpacking jsonschema==2.5.1 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08:48 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:48 INFO - warning: no files found matching '*.txt' 12:08:48 INFO - no previously-included directories found matching 'build' 12:08:48 INFO - no previously-included directories found matching 'dist' 12:08:48 INFO - no previously-included directories found matching '.git*' 12:08:48 INFO - Installing collected packages: jsonschema, functools32 12:08:48 INFO - Running setup.py install for functools32 12:08:48 INFO - warning: no files found matching '*.txt' 12:08:48 INFO - no previously-included directories found matching 'build' 12:08:48 INFO - no previously-included directories found matching 'dist' 12:08:48 INFO - no previously-included directories found matching '.git*' 12:08:48 INFO - Successfully installed jsonschema functools32 12:08:48 INFO - Cleaning up... 12:08:48 INFO - Return code: 0 12:08:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:48 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:48 INFO - 'HOME': '/Users/cltbld', 12:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:48 INFO - 'LOGNAME': 'cltbld', 12:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:48 INFO - 'MOZ_NO_REMOTE': '1', 12:08:48 INFO - 'NO_EM_RESTART': '1', 12:08:48 INFO - 'PAGER': '/bin/cat', 12:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:48 INFO - 'PWD': '/builds/slave/test', 12:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:48 INFO - 'SHELL': '/bin/bash', 12:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:48 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:48 INFO - 'USER': 'cltbld', 12:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:48 INFO - 'XPC_FLAGS': '0x0', 12:08:48 INFO - 'XPC_SERVICE_NAME': '0', 12:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:48 INFO - Cleaning up... 12:08:48 INFO - Return code: 0 12:08:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:48 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:48 INFO - 'HOME': '/Users/cltbld', 12:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:48 INFO - 'LOGNAME': 'cltbld', 12:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:48 INFO - 'MOZ_NO_REMOTE': '1', 12:08:48 INFO - 'NO_EM_RESTART': '1', 12:08:48 INFO - 'PAGER': '/bin/cat', 12:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:48 INFO - 'PWD': '/builds/slave/test', 12:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:48 INFO - 'SHELL': '/bin/bash', 12:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:48 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:48 INFO - 'USER': 'cltbld', 12:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:48 INFO - 'XPC_FLAGS': '0x0', 12:08:48 INFO - 'XPC_SERVICE_NAME': '0', 12:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:49 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:08:52 INFO - Downloading docopt-0.6.1.tar.gz 12:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:53 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:53 INFO - Running setup.py install for blobuploader 12:08:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:53 INFO - Running setup.py install for requests 12:08:53 INFO - Running setup.py install for docopt 12:08:53 INFO - Successfully installed blobuploader requests docopt 12:08:53 INFO - Cleaning up... 12:08:53 INFO - Return code: 0 12:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:54 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:54 INFO - 'HOME': '/Users/cltbld', 12:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:54 INFO - 'LOGNAME': 'cltbld', 12:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:54 INFO - 'MOZ_NO_REMOTE': '1', 12:08:54 INFO - 'NO_EM_RESTART': '1', 12:08:54 INFO - 'PAGER': '/bin/cat', 12:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:54 INFO - 'PWD': '/builds/slave/test', 12:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:54 INFO - 'SHELL': '/bin/bash', 12:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:54 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:54 INFO - 'USER': 'cltbld', 12:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:54 INFO - 'XPC_FLAGS': '0x0', 12:08:54 INFO - 'XPC_SERVICE_NAME': '0', 12:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-nECShb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-fFXEDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-0v87Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-UPnlJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-xsdf6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-IEvazq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:54 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-2Qjl2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4SqNgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-9tgFKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-KLV5AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-pv8Teb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-SNaF9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-AFG9cW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-VZrr_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ehKZ4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Dk935L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:55 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-mLxOcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:56 INFO - Running setup.py install for manifestparser 12:08:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Running setup.py install for mozcrash 12:08:56 INFO - Running setup.py install for mozdebug 12:08:56 INFO - Running setup.py install for mozdevice 12:08:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Running setup.py install for mozfile 12:08:56 INFO - Running setup.py install for mozhttpd 12:08:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Running setup.py install for mozinfo 12:08:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozInstall 12:08:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozleak 12:08:57 INFO - Running setup.py install for mozlog 12:08:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for moznetwork 12:08:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:57 INFO - Running setup.py install for mozprocess 12:08:57 INFO - Running setup.py install for mozprofile 12:08:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Running setup.py install for mozrunner 12:08:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Running setup.py install for mozscreenshot 12:08:58 INFO - Running setup.py install for moztest 12:08:58 INFO - Running setup.py install for mozversion 12:08:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:58 INFO - Cleaning up... 12:08:58 INFO - Return code: 0 12:08:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:58 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:58 INFO - 'HOME': '/Users/cltbld', 12:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:58 INFO - 'LOGNAME': 'cltbld', 12:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:58 INFO - 'MOZ_NO_REMOTE': '1', 12:08:58 INFO - 'NO_EM_RESTART': '1', 12:08:58 INFO - 'PAGER': '/bin/cat', 12:08:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:58 INFO - 'PWD': '/builds/slave/test', 12:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:58 INFO - 'SHELL': '/bin/bash', 12:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:08:58 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:58 INFO - 'USER': 'cltbld', 12:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:58 INFO - 'XPC_FLAGS': '0x0', 12:08:58 INFO - 'XPC_SERVICE_NAME': '0', 12:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:58 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-u4mgUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-AJo3PZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-x0ynkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-2W7yQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4X5nv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-OpeWVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ynvX58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-zQbgIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-xJl46u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:59 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-lH8Jrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-AqVblY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-4tdHEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-WywI7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-nWQGWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-6ifPbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-C3VZ8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:00 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)) 12:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:00 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-vDbpEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:03 INFO - Downloading blessings-1.6.tar.gz 12:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:03 INFO - Installing collected packages: blessings 12:09:03 INFO - Running setup.py install for blessings 12:09:03 INFO - Successfully installed blessings 12:09:03 INFO - Cleaning up... 12:09:03 INFO - Return code: 0 12:09:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:04 INFO - Reading from file tmpfile_stdout 12:09:04 INFO - Current package versions: 12:09:04 INFO - blessings == 1.6 12:09:04 INFO - blobuploader == 1.2.4 12:09:04 INFO - docopt == 0.6.1 12:09:04 INFO - functools32 == 3.2.3-2 12:09:04 INFO - jsonschema == 2.5.1 12:09:04 INFO - manifestparser == 1.1 12:09:04 INFO - mozInstall == 1.12 12:09:04 INFO - mozcrash == 1.0 12:09:04 INFO - mozdebug == 0.1 12:09:04 INFO - mozdevice == 0.49 12:09:04 INFO - mozfile == 1.2 12:09:04 INFO - mozhttpd == 0.7 12:09:04 INFO - mozinfo == 0.9 12:09:04 INFO - mozleak == 0.1 12:09:04 INFO - mozlog == 3.4 12:09:04 INFO - moznetwork == 0.27 12:09:04 INFO - mozprocess == 0.23 12:09:04 INFO - mozprofile == 0.28 12:09:04 INFO - mozrunner == 6.13 12:09:04 INFO - mozscreenshot == 0.1 12:09:04 INFO - mozsystemmonitor == 0.3 12:09:04 INFO - moztest == 0.7 12:09:04 INFO - mozversion == 1.4 12:09:04 INFO - psutil == 3.1.1 12:09:04 INFO - requests == 1.2.3 12:09:04 INFO - wsgiref == 0.1.2 12:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:04 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:04 INFO - 'HOME': '/Users/cltbld', 12:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:04 INFO - 'LOGNAME': 'cltbld', 12:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:04 INFO - 'MOZ_NO_REMOTE': '1', 12:09:04 INFO - 'NO_EM_RESTART': '1', 12:09:04 INFO - 'PAGER': '/bin/cat', 12:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:04 INFO - 'PWD': '/builds/slave/test', 12:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:04 INFO - 'SHELL': '/bin/bash', 12:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:09:04 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:04 INFO - 'USER': 'cltbld', 12:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:04 INFO - 'XPC_FLAGS': '0x0', 12:09:04 INFO - 'XPC_SERVICE_NAME': '0', 12:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:04 INFO - Cleaning up... 12:09:04 INFO - Return code: 0 12:09:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:04 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 0x10ec89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea3b56cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, '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.udGWxKCKVD/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.3C4uO6YRRd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:04 INFO - 'HOME': '/Users/cltbld', 12:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:04 INFO - 'LOGNAME': 'cltbld', 12:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:04 INFO - 'MOZ_NO_REMOTE': '1', 12:09:04 INFO - 'NO_EM_RESTART': '1', 12:09:04 INFO - 'PAGER': '/bin/cat', 12:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:04 INFO - 'PWD': '/builds/slave/test', 12:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:04 INFO - 'SHELL': '/bin/bash', 12:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:09:04 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:04 INFO - 'USER': 'cltbld', 12:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:04 INFO - 'XPC_FLAGS': '0x0', 12:09:04 INFO - 'XPC_SERVICE_NAME': '0', 12:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:04 INFO - Cleaning up... 12:09:04 INFO - Return code: 0 12:09:04 INFO - Running post-action listener: _resource_record_post_action 12:09:04 INFO - Running post-action listener: _start_resource_monitoring 12:09:04 INFO - Starting resource monitoring. 12:09:04 INFO - [mozharness: 2016-12-20 20:09:04.613978Z] Finished create-virtualenv step (success) 12:09:04 INFO - [mozharness: 2016-12-20 20:09:04.614961Z] Running install step. 12:09:04 INFO - Running pre-action listener: _resource_record_pre_action 12:09:04 INFO - Running main action method: install 12:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:04 INFO - Reading from file tmpfile_stdout 12:09:04 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:04 INFO - Reading from file tmpfile_stdout 12:09:04 INFO - Output received: 12:09:04 INFO - Usage: mozinstall [options] installer 12:09:04 INFO - Options: 12:09:04 INFO - -h, --help show this help message and exit 12:09:04 INFO - -d DEST, --destination=DEST 12:09:04 INFO - Directory to install application into. [default: 12:09:04 INFO - "/builds/slave/test"] 12:09:04 INFO - --app=APP Application being installed. [default: firefox] 12:09:04 INFO - mkdir: /builds/slave/test/build/application 12:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:09:27 INFO - Reading from file tmpfile_stdout 12:09:27 INFO - Output received: 12:09:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:09:27 INFO - Running post-action listener: _resource_record_post_action 12:09:27 INFO - [mozharness: 2016-12-20 20:09:27.823394Z] Finished install step (success) 12:09:27 INFO - [mozharness: 2016-12-20 20:09:27.823553Z] Running run-tests step. 12:09:27 INFO - Running pre-action listener: _resource_record_pre_action 12:09:27 INFO - Running main action method: run_tests 12:09:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:27 INFO - 2016-12-20 12:09:27.838 screenresolution[1632:9294] starting screenresolution argv=screenresolution get 12:09:27 INFO - 2016-12-20 12:09:27.840 screenresolution[1632:9294] Display 0: 1600x1200x32@60 12:09:27 INFO - 2016-12-20 12:09:27.846 screenresolution[1633:9296] starting screenresolution argv=screenresolution list 12:09:27 INFO - Available Modes on Display 0 12:09:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:28 INFO - Intel Iris: 12:09:28 INFO - Chipset Model: Intel Iris 12:09:28 INFO - Type: GPU 12:09:28 INFO - Bus: Built-In 12:09:28 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:28 INFO - Vendor: Intel (0x8086) 12:09:28 INFO - Device ID: 0x0a2e 12:09:28 INFO - Revision ID: 0x0009 12:09:28 INFO - Displays: 12:09:28 INFO - Display: 12:09:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:28 INFO - Display Serial Number: 1600x1200 60 12:09:28 INFO - Main Display: Yes 12:09:28 INFO - Mirror: Off 12:09:28 INFO - Online: Yes 12:09:28 INFO - Rotation: Supported 12:09:28 INFO - Return code: 0 12:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:28 INFO - Python 2.7.3 12:09:28 INFO - Return code: 0 12:09:28 INFO - grabbing minidump binary from tooltool 12:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:28 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 0x10ea67d30>, '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 0x10e98edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea5cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:09:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:09:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:09:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:28 INFO - Return code: 0 12:09:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:09:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:09:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:28 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/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:09:28 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/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0201 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:09:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udGWxKCKVD/Render', 12:09:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:28 INFO - 'HOME': '/Users/cltbld', 12:09:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:28 INFO - 'LOGNAME': 'cltbld', 12:09:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:28 INFO - 'MOZ_NO_REMOTE': '1', 12:09:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:28 INFO - 'NO_EM_RESTART': '1', 12:09:28 INFO - 'PAGER': '/bin/cat', 12:09:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:28 INFO - 'PWD': '/builds/slave/test', 12:09:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:28 INFO - 'SHELL': '/bin/bash', 12:09:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3C4uO6YRRd/Listeners', 12:09:28 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 12:09:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:28 INFO - 'USER': 'cltbld', 12:09:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:28 INFO - 'XPC_FLAGS': '0x0', 12:09:28 INFO - 'XPC_SERVICE_NAME': '0', 12:09:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:28 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/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:09:28 INFO - mozversion application_buildid: 20161220094753 12:09:28 INFO - mozversion application_changeset: fe6d772d34c2d6ef137210a797d04d0f62da7927 12:09:28 INFO - mozversion application_display_name: Nightly 12:09:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:28 INFO - mozversion application_name: Firefox 12:09:28 INFO - mozversion application_remotingname: firefox 12:09:28 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:09:28 INFO - mozversion application_vendor: Mozilla 12:09:28 INFO - mozversion application_version: 53.0a1 12:09:28 INFO - mozversion platform_buildid: 20161220094753 12:09:28 INFO - mozversion platform_changeset: fe6d772d34c2d6ef137210a797d04d0f62da7927 12:09:28 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:09:28 INFO - mozversion platform_version: 53.0a1 12:09:28 INFO - using testdate: 1482264568 12:09:28 INFO - actual date: 1482264568 12:09:28 INFO - starting webserver on 'localhost:49241' 12:09:28 INFO - SUITE-START | Running 2 tests 12:09:28 INFO - TEST-START | dromaeo_css 12:09:28 INFO - operating with platform_type : mac_ 12:09:28 INFO - Initialising browser for dromaeo_css test... 12:09:28 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpWO9WVk/profile http://localhost:49241/getInfo.html) 12:09:31 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 12:09:31 INFO - PROCESS | 1643 | colorDepth:24 12:09:31 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 12:09:31 INFO - PROCESS | 1643 | __metrics 12:09:31 INFO - PROCESS | 1643 | 2016-12-20 12:09:31.425 plugin-container[1645:9463] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 12:09:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:31 INFO - PROCESS | 1643 | 2016-12-20 12:09:31.428 plugin-container[1645:9463] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 12:09:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:31 INFO - PROCESS | 1643 | 2016-12-20 12:09:31.429 plugin-container[1645:9463] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:31 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 12:09:32 INFO - TEST-INFO | 1643: exit 0 12:09:32 INFO - Browser initialized. 12:09:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:32 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpWO9WVk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:34 INFO - PROCESS | 1647 | 2016-12-20 12:09:34.689 plugin-container[1648:9587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 12:09:34 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:34 INFO - PROCESS | 1647 | 2016-12-20 12:09:34.690 plugin-container[1648:9587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 12:09:34 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:09:34 INFO - PROCESS | 1647 | 2016-12-20 12:09:34.690 plugin-container[1648:9587] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:09:44 INFO - PROCESS | 1647 | RSS: Main: 236834816 12:09:44 INFO - PROCESS | 1647 | 12:10:34 INFO - PROCESS | 1647 | 1482264634011 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] 12:12:47 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 12:12:49 INFO - PROCESS | 1647 | RSS: Main: 253030400 12:12:49 INFO - PROCESS | 1647 | 12:15:51 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 12:15:52 INFO - PROCESS | 1647 | RSS: Main: 264896512 12:15:52 INFO - PROCESS | 1647 | 12:18:54 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 12:18:55 INFO - PROCESS | 1647 | RSS: Main: 269041664 12:18:55 INFO - PROCESS | 1647 | 12:21:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 12:21:57 INFO - PROCESS | 1647 | RSS: Main: 268607488 12:21:57 INFO - PROCESS | 1647 | 12:25:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 12:25:01 INFO - PROCESS | 1647 | RSS: Main: 274100224 12:25:01 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 12:28:04 INFO - PROCESS | 1647 | RSS: Main: 276242432 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | __start_tp_report 12:28:04 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 12:28:04 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 12:28:04 INFO - PROCESS | 1647 | |i|pagename|runs| 12:28:04 INFO - PROCESS | 1647 | |0;dojo.html;2852.1871266689;3011.412774508971;3356.7146792522635;3395.6943875051256;3460.809655468949;5678.290213723285;5727.885442291152;5766.135079738036;5812.1572372006085;5827.912581311284;5310.548603368708;5787.536997040237;5830.154627579009;5890.852728681774;5916.2308899843065;5274.630775845692;5287.312649355578;5295.338082739658;5305.522502974741;5310.256564081013;5258.079836028699;5304.177852432879;5314.202869569557;5355.3305836770405;5385.703786291752;5255.054090263751;5276.577873770089;5299.311089558352;5310.70791106489;5364.570834333244;5168.457311982263;5206.661566998133;5210.426853046162;5235.55497782689;5258.894822103574;5200.427952925175;5245.527902488756;5254.264402983586;5262;5339.252504649352;5354.437784032699;5629.4652008059165;5668.461496157858;5710.7430165642645;5797.594168408213;4791.329213910055;5398.974194902956;5540;5726.055200891848;6227.813165605039;6195;6242.219722534684;6257.061440783918;6263.154474146023;6274.372562743734;5886.676232807194;5928.835974869142;5945.137954996501;5990.5507086968655;6004.099385092271;6160.661163636043;6294.59040045216;6412.743490260337;6429.03564465334;6431.614103153825;6113.358097399798;6116.29662588808;6134.222293197073;6144.41628045335;6198.318184999646;4890.633202509826;6285.43431091204;6291.496680265568;6293.496520278367;6306.463950564162;6048.818535443944;6097.390260973956;6139.216166136444;6163.921313770073;6216.689165541705;5639.605227634026;6209.565330426917;6300.054991751183;6310.305866354697;6321.52588555851;4640.79116439761;4886.780094895738;4941.308216849644;4949.430815456197;4956.925646115311;4448.154850578379;4465.972826249981;4474.552544745565;4475.776211189493;4517.847948773042;5933.813237352547;5982.132590774313;5983.940160598426;5988;6007.939920600738;6165.630062196283;6213.409726076015;6235.625862448268;6306.990881458944;6353.841153971188;5859.472647461749;5950.851228719317;5954.136650185699;6005.849853753691;6024;5455.236266922634;5479;5486.91769623456;5495.752691128909;5496.587755918322;6147.815565533041;6284.277308109626;6389.456896163878;6393.936060639334;6403.839904002437;6207.410296022052;6229.719662615103;6257.655828929453;6279.529035322461;6288.937110628835;6356.173697419306;6361.936380636134;6366.936330636634;6367.649779262185;6376.330485299162;6100.847478812888;6265.248170219603;6270.839894619327;6283.49732021428;6296.685165741603;6141.723622437091;6212.534059945612;6213.2233470816145;6233.594816336924;6322.715477803418;6165.722542485689;6178.505719542516;6234.812955611339;6247.5001999840815;6250.749970001149;4986.8753281168265;5074.858156914813;5148.4851514848215;6236.501079913687;6265.028920517327;5375.709829640936;5865.3254875689845;5871.2073870027;6007.399260074132;6094.51243900478;6181.196444462191;6183.536234782319;6221.813345599639;6236.812895613139;6244.562880598314;5882.029465138205;5888.941110588839;6081.087836824512;6108.08378743192;6125.418085281891 12:28:04 INFO - PROCESS | 1647 | |1;ext.html;429.5682838747059;446.3973635591951;484.2639188433581;508.1031978557844;510.3136281701112;39213.80393098034;40870.59129408705;40996.38505422421;43229.7838510807;43304.78347608258;41387;43157.568424315745;43407.56592434082;43520.347194792106;43706.7814660927;8452.8732069019;8703.825923481527;9322.06779322069;9513.476758778299;9551.952240238823;9373.578188981479;11416.942915285454;11430.42847857608;11540.65378038656;11607.361595112265;11379.772404551904;11432.942835285812;11434.313941163515;11438.02776763976;11453.083753299716;11260;11311.773764524705;11313.886861131448;11335;11339.943300283447;11251.268667536666;11348.602798901999;11349.375784331858;11367.545298188063;11392.886071139266;40945.38581921274;41009.179816403805;41017;41337.58662413368;41359.552415665305;3352.513885486591;3438.209211881281;3608.6932610728118;3819;3926.0969976905185;30636.54045189324;31104.222394440094;31150.84424577885;31806.681933180836;31869.681303186902;3000.6249218847643;3127.9061628151194;3235.5470234167237;3291.0949488890747;3311.6853898879567;481175;487609;492836;493259;496634.5168274172;56477.71761141168;56852.71573642105;57212.14181787276;57284;57385.426145738005;59831;59872.10191847126;60018;60093.39906600965;60264.397356026755;15325.923370383076;15383.307751151226;15562.922185389227;15581.766273505906;15610.921945390428;10909.781804363869;11087.168462365355;11841.526338946345;12076.818847717292;12088.516459341528;17616.823831761518;17645.470635880945;17722.02528860925;17766.378176763752;17851;7654;7707.421943354271;7745.92254077452;7769.223077692299;7825.530468171928;844.7550210439027;921.9121436704753;924.246738907788;929.9256059515358;953.5708930981086;29552;29919.700802992127;29979.100626981224;30497.085087447413;31084;994.636957510518;1044.6082718980383;1052.0058544675207;1082.3127314155568;1105.3588918427292;14389.856101438852;15173.317200725995;15779.763303550455;15802.288896999433;15883;582756.2586561205;616675;634298;638823;651588;55094.449055509736;61483.6925815377;61514.69242653758;61538;61607.691961540804;1344.8587898270737;1370.5751217122524;1398.1052126638958;1401.1032938918895;1402.9298535073206;27393.45213095687;29853.34573594292;30308;30530.84734576313;30789.92235271755;52983.73508132589;53031;53091.20363194632;53164;53261;543733;547686;548094.2595286998;551846;552374;23522.64716029227;24084.63873041871;25477.490450191264;25782.226533204033;26919.461610767285;44517.10965780731;46987;47144.7642761784;48076.519234808606;48339.74903764464;31769.888053918003;36429.63570364263;39028.219435611696;39440.60559394484;40259.3961090578;38520.6147938517;39062.63280785159;39134.412983805836;40145.59854401535;40592.594074058885;37941.430878537394;38302.042448939;38440.231195376495;38848.22303553857;38918;32156.03531894047;33538.664613353554;33722.156946076546;34573.65426345704;34587.654123458444;23420.88289558541;25220.369490762143;25256.97944164499;25449;25778.226653200432 12:28:04 INFO - PROCESS | 1647 | |2;jquery.html;370.62751934306016;376.3564305038384;384.5001498052531;390.4358202397537;401.25967589796835;17148.571285717833;18675.71986420205;19966.50083747907;20237.494062648442;20242.59514809703;15405.845941540581;16433.506994790176;19702.014899255053;20003.899980500115;20183.29358472454;4971.254311853215;5852.619579727326;5884.941150588504;6091.0558863376245;6094.60385074971;5884.558658100638;6022.156898034257;6031.487323577501;6042.516598672096;6042.758289668408;5676.2620859288445;5948.1969934059;5961.701914904259;5983.491403230731;6000.009998350267;6054.5459090568165;6062.42406971337;6110.3889611038985;6121.234845644294;6131.3868613138775;4468.798904049293;5717.313392549208;5780.971095144498;5862.824115276549;5897.262842144732;12571.371431428392;12833.294168820712;12845.357732113356;13015.219086854724;13027.739445211042;2136.337735302046;2157.7410710714657;2368.052778888441;2528.4943011397795;2777.88888444464;9645.08371704687;9650.662226822027;9830.606775728962;10084.899151008543;10237.129843963272;1773.3704534890187;1843.6957901946162;1849.0754622688655;1864.189077751169;1870.9719354209699;97863;126305;130223;141674.1665166701;142979.28510357486;19445.027748612512;20231.696524552142;21087.78912210858;21393.358199254046;21862.89068554647;30054;30062.098137055924;30069.699303007128;30080.849595751883;30113.397732045676;8709.38876307885;10369.74075648115;10733.195010374253;10746;10890.72773180661;6952.687129079203;7328.633568321669;8804.647814087493;8815.426997245158;8990.056044115405;8356.916430835736;8500;9360.251179905592;9660.903390966141;9970.30207885441;3409.3522230776075;3682.3187710273687;3844.59631738669;3852.9422058668583;3910.452536644872;372.9776213427149;810.7405630198398;876.6756300168978;889.4929889962659;889.857622780352;11245.775084498264;11699.824502632297;12018.519259229708;12093.093018023683;12099.213551119148;422.0925011225889;849.6728759427541;884.2483888694558;915.441580635825;932.067932067932;10228.590856365661;10397.68806935778;10789.946050269698;10800.24398292112;10845.783084338427;461849;477113;490863;496829;498890;111198;119432;124813.50372992671;126830;130711.34644326718;1001.6093723447862;1022.9693109206736;1040.9167266618804;1044.0446991003298;1044.4203467075845;14305.642358940693;18162.54593635117;19373.934433606286;19465;19477.707834382778;43690.34464483099;50227.49772502228;53080.73459632677;53360.73319633377;53865;71650.64174679093;73566.63216683883;73907;74380.62809686133;74881.62559187169;6928.376446119873;8040.638171282189;8053.919460805317;8094.51432914027;8206.63070161856;10904.236703430684;10955.671329860117;12516.998640108588;12696.555620553236;12981.935090324489;9421.670241541513;10292.588296468357;10913.217129631894;11046.558137674403;11140.832887506533;10826.025657690845;10857.782844342912;10995.395253261147;11144.721381965515;11198.272112312674;8823.470591764515;10931;11282.605108821152;11283.887161128283;11425.2002359834;6339.302676705466;7890.210978902064;8061.1938806118915;8205.753827385188;8312.41813073079;9115.635374584943;9219.677311294072;9383.086068235067;9754.219662426847;11237.932396422328 12:28:04 INFO - PROCESS | 1647 | |3;mootools.html;1463.070949946784;1530.8239552451464;1751.7197248440243;1879.323443560319;1912.3689182569744;7568.281013303727;7810.3751699864015;8071.394645401597;8504.48973061616;8536.189062039111;6990.335918087773;8053.91946080539;8139.430239883211;8303.66785328586;8492.66029358825;2267.4331417145713;2313.745487996319;2330.277613939684;2331.5103828196097;2333.2416964486524;2309.872956987365;2338.9240949163404;2361.633946738258;2362.799162071226;2363.4800343924394;2157.6332023555956;2269.897854596547;2285.531466049456;2293.5298263855952;2310.9422264443356;1791.7401976713434;1906.389955214325;1912.6652835753825;2251.448395143184;2274.692916456274;1926.6532024235632;2286.588414085464;2329.557384097016;2339.4502291961376;2347.3896786835544;8433;8443.746687599381;8517.148285171434;8554.743357699279;8556.272716819016;5073.315102461157;5240.292560504321;5242.2136679498;5256.737163141827;5259.500347466985;3379.459286514146;3400.2009527760956;3617.1318883468043;3623.1304486923214;3624;1037.7976294622551;1057.9896199129812;1078.0997866781247;1083.6153165626167;1089.2865173311493;10063.792344918656;10875;10964.835527467094;11007.889921100847;11064.944675276653;4459.264221403497;4566.862994110182;4629.722216667011;4648.279516674921;4667.556582124693;7391.778246652609;9464.905350946403;9487.193588544913;9487.241020718326;9488.620455181854;5229.163333866563;5229.555487783581;5232.293640358579;5235.162374020215;5241.554467870197;4289.270823959934;4314.158739045919;4751.64362672801;4824.324594556765;4830.227163653869;3754.9436758448646;3833.9616603833606;3863.9806800965816;3883.0680636647567;3914.451976723261;1282.8204051432806;1289.9296628910652;1295.7602843473987;1306.392527474729;1309.541660418865;806.8628333183373;871.407442938795;887.4675194883018;910.8724778531011;940.6331530703031;5391.487808658171;5666.206731057655;5716.599838011382;5805.506531944832;5961.433663801932;943.6602822983721;944.8393773058597;947.5404428852002;962.8590120706984;1014.9035841595037;3170.270837707294;3698.3158115748674;3803.5245594300713;3844.1350696093155;3910.843566257318;9818.509074546244;10275.7431064224;10279.17766578672;10301.587936482607;10311.587536498606;10112.544935477921;10199.694009179737;10200.132988695896;10225.897741022643;10240.48797560134;7660.348870345972;7845.646945887449;7887.723929662549;7969.163237859942;8023;5298.28473156119;5511.559075274049;5551.777928882961;5577.77688892456;5643.576731745054;9368.859467108136;9382.29632777558;9397.013313601974;9404.623815047322;9408.01215872351;9046.140616641409;9492.050794920453;9550.617975280911;9781.266405019509;9784.51077446139;6707.798766037026;6723.663816809042;6765.221999470124;6780.898286525608;6825.35159159879;7820.17888121739;7895.249951254622;7896.565688887166;7902.209779022051;7902.604869756374;6941.556255322616;6986.441084713109;7140.143182818095;7190.460715446257;7299.014633024686;5954.702264886825;6034.883546543903;7082.504224704221;7104.431645468453;7149.0706208192605;5712.514436272797;6551.672416379067;6660.134182556237;6707.765228216989;6783.626900520519;4818.325434439252;4866.975665121652;4871.318015477765;4914.877128071684;4975.577075948585;4031.737937034083;4817.686850354716;4828.420589529279;5021.698698078127;5089.745512724275 12:28:04 INFO - PROCESS | 1647 | |4;prototype.html;298.7580060151277;353.51347093114856;358.4032585744735;363.78354878847085;366.8330909436207;5191.5327620514145;5246.37043554773;5331.520163185312;5359.928014397122;5567.9999999999945;4721.575058244757;5063.696178229309;5202.583793296537;5326.520613144816;5346.973265133679;3729.888103356897;3923.960150560104;4248.745075295476;4249.44757181566;4298.527162012176;3297.29108241728;3462.7229821614337;3477.4436090225568;3972.1459886124476;4268.801567192898;4206.221848957937;4224.97887510562;4238;4245.80893859777;4249.150169966004;3546.308469848381;3586.55168103987;4226.640735537468;4226.830926762926;4256.34002738932;3649;3770.509833721626;3776.206996530732;3809.3524100902914;3822.503074600312;4777.976110119427;4786.593139583139;5126.794928202868;5257.395399529065;5425.9728701356635;4791.873909631234;4892.559669629715;4914.754262286871;4931;4943.431505376891;4637;5464.863378415572;5698.971505142489;5720.427957204288;5750.539956803445;3645.398509245971;3692.8522859085874;4114.403411505315;4147.170565886805;4171.791410429466;5978.8505287367725;6042.728077236535;6048.637081775108;6069.2110025696375;6089.969550152221;3628.82063329419;3726.7018638508853;3782.7730336179916;3864.2271545690974;4277.315629499265;3475.1138459692615;3725.6646901778972;4058.7271128168322;4181.059261666101;4253.3194688849635;4634.632783328926;4866.7079975201605;4877.244027175793;4891.6575839691595;4902.876494063712;5683.8010669626365;5718.311688960597;5733.885322293614;5753.539716822645;5799.18811366409;1264.879836415539;1293.9611811645666;1337.555440124663;1387.9097858639157;1407.7536431124513;953.1612181280428;983.5967253426061;984.7685793838442;987.9901200987898;994.6220436234184;4494.0675509530565;4977.950220497749;5141.897162056738;5145.742712864342;5221.911235505828;4724.543152658684;5027.82402615914;5544.91682624761;5594.944050559524;5659;4393.758343291086;4462.68350836504;4626.768661566908;4975.477574854661;5152.793888244429;2759.3030286373737;2848.572714092861;2907.941841163165;2934.339773550934;2935.2074939766144;4617.699849509771;4820.71075735457;4852.44196917359;5382.703951282639;5431.484009019216;5071.619628527875;5170.3278573785165;5343.5190832824455;5706.920840452001;5763.366029736726;5445.700486473203;5548.750306236229;5719.113537401709;5795.4647664925205;5829.09649004405;4797.616190704665;4949.7030178189425;5088.669236499616;5441.863953401196;5619.466150715675;4939.580135688363;5265.842024739264;5437.673739575638;5578;5665.518430933417;5104.642675012862;5588.993981083444;5765.4811067004175;5846.970765146147;5849.415058494116;3879.3599056155417;3968.5832987537046;4026.355783074694;4040.3535434330365;4074.3888416737727;4775.498572649822;5432;5433.794061503731;5754.856128596819;5844.444777746107;4643.373144625432;4978.925316120333;5320.866978325573;5512.649400896806;5646.604737668324;4921.803127874988;5135.38375394955;5721.628094173865;5780.913286300793;5805.506531944832;5575.470330318613;5582.581306401955;5588.217649529151;5596.580256480699;5622.493975542221;4890.60103228253;5019.7490125494305;5700.118960743028;5714.742836572281;5767.625104368202;4716.20784102039;5157.865269640673;5291.682499050069;5590.2670172246735;5644.8024319148635 12:28:04 INFO - PROCESS | 1647 | |5;yui.html;685.6366125953246;727.7125535413516;743.6504842723917;786.8701664225404;796.5539297993124;14290.571282861518;15365;15374.154421506813;15389.846101538982;15419;13849.792253116211;14008.0194386393;14042.157470551774;14052.859471405283;14085.295735213223;583.7285662167092;641.2785616181795;709.4111887133693;824.9381296402764;848.8811566380712;744.2929217243613;868.9000764912053;873.3319010956624;875.9255463285597;891.7057371067565;924.6809850601531;929.363386080537;930.4463844012802;930.8976012638639;937.7327461673425;784.8861915022347;915.0666320353203;929.1359036096428;930.5207817973749;935.4387367579466;670.3263220463451;680.8672308899767;689.5793565924782;693.0782059860373;694.7360003198803;14882.479113231093;15154.31805568741;15306.234688265542;15374.769378459332;15429.537113886601;14112.50606228777;14481.855181448262;14634;14676.19280939548;14750.33623486935;11789.705257368549;11799.115066370057;11891.643250702493;11896.643100706993;11978.70053248667;3677.2645470905927;3705.6664900159117;3740.420234863573;3763.0592351912023;3857.267119247362;91277.50308714516;112779;114422.99999999917;115886;116013;9821.901780982098;9824.312298139204;9838.950805246071;9902.207823374112;10347.379157250589;22136.88931555332;22343.776562234496;22529.32412027642;22569.774302257094;22590.322290331318;7793.298603125746;7836;7843.764687059397;7862.174471680506;8011.038675358877;4240.406343111967;4343.565643435694;4344.391785150082;4360.716553424018;4382.474103107647;3506.7545271831236;3603.4234522476277;3608.4948107265004;3613.9638603614153;3618.3667858124722;451.2959782738872;535.8151437753969;553.1647212708839;563.2677519225073;575.6689903305532;499.04183966783864;585.8857522783017;621.9004959206505;660.5838321857199;672.6213438578783;9125.589348479334;9176.770580735401;9212.447253164832;9513.714588562354;9543.47510886908;589.8938191125638;656.5273003437518;660.4650233311033;673.373762400962;697.5396238482577;4206.47419072616;4537.137943790735;4606.055778479177;4637.814487420465;4667.789949452215;76793;90604.54697726601;94457;95016.09967800605;95559.52220238994;84035.31929361502;85175;85539;86582.13417865867;88623;79718.40563188704;80010.79983800248;80056.39887202224;80067;82464.58767706124;7854.3323817473865;7997.280244777765;8139.3488520917;8824.558772061242;8943.776405589651;43537.78231108824;44384;52848.207276890105;53015.87936482652;53501.19748203858;54447.18329225144;74656;74798;76221;77703;11779.52881884715;11880.524779008743;11893.583724569598;12190.75618487643;12352.691182720504;12052.51789928393;12322.815157772462;12660.124428215666;12957.22256664603;13018.739625207632;9910.603575856885;11670.824937626112;11833.644990650295;12415.813762793385;12422.813657795321;11859.58491452795;11939.164258501822;11946.522139114339;11948.701282468008;12070.758584828078;11686.123540734648;11965.76068478608;12318.384080795746;12368.072394570263;12369.62891113268;9922.355046921926;10032.347897386646;10037.598496060076;10106.696799096038;10576.84134737964;10062.647807326708;10313.1233845124;10340.53467593975;10348.120409764953;10349.223808214565 12:28:04 INFO - PROCESS | 1647 | __end_tp_report 12:28:04 INFO - PROCESS | 1647 | __start_cc_report 12:28:04 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,373 12:28:04 INFO - PROCESS | 1647 | __end_cc_report 12:28:04 INFO - PROCESS | 1647 | __startTimestamp1482265684572__endTimestamp 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | ------- Summary: start ------- 12:28:04 INFO - PROCESS | 1647 | Number of tests: 6 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5743.82 Median:5983.94 stddev:671.34 (11.2%) stddev-sans-first:633.91 12:28:04 INFO - PROCESS | 1647 | Values: 2852.2 3011.4 3356.7 3395.7 3460.8 5678.3 5727.9 5766.1 5812.2 5827.9 5310.5 5787.5 5830.2 5890.9 5916.2 5274.6 5287.3 5295.3 5305.5 5310.3 5258.1 5304.2 5314.2 5355.3 5385.7 5255.1 5276.6 5299.3 5310.7 5364.6 5168.5 5206.7 5210.4 5235.6 5258.9 5200.4 5245.5 5254.3 5262.0 5339.3 5354.4 5629.5 5668.5 5710.7 5797.6 4791.3 5399.0 5540.0 5726.1 6227.8 6195.0 6242.2 6257.1 6263.2 6274.4 5886.7 5928.8 5945.1 5990.6 6004.1 6160.7 6294.6 6412.7 6429.0 6431.6 6113.4 6116.3 6134.2 6144.4 6198.3 4890.6 6285.4 6291.5 6293.5 6306.5 6048.8 6097.4 6139.2 6163.9 6216.7 5639.6 6209.6 6300.1 6310.3 6321.5 4640.8 4886.8 4941.3 4949.4 4956.9 4448.2 4466.0 4474.6 4475.8 4517.8 5933.8 5982.1 5983.9 5988.0 6007.9 6165.6 6213.4 6235.6 6307.0 6353.8 5859.5 5950.9 5954.1 6005.8 6024.0 5455.2 5479.0 5486.9 5495.8 5496.6 6147.8 6284.3 6389.5 6393.9 6403.8 6207.4 6229.7 6257.7 6279.5 6288.9 6356.2 6361.9 6366.9 6367.6 6376.3 6100.8 6265.2 6270.8 6283.5 6296.7 6141.7 6212.5 6213.2 6233.6 6322.7 6165.7 6178.5 6234.8 6247.5 6250.7 4986.9 5074.9 5148.5 6236.5 6265.0 5375.7 5865.3 5871.2 6007.4 6094.5 6181.2 6183.5 6221.8 6236.8 6244.6 5882.0 5888.9 6081.1 6108.1 6125.4 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:69178.66 Median:38371.14 stddev:148764.44 (387.7%) stddev-sans-first:149092.22 12:28:04 INFO - PROCESS | 1647 | Values: 429.6 446.4 484.3 508.1 510.3 39213.8 40870.6 40996.4 43229.8 43304.8 41387.0 43157.6 43407.6 43520.3 43706.8 8452.9 8703.8 9322.1 9513.5 9552.0 9373.6 11416.9 11430.4 11540.7 11607.4 11379.8 11432.9 11434.3 11438.0 11453.1 11260.0 11311.8 11313.9 11335.0 11339.9 11251.3 11348.6 11349.4 11367.5 11392.9 40945.4 41009.2 41017.0 41337.6 41359.6 3352.5 3438.2 3608.7 3819.0 3926.1 30636.5 31104.2 31150.8 31806.7 31869.7 3000.6 3127.9 3235.5 3291.1 3311.7 481175.0 487609.0 492836.0 493259.0 496634.5 56477.7 56852.7 57212.1 57284.0 57385.4 59831.0 59872.1 60018.0 60093.4 60264.4 15325.9 15383.3 15562.9 15581.8 15610.9 10909.8 11087.2 11841.5 12076.8 12088.5 17616.8 17645.5 17722.0 17766.4 17851.0 7654.0 7707.4 7745.9 7769.2 7825.5 844.8 921.9 924.2 929.9 953.6 29552.0 29919.7 29979.1 30497.1 31084.0 994.6 1044.6 1052.0 1082.3 1105.4 14389.9 15173.3 15779.8 15802.3 15883.0 582756.3 616675.0 634298.0 638823.0 651588.0 55094.4 61483.7 61514.7 61538.0 61607.7 1344.9 1370.6 1398.1 1401.1 1402.9 27393.5 29853.3 30308.0 30530.8 30789.9 52983.7 53031.0 53091.2 53164.0 53261.0 543733.0 547686.0 548094.3 551846.0 552374.0 23522.6 24084.6 25477.5 25782.2 26919.5 44517.1 46987.0 47144.8 48076.5 48339.7 31769.9 36429.6 39028.2 39440.6 40259.4 38520.6 39062.6 39134.4 40145.6 40592.6 37941.4 38302.0 38440.2 38848.2 38918.0 32156.0 33538.7 33722.2 34573.7 34587.7 23420.9 25220.4 25257.0 25449.0 25778.2 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:31930.47 Median:2026.47 stddev:82319.02 (4062.2%) stddev-sans-first:82515.84 12:28:04 INFO - PROCESS | 1647 | Values: 370.6 376.4 384.5 390.4 401.3 17148.6 18675.7 19966.5 20237.5 20242.6 15405.8 16433.5 19702.0 20003.9 20183.3 4971.3 5852.6 5884.9 6091.1 6094.6 5884.6 6022.2 6031.5 6042.5 6042.8 5676.3 5948.2 5961.7 5983.5 6000.0 6054.5 6062.4 6110.4 6121.2 6131.4 4468.8 5717.3 5781.0 5862.8 5897.3 12571.4 12833.3 12845.4 13015.2 13027.7 2136.3 2157.7 2368.1 2528.5 2777.9 9645.1 9650.7 9830.6 10084.9 10237.1 1773.4 1843.7 1849.1 1864.2 1871.0 97863.0 126305.0 130223.0 141674.2 142979.3 19445.0 20231.7 21087.8 21393.4 21862.9 30054.0 30062.1 30069.7 30080.8 30113.4 8709.4 10369.7 10733.2 10746.0 10890.7 6952.7 7328.6 8804.6 8815.4 8990.1 8356.9 8500.0 9360.3 9660.9 9970.3 3409.4 3682.3 3844.6 3852.9 3910.5 373.0 810.7 876.7 889.5 889.9 11245.8 11699.8 12018.5 12093.1 12099.2 422.1 849.7 884.2 915.4 932.1 10228.6 10397.7 10789.9 10800.2 10845.8 461849.0 477113.0 490863.0 496829.0 498890.0 111198.0 119432.0 124813.5 126830.0 130711.3 1001.6 1023.0 1040.9 1044.0 1044.4 14305.6 18162.5 19373.9 19465.0 19477.7 43690.3 50227.5 53080.7 53360.7 53865.0 71650.6 73566.6 73907.0 74380.6 74881.6 6928.4 8040.6 8053.9 8094.5 8206.6 10904.2 10955.7 12517.0 12696.6 12981.9 9421.7 10292.6 10913.2 11046.6 11140.8 10826.0 10857.8 10995.4 11144.7 11198.3 8823.5 10931.0 11282.6 11283.9 11425.2 6339.3 7890.2 8061.2 8205.8 8312.4 9115.6 9219.7 9383.1 9754.2 11237.9 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5423.65 Median:5159.45 stddev:2978.61 (57.7%) stddev-sans-first:2972.10 12:28:04 INFO - PROCESS | 1647 | Values: 1463.1 1530.8 1751.7 1879.3 1912.4 7568.3 7810.4 8071.4 8504.5 8536.2 6990.3 8053.9 8139.4 8303.7 8492.7 2267.4 2313.7 2330.3 2331.5 2333.2 2309.9 2338.9 2361.6 2362.8 2363.5 2157.6 2269.9 2285.5 2293.5 2310.9 1791.7 1906.4 1912.7 2251.4 2274.7 1926.7 2286.6 2329.6 2339.5 2347.4 8433.0 8443.7 8517.1 8554.7 8556.3 5073.3 5240.3 5242.2 5256.7 5259.5 3379.5 3400.2 3617.1 3623.1 3624.0 1037.8 1058.0 1078.1 1083.6 1089.3 10063.8 10875.0 10964.8 11007.9 11064.9 4459.3 4566.9 4629.7 4648.3 4667.6 7391.8 9464.9 9487.2 9487.2 9488.6 5229.2 5229.6 5232.3 5235.2 5241.6 4289.3 4314.2 4751.6 4824.3 4830.2 3754.9 3834.0 3864.0 3883.1 3914.5 1282.8 1289.9 1295.8 1306.4 1309.5 806.9 871.4 887.5 910.9 940.6 5391.5 5666.2 5716.6 5805.5 5961.4 943.7 944.8 947.5 962.9 1014.9 3170.3 3698.3 3803.5 3844.1 3910.8 9818.5 10275.7 10279.2 10301.6 10311.6 10112.5 10199.7 10200.1 10225.9 10240.5 7660.3 7845.6 7887.7 7969.2 8023.0 5298.3 5511.6 5551.8 5577.8 5643.6 9368.9 9382.3 9397.0 9404.6 9408.0 9046.1 9492.1 9550.6 9781.3 9784.5 6707.8 6723.7 6765.2 6780.9 6825.4 7820.2 7895.2 7896.6 7902.2 7902.6 6941.6 6986.4 7140.1 7190.5 7299.0 5954.7 6034.9 7082.5 7104.4 7149.1 5712.5 6551.7 6660.1 6707.8 6783.6 4818.3 4867.0 4871.3 4914.9 4975.6 4031.7 4817.7 4828.4 5021.7 5089.7 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4536.34 Median:4976.71 stddev:1346.43 (27.1%) stddev-sans-first:1312.10 12:28:04 INFO - PROCESS | 1647 | Values: 298.8 353.5 358.4 363.8 366.8 5191.5 5246.4 5331.5 5359.9 5568.0 4721.6 5063.7 5202.6 5326.5 5347.0 3729.9 3924.0 4248.7 4249.4 4298.5 3297.3 3462.7 3477.4 3972.1 4268.8 4206.2 4225.0 4238.0 4245.8 4249.2 3546.3 3586.6 4226.6 4226.8 4256.3 3649.0 3770.5 3776.2 3809.4 3822.5 4778.0 4786.6 5126.8 5257.4 5426.0 4791.9 4892.6 4914.8 4931.0 4943.4 4637.0 5464.9 5699.0 5720.4 5750.5 3645.4 3692.9 4114.4 4147.2 4171.8 5978.9 6042.7 6048.6 6069.2 6090.0 3628.8 3726.7 3782.8 3864.2 4277.3 3475.1 3725.7 4058.7 4181.1 4253.3 4634.6 4866.7 4877.2 4891.7 4902.9 5683.8 5718.3 5733.9 5753.5 5799.2 1264.9 1294.0 1337.6 1387.9 1407.8 953.2 983.6 984.8 988.0 994.6 4494.1 4978.0 5141.9 5145.7 5221.9 4724.5 5027.8 5544.9 5594.9 5659.0 4393.8 4462.7 4626.8 4975.5 5152.8 2759.3 2848.6 2907.9 2934.3 2935.2 4617.7 4820.7 4852.4 5382.7 5431.5 5071.6 5170.3 5343.5 5706.9 5763.4 5445.7 5548.8 5719.1 5795.5 5829.1 4797.6 4949.7 5088.7 5441.9 5619.5 4939.6 5265.8 5437.7 5578.0 5665.5 5104.6 5589.0 5765.5 5847.0 5849.4 3879.4 3968.6 4026.4 4040.4 4074.4 4775.5 5432.0 5433.8 5754.9 5844.4 4643.4 4978.9 5320.9 5512.6 5646.6 4921.8 5135.4 5721.6 5780.9 5805.5 5575.5 5582.6 5588.2 5596.6 5622.5 4890.6 5019.7 5700.1 5714.7 5767.6 4716.2 5157.9 5291.7 5590.3 5644.8 12:28:04 INFO - PROCESS | 1647 | 12:28:04 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20010.73 Median:2583.42 stddev:29142.75 (1128.1%) stddev-sans-first:29188.38 12:28:04 INFO - PROCESS | 1647 | Values: 685.6 727.7 743.7 786.9 796.6 14290.6 15365.0 15374.2 15389.8 15419.0 13849.8 14008.0 14042.2 14052.9 14085.3 583.7 641.3 709.4 824.9 848.9 744.3 868.9 873.3 875.9 891.7 924.7 929.4 930.4 930.9 937.7 784.9 915.1 929.1 930.5 935.4 670.3 680.9 689.6 693.1 694.7 14882.5 15154.3 15306.2 15374.8 15429.5 14112.5 14481.9 14634.0 14676.2 14750.3 11789.7 11799.1 11891.6 11896.6 11978.7 3677.3 3705.7 3740.4 3763.1 3857.3 91277.5 112779.0 114423.0 115886.0 116013.0 9821.9 9824.3 9839.0 9902.2 10347.4 22136.9 22343.8 22529.3 22569.8 22590.3 7793.3 7836.0 7843.8 7862.2 8011.0 4240.4 4343.6 4344.4 4360.7 4382.5 3506.8 3603.4 3608.5 3614.0 3618.4 451.3 535.8 553.2 563.3 575.7 499.0 585.9 621.9 660.6 672.6 9125.6 9176.8 9212.4 9513.7 9543.5 589.9 656.5 660.5 673.4 697.5 4206.5 4537.1 4606.1 4637.8 4667.8 76793.0 90604.5 94457.0 95016.1 95559.5 84035.3 85175.0 85539.0 86582.1 88623.0 79718.4 80010.8 80056.4 80067.0 82464.6 7854.3 7997.3 8139.3 8824.6 8943.8 43537.8 44384.0 52848.2 53015.9 53501.2 54447.2 74656.0 74798.0 76221.0 77703.0 11779.5 11880.5 11893.6 12190.8 12352.7 12052.5 12322.8 12660.1 12957.2 13018.7 9910.6 11670.8 11833.6 12415.8 12422.8 11859.6 11939.2 11946.5 11948.7 12070.8 11686.1 11965.8 12318.4 12368.1 12369.6 9922.4 10032.3 10037.6 10106.7 10576.8 10062.6 10313.1 10340.5 10348.1 10349.2 12:28:04 INFO - PROCESS | 1647 | -------- Summary: end -------- 12:28:04 INFO - PROCESS | 1647 | 12:28:05 INFO - TEST-INFO | 1647: exit 0 12:28:05 INFO - TEST-OK | dromaeo_css | took 1116794ms 12:28:05 INFO - TEST-START | kraken 12:28:05 INFO - operating with platform_type : mac_ 12:28:05 INFO - Initialising browser for kraken test... 12:28:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpUaeQEt/profile http://localhost:49241/getInfo.html) 12:28:07 INFO - PROCESS | 1655 | 2016-12-20 12:28:07.976 plugin-container[1656:11743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 12:28:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:07 INFO - PROCESS | 1655 | 2016-12-20 12:28:07.979 plugin-container[1656:11743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 12:28:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:07 INFO - PROCESS | 1655 | 2016-12-20 12:28:07.979 plugin-container[1656:11743] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:08 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:28:08 INFO - PROCESS | 1655 | colorDepth:24 12:28:08 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:28:08 INFO - PROCESS | 1655 | __metrics 12:28:08 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 12:28:08 INFO - TEST-INFO | 1655: exit 0 12:28:08 INFO - Browser initialized. 12:28:08 INFO - Running cycle 1/1 for kraken test... 12:28:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpUaeQEt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:11 INFO - PROCESS | 1657 | 2016-12-20 12:28:11.390 plugin-container[1658:11863] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 12:28:11 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:11 INFO - PROCESS | 1657 | 2016-12-20 12:28:11.391 plugin-container[1658:11863] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:28:11 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:11 INFO - PROCESS | 1657 | 2016-12-20 12:28:11.391 plugin-container[1658:11863] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:20 INFO - PROCESS | 1657 | RSS: Main: 236822528 12:28:20 INFO - PROCESS | 1657 | 12:28:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:24 INFO - PROCESS | 1657 | RSS: Main: 249544704 12:28:24 INFO - PROCESS | 1657 | 12:28:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 12:28:27 INFO - PROCESS | 1657 | RSS: Main: 257122304 12:28:27 INFO - PROCESS | 1657 | 12:28:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 12:28:31 INFO - PROCESS | 1657 | RSS: Main: 259956736 12:28:31 INFO - PROCESS | 1657 | 12:28:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 12:28:34 INFO - PROCESS | 1657 | RSS: Main: 261148672 12:28:34 INFO - PROCESS | 1657 | 12:28:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:36 INFO - PROCESS | 1657 | RSS: Main: 261537792 12:28:36 INFO - PROCESS | 1657 | 12:28:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:40 INFO - PROCESS | 1657 | RSS: Main: 268574720 12:28:40 INFO - PROCESS | 1657 | 12:28:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:44 INFO - PROCESS | 1657 | RSS: Main: 268595200 12:28:44 INFO - PROCESS | 1657 | 12:28:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 12:28:47 INFO - PROCESS | 1657 | RSS: Main: 267972608 12:28:47 INFO - PROCESS | 1657 | 12:28:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:50 INFO - PROCESS | 1657 | RSS: Main: 266903552 12:28:50 INFO - PROCESS | 1657 | 12:28:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:28:52 INFO - PROCESS | 1657 | RSS: Main: 266989568 12:28:52 INFO - PROCESS | 1657 | 12:28:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:28:55 INFO - PROCESS | 1657 | RSS: Main: 267005952 12:28:55 INFO - PROCESS | 1657 | 12:28:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:28:59 INFO - PROCESS | 1657 | RSS: Main: 267288576 12:28:59 INFO - PROCESS | 1657 | 12:29:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:03 INFO - PROCESS | 1657 | RSS: Main: 267313152 12:29:03 INFO - PROCESS | 1657 | 12:29:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 12:29:05 INFO - PROCESS | 1657 | RSS: Main: 268017664 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | __start_tp_report 12:29:05 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 12:29:05 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 12:29:05 INFO - PROCESS | 1657 | |i|pagename|runs| 12:29:05 INFO - PROCESS | 1657 | |0;ai-astar;116;121;118;119;121;119;118;118;119;124 12:29:05 INFO - PROCESS | 1657 | |1;audio-beat-detection;155;140;143;152;113;114;114;113;131;111 12:29:05 INFO - PROCESS | 1657 | |2;audio-dft;195;196;208;230;196;194;194;196;196;192 12:29:05 INFO - PROCESS | 1657 | |3;audio-fft;85;60;60;86;63;59;59;59;70;60 12:29:05 INFO - PROCESS | 1657 | |4;audio-oscillator;76;71;85;72;69;70;83;71;72;82 12:29:05 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;120;117;116;115;116;117;135;122;113;120 12:29:05 INFO - PROCESS | 1657 | |6;imaging-darkroom;99;99;99;134;108;152;135;150;139;98 12:29:05 INFO - PROCESS | 1657 | |7;imaging-desaturate;93;95;93;93;122;149;144;125;93;93 12:29:05 INFO - PROCESS | 1657 | |8;json-parse-financial;49;49;51;57;48;50;50;48;48;71 12:29:05 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;70;73;104;79;97;69;84;62;88;107 12:29:05 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;100;80;95;115;110;104;91;90;92 12:29:05 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;160;141;156;155;149;155;135;160;218;168 12:29:05 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;234;238;182;179;173;172;229;231;175;172 12:29:05 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;69;66;68;69;67;68;69;83;91 12:29:05 INFO - PROCESS | 1657 | __end_tp_report 12:29:05 INFO - PROCESS | 1657 | __start_cc_report 12:29:05 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,946 12:29:05 INFO - PROCESS | 1657 | __end_cc_report 12:29:05 INFO - PROCESS | 1657 | __startTimestamp1482265745220__endTimestamp 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | ------- Summary: start ------- 12:29:05 INFO - PROCESS | 1657 | Number of tests: 14 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:119.30 Median:119.00 stddev:2.21 (1.9%) stddev-sans-first:2.00 12:29:05 INFO - PROCESS | 1657 | Values: 116.0 121.0 118.0 119.0 121.0 119.0 118.0 118.0 119.0 124.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:135.50 stddev:17.67 (13.0%) stddev-sans-first:15.95 12:29:05 INFO - PROCESS | 1657 | Values: 155.0 140.0 143.0 152.0 113.0 114.0 114.0 113.0 131.0 111.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:199.70 Median:196.00 stddev:11.49 (5.9%) stddev-sans-first:12.06 12:29:05 INFO - PROCESS | 1657 | Values: 195.0 196.0 208.0 230.0 196.0 194.0 194.0 196.0 196.0 192.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:66.10 Median:61.50 stddev:10.75 (17.5%) stddev-sans-first:8.97 12:29:05 INFO - PROCESS | 1657 | Values: 85.0 60.0 60.0 86.0 63.0 59.0 59.0 59.0 70.0 60.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:74.00 stddev:6.01 (8.1%) stddev-sans-first:6.36 12:29:05 INFO - PROCESS | 1657 | Values: 76.0 71.0 85.0 72.0 69.0 70.0 83.0 71.0 72.0 82.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:119.10 Median:118.50 stddev:6.19 (5.2%) stddev-sans-first:6.56 12:29:05 INFO - PROCESS | 1657 | Values: 120.0 117.0 116.0 115.0 116.0 117.0 135.0 122.0 113.0 120.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:121.30 Median:125.00 stddev:22.71 (18.2%) stddev-sans-first:22.60 12:29:05 INFO - PROCESS | 1657 | Values: 99.0 99.0 99.0 134.0 108.0 152.0 135.0 150.0 139.0 98.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:110.00 Median:93.00 stddev:22.89 (24.6%) stddev-sans-first:23.44 12:29:05 INFO - PROCESS | 1657 | Values: 93.0 95.0 93.0 93.0 122.0 149.0 144.0 125.0 93.0 93.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:50.00 stddev:7.16 (14.3%) stddev-sans-first:7.50 12:29:05 INFO - PROCESS | 1657 | Values: 49.0 49.0 51.0 57.0 48.0 50.0 50.0 48.0 48.0 71.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:83.30 Median:76.00 stddev:15.49 (20.4%) stddev-sans-first:15.67 12:29:05 INFO - PROCESS | 1657 | Values: 70.0 73.0 104.0 79.0 97.0 69.0 84.0 62.0 88.0 107.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:96.60 Median:89.50 stddev:10.63 (11.9%) stddev-sans-first:10.91 12:29:05 INFO - PROCESS | 1657 | Values: 89.0 100.0 80.0 95.0 115.0 110.0 104.0 91.0 90.0 92.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:159.70 Median:158.00 stddev:22.61 (14.3%) stddev-sans-first:23.98 12:29:05 INFO - PROCESS | 1657 | Values: 160.0 141.0 156.0 155.0 149.0 155.0 135.0 160.0 218.0 168.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.50 Median:205.50 stddev:29.94 (14.6%) stddev-sans-first:28.87 12:29:05 INFO - PROCESS | 1657 | Values: 234.0 238.0 182.0 179.0 173.0 172.0 229.0 231.0 175.0 172.0 12:29:05 INFO - PROCESS | 1657 | 12:29:05 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:69.00 stddev:8.28 (12.0%) stddev-sans-first:8.67 12:29:05 INFO - PROCESS | 1657 | Values: 68.0 69.0 66.0 68.0 69.0 67.0 68.0 69.0 83.0 91.0 12:29:05 INFO - PROCESS | 1657 | -------- Summary: end -------- 12:29:05 INFO - PROCESS | 1657 | 12:29:06 INFO - TEST-INFO | 1657: exit 0 12:29:06 INFO - TEST-OK | kraken | took 60863ms 12:29:06 INFO - SUITE-END | took 1177s 12:29:06 INFO - Completed test suite (00:19:37) 12:29:06 INFO - javascript benchmark 12:29:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7257.444640511974, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2852.1871266689, 3011.412774508971, 3356.7146792522635, 3395.6943875051256, 3460.809655468949, 5678.290213723285, 5727.885442291152, 5766.135079738036, 5812.1572372006085, 5827.912581311284, 5310.548603368708, 5787.536997040237, 5830.154627579009, 5890.852728681774, 5916.2308899843065, 5274.630775845692, 5287.312649355578, 5295.338082739658, 5305.522502974741, 5310.256564081013, 5258.079836028699, 5304.177852432879, 5314.202869569557, 5355.3305836770405, 5385.703786291752, 5255.054090263751, 5276.577873770089, 5299.311089558352, 5310.70791106489, 5364.570834333244, 5168.457311982263, 5206.661566998133, 5210.426853046162, 5235.55497782689, 5258.894822103574, 5200.427952925175, 5245.527902488756, 5254.264402983586, 5262.0, 5339.252504649352, 5354.437784032699, 5629.4652008059165, 5668.461496157858, 5710.7430165642645, 5797.594168408213, 4791.329213910055, 5398.974194902956, 5540.0, 5726.055200891848, 6227.813165605039, 6195.0, 6242.219722534684, 6257.061440783918, 6263.154474146023, 6274.372562743734, 5886.676232807194, 5928.835974869142, 5945.137954996501, 5990.5507086968655, 6004.099385092271, 6160.661163636043, 6294.59040045216, 6412.743490260337, 6429.03564465334, 6431.614103153825, 6113.358097399798, 6116.29662588808, 6134.222293197073, 6144.41628045335, 6198.318184999646, 4890.633202509826, 6285.43431091204, 6291.496680265568, 6293.496520278367, 6306.463950564162, 6048.818535443944, 6097.390260973956, 6139.216166136444, 6163.921313770073, 6216.689165541705, 5639.605227634026, 6209.565330426917, 6300.054991751183, 6310.305866354697, 6321.52588555851, 4640.79116439761, 4886.780094895738, 4941.308216849644, 4949.430815456197, 4956.925646115311, 4448.154850578379, 4465.972826249981, 4474.552544745565, 4475.776211189493, 4517.847948773042, 5933.813237352547, 5982.132590774313, 5983.940160598426, 5988.0, 6007.939920600738, 6165.630062196283, 6213.409726076015, 6235.625862448268, 6306.990881458944, 6353.841153971188, 5859.472647461749, 5950.851228719317, 5954.136650185699, 6005.849853753691, 6024.0, 5455.236266922634, 5479.0, 5486.91769623456, 5495.752691128909, 5496.587755918322, 6147.815565533041, 6284.277308109626, 6389.456896163878, 6393.936060639334, 6403.839904002437, 6207.410296022052, 6229.719662615103, 6257.655828929453, 6279.529035322461, 6288.937110628835, 6356.173697419306, 6361.936380636134, 6366.936330636634, 6367.649779262185, 6376.330485299162, 6100.847478812888, 6265.248170219603, 6270.839894619327, 6283.49732021428, 6296.685165741603, 6141.723622437091, 6212.534059945612, 6213.2233470816145, 6233.594816336924, 6322.715477803418, 6165.722542485689, 6178.505719542516, 6234.812955611339, 6247.5001999840815, 6250.749970001149, 4986.8753281168265, 5074.858156914813, 5148.4851514848215, 6236.501079913687, 6265.028920517327, 5375.709829640936, 5865.3254875689845, 5871.2073870027, 6007.399260074132, 6094.51243900478, 6181.196444462191, 6183.536234782319, 6221.813345599639, 6236.812895613139, 6244.562880598314, 5882.029465138205, 5888.941110588839, 6081.087836824512, 6108.08378743192, 6125.418085281891], "value": 5700.054535764649, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.5682838747059, 446.3973635591951, 484.2639188433581, 508.1031978557844, 510.3136281701112, 39213.80393098034, 40870.59129408705, 40996.38505422421, 43229.7838510807, 43304.78347608258, 41387.0, 43157.568424315745, 43407.56592434082, 43520.347194792106, 43706.7814660927, 8452.8732069019, 8703.825923481527, 9322.06779322069, 9513.476758778299, 9551.952240238823, 9373.578188981479, 11416.942915285454, 11430.42847857608, 11540.65378038656, 11607.361595112265, 11379.772404551904, 11432.942835285812, 11434.313941163515, 11438.02776763976, 11453.083753299716, 11260.0, 11311.773764524705, 11313.886861131448, 11335.0, 11339.943300283447, 11251.268667536666, 11348.602798901999, 11349.375784331858, 11367.545298188063, 11392.886071139266, 40945.38581921274, 41009.179816403805, 41017.0, 41337.58662413368, 41359.552415665305, 3352.513885486591, 3438.209211881281, 3608.6932610728118, 3819.0, 3926.0969976905185, 30636.54045189324, 31104.222394440094, 31150.84424577885, 31806.681933180836, 31869.681303186902, 3000.6249218847643, 3127.9061628151194, 3235.5470234167237, 3291.0949488890747, 3311.6853898879567, 481175.0, 487609.0, 492836.0, 493259.0, 496634.5168274172, 56477.71761141168, 56852.71573642105, 57212.14181787276, 57284.0, 57385.426145738005, 59831.0, 59872.10191847126, 60018.0, 60093.39906600965, 60264.397356026755, 15325.923370383076, 15383.307751151226, 15562.922185389227, 15581.766273505906, 15610.921945390428, 10909.781804363869, 11087.168462365355, 11841.526338946345, 12076.818847717292, 12088.516459341528, 17616.823831761518, 17645.470635880945, 17722.02528860925, 17766.378176763752, 17851.0, 7654.0, 7707.421943354271, 7745.92254077452, 7769.223077692299, 7825.530468171928, 844.7550210439027, 921.9121436704753, 924.246738907788, 929.9256059515358, 953.5708930981086, 29552.0, 29919.700802992127, 29979.100626981224, 30497.085087447413, 31084.0, 994.636957510518, 1044.6082718980383, 1052.0058544675207, 1082.3127314155568, 1105.3588918427292, 14389.856101438852, 15173.317200725995, 15779.763303550455, 15802.288896999433, 15883.0, 582756.2586561205, 616675.0, 634298.0, 638823.0, 651588.0, 55094.449055509736, 61483.6925815377, 61514.69242653758, 61538.0, 61607.691961540804, 1344.8587898270737, 1370.5751217122524, 1398.1052126638958, 1401.1032938918895, 1402.9298535073206, 27393.45213095687, 29853.34573594292, 30308.0, 30530.84734576313, 30789.92235271755, 52983.73508132589, 53031.0, 53091.20363194632, 53164.0, 53261.0, 543733.0, 547686.0, 548094.2595286998, 551846.0, 552374.0, 23522.64716029227, 24084.63873041871, 25477.490450191264, 25782.226533204033, 26919.461610767285, 44517.10965780731, 46987.0, 47144.7642761784, 48076.519234808606, 48339.74903764464, 31769.888053918003, 36429.63570364263, 39028.219435611696, 39440.60559394484, 40259.3961090578, 38520.6147938517, 39062.63280785159, 39134.412983805836, 40145.59854401535, 40592.594074058885, 37941.430878537394, 38302.042448939, 38440.231195376495, 38848.22303553857, 38918.0, 32156.03531894047, 33538.664613353554, 33722.156946076546, 34573.65426345704, 34587.654123458444, 23420.88289558541, 25220.369490762143, 25256.97944164499, 25449.0, 25778.226653200432], "value": 20149.799835313825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.62751934306016, 376.3564305038384, 384.5001498052531, 390.4358202397537, 401.25967589796835, 17148.571285717833, 18675.71986420205, 19966.50083747907, 20237.494062648442, 20242.59514809703, 15405.845941540581, 16433.506994790176, 19702.014899255053, 20003.899980500115, 20183.29358472454, 4971.254311853215, 5852.619579727326, 5884.941150588504, 6091.0558863376245, 6094.60385074971, 5884.558658100638, 6022.156898034257, 6031.487323577501, 6042.516598672096, 6042.758289668408, 5676.2620859288445, 5948.1969934059, 5961.701914904259, 5983.491403230731, 6000.009998350267, 6054.5459090568165, 6062.42406971337, 6110.3889611038985, 6121.234845644294, 6131.3868613138775, 4468.798904049293, 5717.313392549208, 5780.971095144498, 5862.824115276549, 5897.262842144732, 12571.371431428392, 12833.294168820712, 12845.357732113356, 13015.219086854724, 13027.739445211042, 2136.337735302046, 2157.7410710714657, 2368.052778888441, 2528.4943011397795, 2777.88888444464, 9645.08371704687, 9650.662226822027, 9830.606775728962, 10084.899151008543, 10237.129843963272, 1773.3704534890187, 1843.6957901946162, 1849.0754622688655, 1864.189077751169, 1870.9719354209699, 97863.0, 126305.0, 130223.0, 141674.1665166701, 142979.28510357486, 19445.027748612512, 20231.696524552142, 21087.78912210858, 21393.358199254046, 21862.89068554647, 30054.0, 30062.098137055924, 30069.699303007128, 30080.849595751883, 30113.397732045676, 8709.38876307885, 10369.74075648115, 10733.195010374253, 10746.0, 10890.72773180661, 6952.687129079203, 7328.633568321669, 8804.647814087493, 8815.426997245158, 8990.056044115405, 8356.916430835736, 8500.0, 9360.251179905592, 9660.903390966141, 9970.30207885441, 3409.3522230776075, 3682.3187710273687, 3844.59631738669, 3852.9422058668583, 3910.452536644872, 372.9776213427149, 810.7405630198398, 876.6756300168978, 889.4929889962659, 889.857622780352, 11245.775084498264, 11699.824502632297, 12018.519259229708, 12093.093018023683, 12099.213551119148, 422.0925011225889, 849.6728759427541, 884.2483888694558, 915.441580635825, 932.067932067932, 10228.590856365661, 10397.68806935778, 10789.946050269698, 10800.24398292112, 10845.783084338427, 461849.0, 477113.0, 490863.0, 496829.0, 498890.0, 111198.0, 119432.0, 124813.50372992671, 126830.0, 130711.34644326718, 1001.6093723447862, 1022.9693109206736, 1040.9167266618804, 1044.0446991003298, 1044.4203467075845, 14305.642358940693, 18162.54593635117, 19373.934433606286, 19465.0, 19477.707834382778, 43690.34464483099, 50227.49772502228, 53080.73459632677, 53360.73319633377, 53865.0, 71650.64174679093, 73566.63216683883, 73907.0, 74380.62809686133, 74881.62559187169, 6928.376446119873, 8040.638171282189, 8053.919460805317, 8094.51432914027, 8206.63070161856, 10904.236703430684, 10955.671329860117, 12516.998640108588, 12696.555620553236, 12981.935090324489, 9421.670241541513, 10292.588296468357, 10913.217129631894, 11046.558137674403, 11140.832887506533, 10826.025657690845, 10857.782844342912, 10995.395253261147, 11144.721381965515, 11198.272112312674, 8823.470591764515, 10931.0, 11282.605108821152, 11283.887161128283, 11425.2002359834, 6339.302676705466, 7890.210978902064, 8061.1938806118915, 8205.753827385188, 8312.41813073079, 9115.635374584943, 9219.677311294072, 9383.086068235067, 9754.219662426847, 11237.932396422328], "value": 9714.364071853302, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.070949946784, 1530.8239552451464, 1751.7197248440243, 1879.323443560319, 1912.3689182569744, 7568.281013303727, 7810.3751699864015, 8071.394645401597, 8504.48973061616, 8536.189062039111, 6990.335918087773, 8053.91946080539, 8139.430239883211, 8303.66785328586, 8492.66029358825, 2267.4331417145713, 2313.745487996319, 2330.277613939684, 2331.5103828196097, 2333.2416964486524, 2309.872956987365, 2338.9240949163404, 2361.633946738258, 2362.799162071226, 2363.4800343924394, 2157.6332023555956, 2269.897854596547, 2285.531466049456, 2293.5298263855952, 2310.9422264443356, 1791.7401976713434, 1906.389955214325, 1912.6652835753825, 2251.448395143184, 2274.692916456274, 1926.6532024235632, 2286.588414085464, 2329.557384097016, 2339.4502291961376, 2347.3896786835544, 8433.0, 8443.746687599381, 8517.148285171434, 8554.743357699279, 8556.272716819016, 5073.315102461157, 5240.292560504321, 5242.2136679498, 5256.737163141827, 5259.500347466985, 3379.459286514146, 3400.2009527760956, 3617.1318883468043, 3623.1304486923214, 3624.0, 1037.7976294622551, 1057.9896199129812, 1078.0997866781247, 1083.6153165626167, 1089.2865173311493, 10063.792344918656, 10875.0, 10964.835527467094, 11007.889921100847, 11064.944675276653, 4459.264221403497, 4566.862994110182, 4629.722216667011, 4648.279516674921, 4667.556582124693, 7391.778246652609, 9464.905350946403, 9487.193588544913, 9487.241020718326, 9488.620455181854, 5229.163333866563, 5229.555487783581, 5232.293640358579, 5235.162374020215, 5241.554467870197, 4289.270823959934, 4314.158739045919, 4751.64362672801, 4824.324594556765, 4830.227163653869, 3754.9436758448646, 3833.9616603833606, 3863.9806800965816, 3883.0680636647567, 3914.451976723261, 1282.8204051432806, 1289.9296628910652, 1295.7602843473987, 1306.392527474729, 1309.541660418865, 806.8628333183373, 871.407442938795, 887.4675194883018, 910.8724778531011, 940.6331530703031, 5391.487808658171, 5666.206731057655, 5716.599838011382, 5805.506531944832, 5961.433663801932, 943.6602822983721, 944.8393773058597, 947.5404428852002, 962.8590120706984, 1014.9035841595037, 3170.270837707294, 3698.3158115748674, 3803.5245594300713, 3844.1350696093155, 3910.843566257318, 9818.509074546244, 10275.7431064224, 10279.17766578672, 10301.587936482607, 10311.587536498606, 10112.544935477921, 10199.694009179737, 10200.132988695896, 10225.897741022643, 10240.48797560134, 7660.348870345972, 7845.646945887449, 7887.723929662549, 7969.163237859942, 8023.0, 5298.28473156119, 5511.559075274049, 5551.777928882961, 5577.77688892456, 5643.576731745054, 9368.859467108136, 9382.29632777558, 9397.013313601974, 9404.623815047322, 9408.01215872351, 9046.140616641409, 9492.050794920453, 9550.617975280911, 9781.266405019509, 9784.51077446139, 6707.798766037026, 6723.663816809042, 6765.221999470124, 6780.898286525608, 6825.35159159879, 7820.17888121739, 7895.249951254622, 7896.565688887166, 7902.209779022051, 7902.604869756374, 6941.556255322616, 6986.441084713109, 7140.143182818095, 7190.460715446257, 7299.014633024686, 5954.702264886825, 6034.883546543903, 7082.504224704221, 7104.431645468453, 7149.0706208192605, 5712.514436272797, 6551.672416379067, 6660.134182556237, 6707.765228216989, 6783.626900520519, 4818.325434439252, 4866.975665121652, 4871.318015477765, 4914.877128071684, 4975.577075948585, 4031.737937034083, 4817.686850354716, 4828.420589529279, 5021.698698078127, 5089.745512724275], "value": 4407.199043855374, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7580060151277, 353.51347093114856, 358.4032585744735, 363.78354878847085, 366.8330909436207, 5191.5327620514145, 5246.37043554773, 5331.520163185312, 5359.928014397122, 5567.9999999999945, 4721.575058244757, 5063.696178229309, 5202.583793296537, 5326.520613144816, 5346.973265133679, 3729.888103356897, 3923.960150560104, 4248.745075295476, 4249.44757181566, 4298.527162012176, 3297.29108241728, 3462.7229821614337, 3477.4436090225568, 3972.1459886124476, 4268.801567192898, 4206.221848957937, 4224.97887510562, 4238.0, 4245.80893859777, 4249.150169966004, 3546.308469848381, 3586.55168103987, 4226.640735537468, 4226.830926762926, 4256.34002738932, 3649.0, 3770.509833721626, 3776.206996530732, 3809.3524100902914, 3822.503074600312, 4777.976110119427, 4786.593139583139, 5126.794928202868, 5257.395399529065, 5425.9728701356635, 4791.873909631234, 4892.559669629715, 4914.754262286871, 4931.0, 4943.431505376891, 4637.0, 5464.863378415572, 5698.971505142489, 5720.427957204288, 5750.539956803445, 3645.398509245971, 3692.8522859085874, 4114.403411505315, 4147.170565886805, 4171.791410429466, 5978.8505287367725, 6042.728077236535, 6048.637081775108, 6069.2110025696375, 6089.969550152221, 3628.82063329419, 3726.7018638508853, 3782.7730336179916, 3864.2271545690974, 4277.315629499265, 3475.1138459692615, 3725.6646901778972, 4058.7271128168322, 4181.059261666101, 4253.3194688849635, 4634.632783328926, 4866.7079975201605, 4877.244027175793, 4891.6575839691595, 4902.876494063712, 5683.8010669626365, 5718.311688960597, 5733.885322293614, 5753.539716822645, 5799.18811366409, 1264.879836415539, 1293.9611811645666, 1337.555440124663, 1387.9097858639157, 1407.7536431124513, 953.1612181280428, 983.5967253426061, 984.7685793838442, 987.9901200987898, 994.6220436234184, 4494.0675509530565, 4977.950220497749, 5141.897162056738, 5145.742712864342, 5221.911235505828, 4724.543152658684, 5027.82402615914, 5544.91682624761, 5594.944050559524, 5659.0, 4393.758343291086, 4462.68350836504, 4626.768661566908, 4975.477574854661, 5152.793888244429, 2759.3030286373737, 2848.572714092861, 2907.941841163165, 2934.339773550934, 2935.2074939766144, 4617.699849509771, 4820.71075735457, 4852.44196917359, 5382.703951282639, 5431.484009019216, 5071.619628527875, 5170.3278573785165, 5343.5190832824455, 5706.920840452001, 5763.366029736726, 5445.700486473203, 5548.750306236229, 5719.113537401709, 5795.4647664925205, 5829.09649004405, 4797.616190704665, 4949.7030178189425, 5088.669236499616, 5441.863953401196, 5619.466150715675, 4939.580135688363, 5265.842024739264, 5437.673739575638, 5578.0, 5665.518430933417, 5104.642675012862, 5588.993981083444, 5765.4811067004175, 5846.970765146147, 5849.415058494116, 3879.3599056155417, 3968.5832987537046, 4026.355783074694, 4040.3535434330365, 4074.3888416737727, 4775.498572649822, 5432.0, 5433.794061503731, 5754.856128596819, 5844.444777746107, 4643.373144625432, 4978.925316120333, 5320.866978325573, 5512.649400896806, 5646.604737668324, 4921.803127874988, 5135.38375394955, 5721.628094173865, 5780.913286300793, 5805.506531944832, 5575.470330318613, 5582.581306401955, 5588.217649529151, 5596.580256480699, 5622.493975542221, 4890.60103228253, 5019.7490125494305, 5700.118960743028, 5714.742836572281, 5767.625104368202, 4716.20784102039, 5157.865269640673, 5291.682499050069, 5590.2670172246735, 5644.8024319148635], "value": 4124.317669184149, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.6366125953246, 727.7125535413516, 743.6504842723917, 786.8701664225404, 796.5539297993124, 14290.571282861518, 15365.0, 15374.154421506813, 15389.846101538982, 15419.0, 13849.792253116211, 14008.0194386393, 14042.157470551774, 14052.859471405283, 14085.295735213223, 583.7285662167092, 641.2785616181795, 709.4111887133693, 824.9381296402764, 848.8811566380712, 744.2929217243613, 868.9000764912053, 873.3319010956624, 875.9255463285597, 891.7057371067565, 924.6809850601531, 929.363386080537, 930.4463844012802, 930.8976012638639, 937.7327461673425, 784.8861915022347, 915.0666320353203, 929.1359036096428, 930.5207817973749, 935.4387367579466, 670.3263220463451, 680.8672308899767, 689.5793565924782, 693.0782059860373, 694.7360003198803, 14882.479113231093, 15154.31805568741, 15306.234688265542, 15374.769378459332, 15429.537113886601, 14112.50606228777, 14481.855181448262, 14634.0, 14676.19280939548, 14750.33623486935, 11789.705257368549, 11799.115066370057, 11891.643250702493, 11896.643100706993, 11978.70053248667, 3677.2645470905927, 3705.6664900159117, 3740.420234863573, 3763.0592351912023, 3857.267119247362, 91277.50308714516, 112779.0, 114422.99999999917, 115886.0, 116013.0, 9821.901780982098, 9824.312298139204, 9838.950805246071, 9902.207823374112, 10347.379157250589, 22136.88931555332, 22343.776562234496, 22529.32412027642, 22569.774302257094, 22590.322290331318, 7793.298603125746, 7836.0, 7843.764687059397, 7862.174471680506, 8011.038675358877, 4240.406343111967, 4343.565643435694, 4344.391785150082, 4360.716553424018, 4382.474103107647, 3506.7545271831236, 3603.4234522476277, 3608.4948107265004, 3613.9638603614153, 3618.3667858124722, 451.2959782738872, 535.8151437753969, 553.1647212708839, 563.2677519225073, 575.6689903305532, 499.04183966783864, 585.8857522783017, 621.9004959206505, 660.5838321857199, 672.6213438578783, 9125.589348479334, 9176.770580735401, 9212.447253164832, 9513.714588562354, 9543.47510886908, 589.8938191125638, 656.5273003437518, 660.4650233311033, 673.373762400962, 697.5396238482577, 4206.47419072616, 4537.137943790735, 4606.055778479177, 4637.814487420465, 4667.789949452215, 76793.0, 90604.54697726601, 94457.0, 95016.09967800605, 95559.52220238994, 84035.31929361502, 85175.0, 85539.0, 86582.13417865867, 88623.0, 79718.40563188704, 80010.79983800248, 80056.39887202224, 80067.0, 82464.58767706124, 7854.3323817473865, 7997.280244777765, 8139.3488520917, 8824.558772061242, 8943.776405589651, 43537.78231108824, 44384.0, 52848.207276890105, 53015.87936482652, 53501.19748203858, 54447.18329225144, 74656.0, 74798.0, 76221.0, 77703.0, 11779.52881884715, 11880.524779008743, 11893.583724569598, 12190.75618487643, 12352.691182720504, 12052.51789928393, 12322.815157772462, 12660.124428215666, 12957.22256664603, 13018.739625207632, 9910.603575856885, 11670.824937626112, 11833.644990650295, 12415.813762793385, 12422.813657795321, 11859.58491452795, 11939.164258501822, 11946.522139114339, 11948.701282468008, 12070.758584828078, 11686.123540734648, 11965.76068478608, 12318.384080795746, 12368.072394570263, 12369.62891113268, 9922.355046921926, 10032.347897386646, 10037.598496060076, 10106.696799096038, 10576.84134737964, 10062.647807326708, 10313.1233845124, 10340.53467593975, 10348.120409764953, 10349.223808214565], "value": 7204.037669737652, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 121.0, 118.0, 119.0, 121.0, 119.0, 118.0, 118.0, 119.0, 124.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 140.0, 143.0, 152.0, 113.0, 114.0, 114.0, 113.0, 131.0, 111.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 196.0, 208.0, 230.0, 196.0, 194.0, 194.0, 196.0, 196.0, 192.0], "value": 199.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 60.0, 86.0, 63.0, 59.0, 59.0, 59.0, 70.0, 60.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 85.0, 72.0, 69.0, 70.0, 83.0, 71.0, 72.0, 82.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 116.0, 115.0, 116.0, 117.0, 135.0, 122.0, 113.0, 120.0], "value": 119.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 134.0, 108.0, 152.0, 135.0, 150.0, 139.0, 98.0], "value": 121.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 93.0, 93.0, 122.0, 149.0, 144.0, 125.0, 93.0, 93.0], "value": 110.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 51.0, 57.0, 48.0, 50.0, 50.0, 48.0, 48.0, 71.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 104.0, 79.0, 97.0, 69.0, 84.0, 62.0, 88.0, 107.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 100.0, 80.0, 95.0, 115.0, 110.0, 104.0, 91.0, 90.0, 92.0], "value": 96.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 141.0, 156.0, 155.0, 149.0, 155.0, 135.0, 160.0, 218.0, 168.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 238.0, 182.0, 179.0, 173.0, 172.0, 229.0, 231.0, 175.0, 172.0], "value": 198.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 68.0, 69.0, 67.0, 68.0, 69.0, 83.0, 91.0], "value": 71.8, "unit": "score"}]}]} 12:29:06 INFO - Return code: 0 12:29:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:06 ERROR - # TBPL SUCCESS # 12:29:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:06 INFO - 2016-12-20 12:29:06.501 screenresolution[1662:12249] starting screenresolution argv=screenresolution get 12:29:06 INFO - 2016-12-20 12:29:06.503 screenresolution[1662:12249] Display 0: 1600x1200x32@60 12:29:06 INFO - 2016-12-20 12:29:06.510 screenresolution[1663:12252] starting screenresolution argv=screenresolution list 12:29:06 INFO - Available Modes on Display 0 12:29:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:06 INFO - Intel Iris: 12:29:06 INFO - Chipset Model: Intel Iris 12:29:06 INFO - Type: GPU 12:29:06 INFO - Bus: Built-In 12:29:06 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:06 INFO - Vendor: Intel (0x8086) 12:29:06 INFO - Device ID: 0x0a2e 12:29:06 INFO - Revision ID: 0x0009 12:29:06 INFO - Displays: 12:29:06 INFO - Display: 12:29:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:06 INFO - Display Serial Number: 1600x1200 60 12:29:06 INFO - Main Display: Yes 12:29:06 INFO - Mirror: Off 12:29:06 INFO - Online: Yes 12:29:06 INFO - Rotation: Supported 12:29:06 INFO - Return code: 0 12:29:06 INFO - Running post-action listener: _resource_record_post_action 12:29:06 INFO - [mozharness: 2016-12-20 20:29:06.744966Z] Finished run-tests step (success) 12:29:06 INFO - Running post-run listener: _resource_record_post_run 12:29:06 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 33329664; Write bytes: 555418112; Read time: 210; Write time: 1694 12:29:06 INFO - TinderboxPrint: CPU usage
54.7% 12:29:06 INFO - TinderboxPrint: I/O read bytes / time
33,329,664 / 210 12:29:06 INFO - TinderboxPrint: I/O write bytes / time
555,418,112 / 1,694 12:29:06 INFO - TinderboxPrint: CPU idle
2,175.9 (45.3%) 12:29:06 INFO - TinderboxPrint: CPU system
972.0 (20.2%) 12:29:06 INFO - TinderboxPrint: CPU user
1,657.7 (34.5%) 12:29:06 INFO - TinderboxPrint: Swap in / out
279,990,272 / 0 12:29:06 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 250022400; Write bytes: 243025408; Read time: 18654; Write time: 536 12:29:06 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 30093824; Write bytes: 301808640; Read time: 188; Write time: 1131 12:29:07 INFO - Running post-run listener: _upload_blobber_files 12:29:07 INFO - Blob upload gear active. 12:29:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:07 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 12:29:07 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'] 12:29:07 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 12:29:07 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:09 INFO - (blobuploader) - INFO - Done attempting. 12:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:10 INFO - (blobuploader) - INFO - Done attempting. 12:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:11 INFO - (blobuploader) - INFO - Done attempting. 12:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:13 INFO - (blobuploader) - INFO - Done attempting. 12:29:13 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:13 INFO - Return code: 0 12:29:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/21a2289d38831380a9e0348c494a4298ff8fba28f96267a6be2f5b3e17ab0f2fbc686498afd68e7900768f4e2db8c178888f9757f76b58b5e4d5b68789944e1f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77ea98a77756a1b9d478b197843f8c8565d98c008e9338cbc3e5d34549745199ab0c15793d5eddbcde658a73882fbcab9f62eddd2f8839aa72ae79f705909e27", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/54c5376daf736de08f4a7bb524931f83b8bef152b81779addb828796f205247186403d4948db672e85d3554cb78dd30e6e50892912ce404281c24ceb1e6cb150", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef81364b23877c6e2c5fee529a1f06baa971d7194138c282f7b7c338bdce453bc5a545bc55764b71fe45d4e8f715096a5c03889e73167d5215d5e571bf03815c"} 12:29:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:13 INFO - Contents: 12:29:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/21a2289d38831380a9e0348c494a4298ff8fba28f96267a6be2f5b3e17ab0f2fbc686498afd68e7900768f4e2db8c178888f9757f76b58b5e4d5b68789944e1f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77ea98a77756a1b9d478b197843f8c8565d98c008e9338cbc3e5d34549745199ab0c15793d5eddbcde658a73882fbcab9f62eddd2f8839aa72ae79f705909e27", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/54c5376daf736de08f4a7bb524931f83b8bef152b81779addb828796f205247186403d4948db672e85d3554cb78dd30e6e50892912ce404281c24ceb1e6cb150", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef81364b23877c6e2c5fee529a1f06baa971d7194138c282f7b7c338bdce453bc5a545bc55764b71fe45d4e8f715096a5c03889e73167d5215d5e571bf03815c"} 12:29:13 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:13 INFO - Copying logs to upload dir... 12:29:13 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.740180 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-20 12:29:13.362070) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 12:29:13.365409) ========= 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.udGWxKCKVD/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.3C4uO6YRRd/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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/21a2289d38831380a9e0348c494a4298ff8fba28f96267a6be2f5b3e17ab0f2fbc686498afd68e7900768f4e2db8c178888f9757f76b58b5e4d5b68789944e1f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77ea98a77756a1b9d478b197843f8c8565d98c008e9338cbc3e5d34549745199ab0c15793d5eddbcde658a73882fbcab9f62eddd2f8839aa72ae79f705909e27", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/54c5376daf736de08f4a7bb524931f83b8bef152b81779addb828796f205247186403d4948db672e85d3554cb78dd30e6e50892912ce404281c24ceb1e6cb150", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef81364b23877c6e2c5fee529a1f06baa971d7194138c282f7b7c338bdce453bc5a545bc55764b71fe45d4e8f715096a5c03889e73167d5215d5e571bf03815c"} build_url:https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011079 build_url: 'https://queue.taskcluster.net/v1/task/cxRIdKl-QJGAlNydsSjdFA/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/21a2289d38831380a9e0348c494a4298ff8fba28f96267a6be2f5b3e17ab0f2fbc686498afd68e7900768f4e2db8c178888f9757f76b58b5e4d5b68789944e1f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77ea98a77756a1b9d478b197843f8c8565d98c008e9338cbc3e5d34549745199ab0c15793d5eddbcde658a73882fbcab9f62eddd2f8839aa72ae79f705909e27", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/54c5376daf736de08f4a7bb524931f83b8bef152b81779addb828796f205247186403d4948db672e85d3554cb78dd30e6e50892912ce404281c24ceb1e6cb150", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef81364b23877c6e2c5fee529a1f06baa971d7194138c282f7b7c338bdce453bc5a545bc55764b71fe45d4e8f715096a5c03889e73167d5215d5e571bf03815c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 12:29:13.400047) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:29:13.400339) ========= 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.udGWxKCKVD/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.3C4uO6YRRd/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 12:29:13.447612) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 12:29:13.447897) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 12:29:13.448216) ========= ========= Total master_lag: 0.25 =========