builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1472515304.23 results: success (0) buildid: 20160829133431 builduid: 093a6f641cd44f66a653dcaad988dd19 revision: 7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.235178) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.235824) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.236157) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.262535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.262950) ========= 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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-08-29 17:01:44-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2016-08-29 17:01:44 (15.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124599 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.524353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.525139) ========= 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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.029080 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.580247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:44.580709) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UkUjSgqHSK/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.A2DnOqiKW1/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-08-29 17:01:44,649 truncating revision to first 12 chars 2016-08-29 17:01:44,649 Setting DEBUG logging. 2016-08-29 17:01:44,650 attempt 1/10 2016-08-29 17:01:44,650 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/7b7cfb21c5b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 17:01:45,354 unpacking tar archive at: larch-7b7cfb21c5b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.970489 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:45.577729) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:45.578125) ========= script_repo_revision: 7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:45.578623) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:45.578965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 17:01:45.598813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-29 17:01:45.599182) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UkUjSgqHSK/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.A2DnOqiKW1/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:01:45 INFO - MultiFileLogger online at 20160829 17:01:45 in /builds/slave/test 17:01:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 17:01:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:45 INFO - {'append_to_log': False, 17:01:45 INFO - 'base_work_dir': '/builds/slave/test', 17:01:45 INFO - 'blob_upload_branch': 'Larch', 17:01:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:45 INFO - 'branch': 'Larch', 17:01:45 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:45 INFO - 'config_files': ('talos/mac_config.py',), 17:01:45 INFO - 'default_actions': ('clobber', 17:01:45 INFO - 'read-buildbot-config', 17:01:45 INFO - 'download-and-extract', 17:01:45 INFO - 'populate-webroot', 17:01:45 INFO - 'create-virtualenv', 17:01:45 INFO - 'install', 17:01:45 INFO - 'run-tests'), 17:01:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:45 INFO - 'download_minidump_stackwalk': True, 17:01:45 INFO - 'download_symbols': 'ondemand', 17:01:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:45 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:45 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:45 INFO - 'installer_path': 'installer.exe', 17:01:45 INFO - 'log_level': 'info', 17:01:45 INFO - 'log_name': 'talos', 17:01:45 INFO - 'log_to_console': True, 17:01:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:01:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:01:45 INFO - 'opt_config_files': (), 17:01:45 INFO - 'pip_index': False, 17:01:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:45 INFO - 'cmd': ('bash', 17:01:45 INFO - '-c', 17:01:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:45 INFO - 'enabled': True, 17:01:45 INFO - 'halt_on_failure': False, 17:01:45 INFO - 'name': 'check_screen_resolution'},), 17:01:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:45 INFO - 'cmd': ('bash', 17:01:45 INFO - '-c', 17:01:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:45 INFO - 'enabled': True, 17:01:45 INFO - 'halt_on_failure': False, 17:01:45 INFO - 'name': 'check_screen_resolution'},), 17:01:45 INFO - 'run_cmd_checks_enabled': True, 17:01:45 INFO - 'sps_profile': False, 17:01:45 INFO - 'sps_profile_interval': 0, 17:01:45 INFO - 'suite': 'dromaeojs', 17:01:45 INFO - 'system_bits': '32', 17:01:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:45 INFO - 'title': 't-yosemite-r7-0132', 17:01:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:45 INFO - 'use_talos_json': True, 17:01:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:45 INFO - 'work_dir': 'build'} 17:01:45 INFO - [mozharness: 2016-08-30 00:01:45.807447Z] Running clobber step. 17:01:45 INFO - Running pre-action listener: _resource_record_pre_action 17:01:45 INFO - Running main action method: clobber 17:01:45 INFO - rmtree: /builds/slave/test/build 17:01:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:48 INFO - Running post-action listener: _resource_record_post_action 17:01:48 INFO - [mozharness: 2016-08-30 00:01:48.217654Z] Finished clobber step (success) 17:01:48 INFO - [mozharness: 2016-08-30 00:01:48.217784Z] Running read-buildbot-config step. 17:01:48 INFO - Running pre-action listener: _resource_record_pre_action 17:01:48 INFO - Running main action method: read_buildbot_config 17:01:48 INFO - Using buildbot properties: 17:01:48 INFO - { 17:01:48 INFO - "project": "", 17:01:48 INFO - "product": "firefox", 17:01:48 INFO - "script_repo_revision": "production", 17:01:48 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 17:01:48 INFO - "repository": "", 17:01:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 17:01:48 INFO - "buildid": "20160829133431", 17:01:48 INFO - "pgo_build": "False", 17:01:48 INFO - "basedir": "/builds/slave/test", 17:01:48 INFO - "buildnumber": 1, 17:01:48 INFO - "slavename": "t-yosemite-r7-0132", 17:01:48 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:01:48 INFO - "platform": "yosemite_r7", 17:01:48 INFO - "scheduler": "tests-larch-macosx64-talos", 17:01:48 INFO - "branch": "larch", 17:01:48 INFO - "repo_path": "projects/larch", 17:01:48 INFO - "revision": "7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d", 17:01:48 INFO - "stage_platform": "macosx64", 17:01:48 INFO - "builduid": "093a6f641cd44f66a653dcaad988dd19", 17:01:48 INFO - "slavebuilddir": "test" 17:01:48 INFO - } 17:01:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:01:48 INFO - Running post-action listener: _resource_record_post_action 17:01:48 INFO - [mozharness: 2016-08-30 00:01:48.221078Z] Finished read-buildbot-config step (success) 17:01:48 INFO - [mozharness: 2016-08-30 00:01:48.221185Z] Running download-and-extract step. 17:01:48 INFO - Running pre-action listener: _resource_record_pre_action 17:01:48 INFO - Running main action method: download_and_extract 17:01:48 INFO - mkdir: /builds/slave/test/build/tests 17:01: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')]} 17:01:48 INFO - trying https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:01:48 INFO - Downloading https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 17:01:49 INFO - Downloaded 1199 bytes. 17:01:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:01:49 INFO - Using the following test package requirements: 17:01:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:01:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:01:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:01:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'jsshell-mac.zip'], 17:01:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:01:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:01:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:01:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:01:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:01:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:01:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:01:49 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')]} 17:01:49 INFO - trying https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:01:49 INFO - Downloading https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:01:50 INFO - Downloaded 16352369 bytes. 17:01:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:01:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:01:57 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')]} 17:01:57 INFO - trying https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:01:57 INFO - Downloading https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:01:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:01:58 INFO - Downloaded 16352369 bytes. 17:01:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:02:05 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')]} 17:02:05 INFO - trying https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:02:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:02:05 INFO - Downloaded 14413981 bytes. 17:02:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 17:02:06 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')]} 17:02:06 INFO - trying https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:02:06 INFO - Downloading https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 17:02:09 INFO - Downloaded 111540983 bytes. 17:02:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:02:09 INFO - mkdir: /builds/slave/test/properties 17:02:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:02:09 INFO - Writing to file /builds/slave/test/properties/build_url 17:02:09 INFO - Contents: 17:02:09 INFO - build_url:https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:02:09 INFO - Running post-action listener: _resource_record_post_action 17:02:09 INFO - Running post-action listener: set_extra_try_arguments 17:02:09 INFO - [mozharness: 2016-08-30 00:02:09.876745Z] Finished download-and-extract step (success) 17:02:09 INFO - [mozharness: 2016-08-30 00:02:09.876884Z] Running populate-webroot step. 17:02:09 INFO - Running pre-action listener: _resource_record_pre_action 17:02:09 INFO - Running main action method: populate_webroot 17:02:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:02:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:02:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:02:09 INFO - '--tppagecycles', 17:02:09 INFO - '7'], 17:02:09 INFO - 'tests': ['tsvgm']}}, 17:02:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:02:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:02:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:02:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:02:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['damp', 'tps']}, 17:02:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'tests': ['damp', 'tps']}, 17:02:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['dromaeo_dom']}, 17:02:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:02:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['basic_compositor_video']}, 17:02:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:02:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other-e10s': {'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tsvgx', 17:02:09 INFO - 'tsvgr_opacity', 17:02:09 INFO - 'tart', 17:02:09 INFO - 'tscrollx', 17:02:09 INFO - 'cart']}, 17:02:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:02:09 INFO - 'tsvgr_opacity', 17:02:09 INFO - 'tart', 17:02:09 INFO - 'tscrollx', 17:02:09 INFO - 'cart']}, 17:02:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tp5o']}, 17:02:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'tests': ['tp5o']}, 17:02:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s', 17:02:09 INFO - '--xperf_path', 17:02:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:09 INFO - 'tests': ['tp5n']}, 17:02:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:09 INFO - 'talos_options': ['--xperf_path', 17:02:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:09 INFO - 'tests': ['tp5n']}}, 17:02:09 INFO - 'talos.zip': {'path': '', 17:02:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:02:09 INFO - Running post-action listener: _resource_record_post_action 17:02:09 INFO - [mozharness: 2016-08-30 00:02:09.895964Z] Finished populate-webroot step (success) 17:02:09 INFO - [mozharness: 2016-08-30 00:02:09.896075Z] Running create-virtualenv step. 17:02:09 INFO - Running pre-action listener: _resource_record_pre_action 17:02:09 INFO - Running main action method: create_virtualenv 17:02:09 INFO - Creating virtualenv /builds/slave/test/build/venv 17:02:09 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 17:02:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:02:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:02:10 INFO - Using real prefix '/tools/python27' 17:02:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:02:10 INFO - Installing distribute.............................................................................................................................................................................................done. 17:02:13 INFO - Installing pip.................done. 17:02:13 INFO - Return code: 0 17:02:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:13 INFO - 'HOME': '/Users/cltbld', 17:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:13 INFO - 'LOGNAME': 'cltbld', 17:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:13 INFO - 'MOZ_NO_REMOTE': '1', 17:02:13 INFO - 'NO_EM_RESTART': '1', 17:02:13 INFO - 'PAGER': '/bin/cat', 17:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:13 INFO - 'PWD': '/builds/slave/test', 17:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:13 INFO - 'SHELL': '/bin/bash', 17:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:13 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:13 INFO - 'USER': 'cltbld', 17:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:13 INFO - 'XPC_FLAGS': '0x0', 17:02:13 INFO - 'XPC_SERVICE_NAME': '0', 17:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:02:13 INFO - Cleaning up... 17:02:13 INFO - Return code: 0 17:02:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:13 INFO - 'HOME': '/Users/cltbld', 17:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:13 INFO - 'LOGNAME': 'cltbld', 17:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:13 INFO - 'MOZ_NO_REMOTE': '1', 17:02:13 INFO - 'NO_EM_RESTART': '1', 17:02:13 INFO - 'PAGER': '/bin/cat', 17:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:13 INFO - 'PWD': '/builds/slave/test', 17:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:13 INFO - 'SHELL': '/bin/bash', 17:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:13 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:13 INFO - 'USER': 'cltbld', 17:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:13 INFO - 'XPC_FLAGS': '0x0', 17:02:13 INFO - 'XPC_SERVICE_NAME': '0', 17:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:14 INFO - Downloading/unpacking psutil>=3.1.1 17:02:14 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 17:02:14 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 17:02:14 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 17:02:14 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 17:02:14 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 17:02:14 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 17:02:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:02:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:17 INFO - Installing collected packages: psutil 17:02:17 INFO - Running setup.py install for psutil 17:02:17 INFO - building 'psutil._psutil_osx' extension 17:02:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:02:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:02:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:02:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:02:18 INFO - building 'psutil._psutil_posix' extension 17:02:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:02:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:02:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:02:18 INFO - ^ 17:02:18 INFO - 1 warning generated. 17:02:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:02:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:18 INFO - Successfully installed psutil 17:02:18 INFO - Cleaning up... 17:02:18 INFO - Return code: 0 17:02:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:02:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:02:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:02:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:18 INFO - 'HOME': '/Users/cltbld', 17:02:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:18 INFO - 'LOGNAME': 'cltbld', 17:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:18 INFO - 'MOZ_NO_REMOTE': '1', 17:02:18 INFO - 'NO_EM_RESTART': '1', 17:02:18 INFO - 'PAGER': '/bin/cat', 17:02:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:18 INFO - 'PWD': '/builds/slave/test', 17:02:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:18 INFO - 'SHELL': '/bin/bash', 17:02:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:18 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:18 INFO - 'USER': 'cltbld', 17:02:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:18 INFO - 'XPC_FLAGS': '0x0', 17:02:18 INFO - 'XPC_SERVICE_NAME': '0', 17:02:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:02:21 INFO - Installing collected packages: mozsystemmonitor 17:02:21 INFO - Running setup.py install for mozsystemmonitor 17:02:21 INFO - Successfully installed mozsystemmonitor 17:02:21 INFO - Cleaning up... 17:02:21 INFO - Return code: 0 17:02:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:21 INFO - 'HOME': '/Users/cltbld', 17:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:21 INFO - 'LOGNAME': 'cltbld', 17:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:21 INFO - 'MOZ_NO_REMOTE': '1', 17:02:21 INFO - 'NO_EM_RESTART': '1', 17:02:21 INFO - 'PAGER': '/bin/cat', 17:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:21 INFO - 'PWD': '/builds/slave/test', 17:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:21 INFO - 'SHELL': '/bin/bash', 17:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:21 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:21 INFO - 'USER': 'cltbld', 17:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:21 INFO - 'XPC_FLAGS': '0x0', 17:02:21 INFO - 'XPC_SERVICE_NAME': '0', 17:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:22 INFO - Downloading/unpacking jsonschema==2.5.1 17:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:02:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:25 INFO - Downloading functools32-3.2.3-2.tar.gz 17:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:02:25 INFO - warning: no files found matching '*.txt' 17:02:25 INFO - no previously-included directories found matching 'build' 17:02:25 INFO - no previously-included directories found matching 'dist' 17:02:25 INFO - no previously-included directories found matching '.git*' 17:02:25 INFO - Installing collected packages: jsonschema, functools32 17:02:25 INFO - Running setup.py install for functools32 17:02:25 INFO - warning: no files found matching '*.txt' 17:02:25 INFO - no previously-included directories found matching 'build' 17:02:25 INFO - no previously-included directories found matching 'dist' 17:02:25 INFO - no previously-included directories found matching '.git*' 17:02:25 INFO - Successfully installed jsonschema functools32 17:02:25 INFO - Cleaning up... 17:02:25 INFO - Return code: 0 17:02:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:25 INFO - 'HOME': '/Users/cltbld', 17:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:25 INFO - 'LOGNAME': 'cltbld', 17:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:25 INFO - 'MOZ_NO_REMOTE': '1', 17:02:25 INFO - 'NO_EM_RESTART': '1', 17:02:25 INFO - 'PAGER': '/bin/cat', 17:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:25 INFO - 'PWD': '/builds/slave/test', 17:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:25 INFO - 'SHELL': '/bin/bash', 17:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:25 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:25 INFO - 'USER': 'cltbld', 17:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:25 INFO - 'XPC_FLAGS': '0x0', 17:02:25 INFO - 'XPC_SERVICE_NAME': '0', 17:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:02:25 INFO - Cleaning up... 17:02:25 INFO - Return code: 0 17:02:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:25 INFO - 'HOME': '/Users/cltbld', 17:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:25 INFO - 'LOGNAME': 'cltbld', 17:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:25 INFO - 'MOZ_NO_REMOTE': '1', 17:02:25 INFO - 'NO_EM_RESTART': '1', 17:02:25 INFO - 'PAGER': '/bin/cat', 17:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:25 INFO - 'PWD': '/builds/slave/test', 17:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:25 INFO - 'SHELL': '/bin/bash', 17:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:25 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:25 INFO - 'USER': 'cltbld', 17:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:25 INFO - 'XPC_FLAGS': '0x0', 17:02:25 INFO - 'XPC_SERVICE_NAME': '0', 17:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:28 INFO - Downloading blobuploader-1.2.4.tar.gz 17:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:02:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:02:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:29 INFO - Downloading docopt-0.6.1.tar.gz 17:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:02:29 INFO - Installing collected packages: blobuploader, requests, docopt 17:02:29 INFO - Running setup.py install for blobuploader 17:02:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:02:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:02:29 INFO - Running setup.py install for requests 17:02:30 INFO - Running setup.py install for docopt 17:02:30 INFO - Successfully installed blobuploader requests docopt 17:02:30 INFO - Cleaning up... 17:02:30 INFO - Return code: 0 17:02:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:02:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:30 INFO - 'HOME': '/Users/cltbld', 17:02:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:30 INFO - 'LOGNAME': 'cltbld', 17:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:30 INFO - 'MOZ_NO_REMOTE': '1', 17:02:30 INFO - 'NO_EM_RESTART': '1', 17:02:30 INFO - 'PAGER': '/bin/cat', 17:02:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:30 INFO - 'PWD': '/builds/slave/test', 17:02:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:30 INFO - 'SHELL': '/bin/bash', 17:02:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:30 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:30 INFO - 'USER': 'cltbld', 17:02:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:30 INFO - 'XPC_FLAGS': '0x0', 17:02:30 INFO - 'XPC_SERVICE_NAME': '0', 17:02:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:30 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-vCajcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:30 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-wPI5Hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-e7ysbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Xh3RCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-XX18LB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-oU0FVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-x9Sdcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-a3ltrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Ic8Ce3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-tMPl_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-vylXoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:31 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ua2Hlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:32 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-xdcsgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:32 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-DeQU3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:32 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-dGEpjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:32 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-6C0c6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:32 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-0Kx5od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:02:32 INFO - Running setup.py install for manifestparser 17:02:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Running setup.py install for mozcrash 17:02:32 INFO - Running setup.py install for mozdebug 17:02:32 INFO - Running setup.py install for mozdevice 17:02:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozfile 17:02:33 INFO - Running setup.py install for mozhttpd 17:02:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozinfo 17:02:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozInstall 17:02:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozleak 17:02:33 INFO - Running setup.py install for mozlog 17:02:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for moznetwork 17:02:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozprocess 17:02:34 INFO - Running setup.py install for mozprofile 17:02:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozrunner 17:02:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozscreenshot 17:02:34 INFO - Running setup.py install for moztest 17:02:34 INFO - Running setup.py install for mozversion 17:02:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:02:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:02:35 INFO - Cleaning up... 17:02:35 INFO - Return code: 0 17:02:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:35 INFO - 'HOME': '/Users/cltbld', 17:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:35 INFO - 'LOGNAME': 'cltbld', 17:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:35 INFO - 'MOZ_NO_REMOTE': '1', 17:02:35 INFO - 'NO_EM_RESTART': '1', 17:02:35 INFO - 'PAGER': '/bin/cat', 17:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:35 INFO - 'PWD': '/builds/slave/test', 17:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:35 INFO - 'SHELL': '/bin/bash', 17:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:35 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:35 INFO - 'USER': 'cltbld', 17:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:35 INFO - 'XPC_FLAGS': '0x0', 17:02:35 INFO - 'XPC_SERVICE_NAME': '0', 17:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cWRYCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YdfmbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-EkUCoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-2sxqPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-KHR73c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-VXGmk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-on9eQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-FIW95L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ofqUpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-_Ir6TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ovLA23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GMiSKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-wkwqrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-c0DtIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ipa8wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7V8TtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:37 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)) 17:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-96_1Om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:02:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:39 INFO - Downloading blessings-1.6.tar.gz 17:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:02:39 INFO - Installing collected packages: blessings 17:02:39 INFO - Running setup.py install for blessings 17:02:40 INFO - Successfully installed blessings 17:02:40 INFO - Cleaning up... 17:02:40 INFO - Return code: 0 17:02:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:40 INFO - Reading from file tmpfile_stdout 17:02:40 INFO - Current package versions: 17:02:40 INFO - blessings == 1.6 17:02:40 INFO - blobuploader == 1.2.4 17:02:40 INFO - docopt == 0.6.1 17:02:40 INFO - functools32 == 3.2.3-2 17:02:40 INFO - jsonschema == 2.5.1 17:02:40 INFO - manifestparser == 1.1 17:02:40 INFO - mozInstall == 1.12 17:02:40 INFO - mozcrash == 0.17 17:02:40 INFO - mozdebug == 0.1 17:02:40 INFO - mozdevice == 0.48 17:02:40 INFO - mozfile == 1.2 17:02:40 INFO - mozhttpd == 0.7 17:02:40 INFO - mozinfo == 0.9 17:02:40 INFO - mozleak == 0.1 17:02:40 INFO - mozlog == 3.3 17:02:40 INFO - moznetwork == 0.27 17:02:40 INFO - mozprocess == 0.23 17:02:40 INFO - mozprofile == 0.28 17:02:40 INFO - mozrunner == 6.12 17:02:40 INFO - mozscreenshot == 0.1 17:02:40 INFO - mozsystemmonitor == 0.3 17:02:40 INFO - moztest == 0.7 17:02:40 INFO - mozversion == 1.4 17:02:40 INFO - psutil == 3.1.1 17:02:40 INFO - requests == 1.2.3 17:02:40 INFO - wsgiref == 0.1.2 17:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02: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')]} 17:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:41 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 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:41 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 17:02:41 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 17:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:41 INFO - 'HOME': '/Users/cltbld', 17:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:41 INFO - 'LOGNAME': 'cltbld', 17:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:41 INFO - 'MOZ_NO_REMOTE': '1', 17:02:41 INFO - 'NO_EM_RESTART': '1', 17:02:41 INFO - 'PAGER': '/bin/cat', 17:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:41 INFO - 'PWD': '/builds/slave/test', 17:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:41 INFO - 'SHELL': '/bin/bash', 17:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:41 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:41 INFO - 'USER': 'cltbld', 17:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:41 INFO - 'XPC_FLAGS': '0x0', 17:02:41 INFO - 'XPC_SERVICE_NAME': '0', 17:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:02:41 INFO - Cleaning up... 17:02:41 INFO - Return code: 0 17:02:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:02: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')]} 17:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02: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')]} 17:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1013aca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101441618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa862d865d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, '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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 17:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:41 INFO - 'HOME': '/Users/cltbld', 17:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:41 INFO - 'LOGNAME': 'cltbld', 17:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:41 INFO - 'MOZ_NO_REMOTE': '1', 17:02:41 INFO - 'NO_EM_RESTART': '1', 17:02:41 INFO - 'PAGER': '/bin/cat', 17:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:41 INFO - 'PWD': '/builds/slave/test', 17:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:41 INFO - 'SHELL': '/bin/bash', 17:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:02:41 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:41 INFO - 'USER': 'cltbld', 17:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:41 INFO - 'XPC_FLAGS': '0x0', 17:02:41 INFO - 'XPC_SERVICE_NAME': '0', 17:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:02:41 INFO - Cleaning up... 17:02:41 INFO - Return code: 0 17:02:41 INFO - Running post-action listener: _resource_record_post_action 17:02:41 INFO - Running post-action listener: _start_resource_monitoring 17:02:41 INFO - Starting resource monitoring. 17:02:41 INFO - [mozharness: 2016-08-30 00:02:41.676743Z] Finished create-virtualenv step (success) 17:02:41 INFO - [mozharness: 2016-08-30 00:02:41.677697Z] Running install step. 17:02:41 INFO - Running pre-action listener: _resource_record_pre_action 17:02:41 INFO - Running main action method: install 17:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:41 INFO - Reading from file tmpfile_stdout 17:02:41 INFO - Detecting whether we're running mozinstall >=1.0... 17:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:02:42 INFO - Reading from file tmpfile_stdout 17:02:42 INFO - Output received: 17:02:42 INFO - Usage: mozinstall [options] installer 17:02:42 INFO - Options: 17:02:42 INFO - -h, --help show this help message and exit 17:02:42 INFO - -d DEST, --destination=DEST 17:02:42 INFO - Directory to install application into. [default: 17:02:42 INFO - "/builds/slave/test"] 17:02:42 INFO - --app=APP Application being installed. [default: firefox] 17:02:42 INFO - mkdir: /builds/slave/test/build/application 17:02:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:03:16 INFO - Reading from file tmpfile_stdout 17:03:16 INFO - Output received: 17:03:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:03:16 INFO - Running post-action listener: _resource_record_post_action 17:03:16 INFO - [mozharness: 2016-08-30 00:03:16.694864Z] Finished install step (success) 17:03:16 INFO - [mozharness: 2016-08-30 00:03:16.695008Z] Running run-tests step. 17:03:16 INFO - Running pre-action listener: _resource_record_pre_action 17:03:16 INFO - Running main action method: run_tests 17:03:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:16 INFO - 2016-08-29 17:03:16.709 screenresolution[1632:7380] starting screenresolution argv=screenresolution get 17:03:16 INFO - 2016-08-29 17:03:16.711 screenresolution[1632:7380] Display 0: 1600x1200x32@60 17:03:16 INFO - 2016-08-29 17:03:16.717 screenresolution[1633:7382] starting screenresolution argv=screenresolution list 17:03:16 INFO - Available Modes on Display 0 17:03:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:16 INFO - Intel Iris: 17:03:16 INFO - Chipset Model: Intel Iris 17:03:16 INFO - Type: GPU 17:03:16 INFO - Bus: Built-In 17:03:16 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:16 INFO - Vendor: Intel (0x8086) 17:03:16 INFO - Device ID: 0x0a2e 17:03:16 INFO - Revision ID: 0x0009 17:03:16 INFO - Displays: 17:03:16 INFO - Display: 17:03:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:16 INFO - Display Serial Number: 1600x1200 60 17:03:16 INFO - Main Display: Yes 17:03:16 INFO - Mirror: Off 17:03:16 INFO - Online: Yes 17:03:16 INFO - Rotation: Supported 17:03:16 INFO - Return code: 0 17:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:03:16 INFO - Python 2.7.3 17:03:16 INFO - Return code: 0 17:03:16 INFO - grabbing minidump binary from tooltool 17:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:16 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 0x101310d30>, '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 0x101075db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101308b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:03:16 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 17:03:16 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 17:03:16 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 17:03:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:03:17 INFO - Return code: 0 17:03:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:03:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:03:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:03:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:03:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:03:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UkUjSgqHSK/Render', 17:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:17 INFO - 'HOME': '/Users/cltbld', 17:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:17 INFO - 'LOGNAME': 'cltbld', 17:03:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:03:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:17 INFO - 'MOZ_NO_REMOTE': '1', 17:03:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:03:17 INFO - 'NO_EM_RESTART': '1', 17:03:17 INFO - 'PAGER': '/bin/cat', 17:03:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:17 INFO - 'PWD': '/builds/slave/test', 17:03:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:17 INFO - 'SHELL': '/bin/bash', 17:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A2DnOqiKW1/Listeners', 17:03:17 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 17:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:17 INFO - 'USER': 'cltbld', 17:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:17 INFO - 'XPC_FLAGS': '0x0', 17:03:17 INFO - 'XPC_SERVICE_NAME': '0', 17:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:03:17 INFO - mozversion application_buildid: 20160829133431 17:03:17 INFO - mozversion application_changeset: 7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d 17:03:17 INFO - mozversion application_display_name: Nightly 17:03:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:03:17 INFO - mozversion application_name: Firefox 17:03:17 INFO - mozversion application_remotingname: firefox 17:03:17 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 17:03:17 INFO - mozversion application_vendor: Mozilla 17:03:17 INFO - mozversion application_version: 51.0a1 17:03:17 INFO - mozversion platform_buildid: 20160829133431 17:03:17 INFO - mozversion platform_changeset: 7b7cfb21c5b4d6f9b62987eaf9006155b8925d3d 17:03:17 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 17:03:17 INFO - mozversion platform_version: 51.0a1 17:03:17 INFO - using testdate: 1472515397 17:03:17 INFO - actual date: 1472515397 17:03:17 INFO - starting webserver on 'localhost:49266' 17:03:17 INFO - SUITE-START | Running 2 tests 17:03:17 INFO - TEST-START | dromaeo_css 17:03:17 INFO - operating with platform_type : mac_ 17:03:17 INFO - Initialising browser for dromaeo_css test... 17:03:17 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp6vqi0_/profile http://localhost:49266/getInfo.html) 17:03:19 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 17:03:19 INFO - PROCESS | 1643 | colorDepth:24 17:03:19 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 17:03:19 INFO - PROCESS | 1643 | __metrics 17:03:19 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:03:19 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:03:20 INFO - TEST-INFO | 1643: exit 0 17:03:20 INFO - Browser initialized. 17:03:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:03:20 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp6vqi0_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:32 INFO - PROCESS | 1648 | RSS: Main: 231452672 17:03:32 INFO - PROCESS | 1648 | 17:04:23 INFO - PROCESS | 1648 | 1472515463909 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] 17:06:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 17:06:37 INFO - PROCESS | 1648 | RSS: Main: 328916992 17:06:37 INFO - PROCESS | 1648 | 17:09:40 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 17:09:41 INFO - PROCESS | 1648 | RSS: Main: 370155520 17:09:41 INFO - PROCESS | 1648 | 17:12:43 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 17:12:44 INFO - PROCESS | 1648 | RSS: Main: 363761664 17:12:44 INFO - PROCESS | 1648 | 17:15:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 17:15:47 INFO - PROCESS | 1648 | RSS: Main: 344907776 17:15:47 INFO - PROCESS | 1648 | 17:18:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 17:18:51 INFO - PROCESS | 1648 | RSS: Main: 329039872 17:18:51 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 17:21:54 INFO - PROCESS | 1648 | RSS: Main: 482459648 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | __start_tp_report 17:21:54 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 17:21:54 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 17:21:54 INFO - PROCESS | 1648 | |i|pagename|runs| 17:21:54 INFO - PROCESS | 1648 | |0;dojo.html;3328.6504916983718;3367.966320336796;3378.7634865559407;3386.949195762064;3403.9319213615713;5657.575681823859;5726.799562015331;5771.769129234831;5883.352831188566;5883.47048765611;5724.570657200717;5735.483806457418;5775.855603609912;5782.681952492611;5818.301803783552;5108.4636113208135;5162.483751624826;5180.300659410969;5186.32577764891;5187.325647665798;5160.56135228506;5274.208868669692;5279.234510995903;5282.339707536558;5294.841154765363;5255.264263003182;5279.472052794728;5283.339582552181;5290.761915713783;5294.020606187868;4249.702520823544;5049.318342023853;5111.693298402108;5179.896402071938;5203.245529398254;5038.697678139323;5044.949550504484;5067.847964561069;5088.491150884919;5107.540321371057;5786.132080187964;5817.912731309034;5831.708414579254;5838.824835254949;5852.941470585282;6117.5717699761035;6175.938240617581;6228.252609686821;6256.6871656417;6258.874822503524;6212.627242365473;6218.626882387026;6232.065190221458;6254.530910181709;6261.186045814061;5943.940560594425;5958.19564358806;5962.986292330314;5969.432903874168;5972.940270597239;6185.597936134137;6255.030470277115;6287.402696743802;6391.680415979183;6393.904091438632;6249.96875015622;6271.811845644638;6276.152719382825;6287.025511045796;6299.118123462627;6295.087212354275;6315.305316415191;6336.20797400325;6344.397282258178;6352.587081839758;5939.46544810969;6122.418370254819;6169.784057557964;6222.315545290015;6265.843353916189;6270.122182894398;6282.057691346335;6318.494520438354;6322.494200464043;6337.904931426032;4986.750662466862;4998.525140111684;5004.549590536869;5005.599552035828;5005.7997680092385;4348.412964249786;4351.717138385995;4425.004374015821;4462.754548499799;4485.955140448554;5739.540836733051;5843.298804143445;5852.473277405054;5853.88292234153;5857.26784151981;6248.375162483715;6254.249490061222;6270;6284.654344011032;6284.937150628527;5639.6334238274385;5687.800926967537;5741.741621627037;5847.94152058474;5848.356680765112;5454.127339625641;5515.641483303572;5521.061419558685;5521.503064724194;5539.806106786243;6383.489320854321;6420.454261387835;6420.550561460653;6431.260405053479;6434.903476447764;6209.285932117864;6256.624602523863;6289.40250676185;6312.210973628296;6315.4947604192475;6340.207474065742;6420.0048992406255;6428.357164283534;6438.3883531064475;6461.19235095613;6267.9373206267355;6273.52948528853;6274.215723034621;6295.18162638854;6295.874082518232;6213.316535181036;6251.874962500816;6299.779507717208;6307.810765677037;6325.019621958604;6178.876422471615;6203.379662033761;6275.905861411991;6277.529185311028;6283.654399008098;6228.56400051992;6259.749610015549;6261.405166509175;6263.3736626337;6263.9664455364355;5791.13133030032;6066.15073889647;6066.757329706762;6084.6349219047;6086.908696369469;5226.712530810842;6095.238095238095;6120.265568131853;6155.569110162246;6261.279952805304;6028.90956635642;6072.301685306246;6080.452759251689;6099.420555047263;6102 17:21:54 INFO - PROCESS | 1648 | |1;ext.html;455.38295609449193;486.8271763523949;505.9797608095676;531.5720844720001;560.3807792389407;30233.395332093372;33578.66421335786;39641;40964.99999999996;42859.92850178747;42161.36757948626;42398;42417.36373954393;42499;42505.14989700204;9632.662856799996;9829.852552211687;9844.556994935208;9851.90148098517;9854.211663066937;9722.854157187612;9795.314327997043;9814.165795907356;9817.705468835911;9839.508024598777;9814.362066465692;9836.458994755285;9862.90137098627;9868.851967220497;9875.358101723401;9757.268204884662;9817.950910245474;9818.625392445063;9826.16477599405;9836.852447213298;9728.90271097287;9796.167325777318;9799.951000245024;9804.754881127958;9812.215022798231;39792.20415591672;40147.99630009244;40183;40193.59806401928;40247.59752402468;3013.9397212055637;3451.758376913617;3500.859965601373;3604.6575575320303;4078.490188726409;31000.689993100004;31556.895508657086;31855.203619909455;31901;32140.87506937246;3270.869165233388;3280.3767284215924;3322.5514555534937;3424;3461.2904354607244;495901.4098590148;496380;498581;499266.503667483;500636;32752.344953100805;33637.83181084079;33806.985790426326;35019.824900875334;59043.40956590465;26494.86752566225;34776;35357;35408.46887296693;35445.468317975254;16664.33342666304;16859.662806743796;16901.577460563585;16941;16952.74570881438;12904.935475322563;13028.022898282667;13033.869661303455;13038.282894440898;13046.804297935538;18712.438626841216;18957.81042189588;18974.81025189758;18975;18976.620467590572;7941.166177551389;8039.35685145187;8046.839063218703;8055.1542088079905;8058.11360750317;812.5206128384282;881.9514926679094;1006.5772375602264;1026.6663334416196;1056.313396292416;28936.27659308492;29847.552286715716;30159.69840301569;30428.695713043027;30678.079657610306;1060.7560261139981;1064.2869277584036;1066.1417558865187;1081.5349399758022;1166.9708257293635;15578.454754083552;15635.765463518057;15752.842471575366;15771.44799931997;16090;654187;660752;660800;660884;662479;57757;58300.41699583035;58323.12515312274;58357.83284334374;58360.70819645959;1238.3127364312893;1275.1265383212715;1298.1172802494395;1312.7899536074183;1369.274284629148;29149.85425072861;30414.84792576023;31045.0686479406;31188.68811311858;31796.841015794773;50367.74816125896;51349.74325128351;51405.22892156546;51473;51585;451267;569334;569557;570192.1490392522;571859;35704;36636;42052.056356056004;42652;43191.56808431875;37766.62233377631;45110.421135260105;45313.773431132635;45914;46511.302330464394;37203.44194837025;37333.81333093426;37661.05847353838;38230.044248894;38731.61268387393;37079.25841483209;38163.42754858624;38377.6162238374;39171;40320.39519407153;37773.24453510969;37996.43005354867;38151.23697525978;38444.807775960944;38781.64264250737;31413.68586314199;33726.66273337235;34689.82655086708;35524.28951421009;35540.756073537304;23743.71609672721;25140.371490712878;25329.240122796346;25429.745702543478;26758.464830703666 17:21:54 INFO - PROCESS | 1648 | |2;jquery.html;370.5793923896377;374.4420812988647;380.6250842919724;387.9069023434373;395.38714991762777;15736.685266294697;16692.165391730425;19782;22540.54918901623;22637.886810565964;20370.287039953604;20472.590548189026;21284.57430851382;21917.452063698416;22068.779312206872;5896;6006.159137720722;6077.78727744529;6154.3537928517535;6259.405356491126;6098.725557349908;6131.233595800525;6148.415900489446;6154.046122850943;6154.692265386735;4083.5712250213746;4246.3205887058075;6482.6434546099945;6798.796036118924;6865.725370985155;6679.198496180441;6689.297623749533;6769.390754832039;6840.623765692885;6860.759873404432;5837.620554663976;5840.3575606683235;5848.444397782247;5856.76572937082;5877.089051197071;25505;26438.339041523923;26482.33794155142;26589.069382571517;26946.326341841414;2309.480366917447;2562.525932702456;2685.731426857318;2776.319801648589;2855.6430446194227;14809.407623695039;15137.470188543348;15153.939224254307;15296.464623738117;15408.152551609657;1802.9368972086045;1828.3509354179341;1861.1810803246528;1894.1381671339507;1941.68932970724;109711;114004;114461;114652.52190954197;114824;10453.634122805664;10868.34789912592;10987.285826421257;11013.559457621606;11159;13692.726145477034;13701.314934253447;13701.862981370257;13704.3833027514;13706;8458.323334133254;8601.46256854217;8618;8634.352423568258;8735.34484913634;8738.388312818042;8745.60644770974;8775.912240877637;8818.95590522056;8939.016708162097;6428.678566071678;6566.212054553389;6574.178227721535;6587.901181482282;6688.996650502366;1762.8149044350414;1797.19126393121;1800.396867049527;1802.2070289072767;1808.21342715919;783.158105037076;800.6797281087612;821.8561751693429;826.995865020683;859.6932662353188;16441;19375.612487750164;20495.077721502566;20556.944305569323;20975;715.4348065028673;849.2569002123142;854.1799872122707;864.4251572704208;881.457903389411;10308.639197628047;10819;10891.509882055327;10933.070688991365;10943.726406839893;485666;491287;494698;546326;551760;128971;137695;139042;140555.29722351322;141864.29067854802;898.8651702244584;987.8221920054316;990.9653162139434;1032.1639472027682;1035.5857656937135;15717.842821572096;16073.598160046093;19394.739341943292;19865.404037878885;20115.59768804645;43620;55154.44845551654;56987.71506142443;57258;57643.423565763806;74340.62829685817;80940.04959206498;82323.39807659718;83137;84429.57785211035;7900.960495197717;8101.594920254081;8137.430379873352;8173.468724532886;8326.29226515753;12516.87483125157;12956.611301660589;12973.221606703628;13163;13365.933170334087;10626.787464250827;10984.121270298518;11118.944405278246;11244.831327530257;11375.54497820078;10961.12311015101;10967.616133435291;11024.724381890195;11301;11427.828582571101;11372.658820235407;11573;11634.767304653691;11674.497650704925;11711.590094346657;7914.722984695508;7983.081945576101;7984.84030319402;8031.317338026333;8089.514629122036;7663.042119735033;7877.724279650414;10810.729731756768;11065.731525037509;11145.164112691418 17:21:54 INFO - PROCESS | 1648 | |3;mootools.html;1355.2410650035974;1412.8799052080572;1515.8938874278801;1695.4150817967798;1892.2241880828872;7651.196624354446;8458.492490450577;8649.870251946219;8737.30101591871;8789.2968562515;7428.517146385481;8590.61342239599;8663.783405414852;8674.34942379321;8680;2307.2962746362355;2334.4980829121737;2338.0881456231996;2340.7659234076627;2340.8127349811975;2328.6157783965627;2362.6692263083182;2379.512199998996;2384.9284521464297;2389.8566086034807;2344.3201471573307;2367.2306500387444;2370.95258094838;2379.095943541452;2385.463270764073;2311.5030268492187;2336.929892103231;2351.1535847095033;2353.5175289065874;2357.9410514737096;2302.8714451823976;2335.346103091137;2345.085416687476;2348.495073559193;2352.788249057593;8599.656013759442;8614.310855131636;8683.348748843862;8720.389572729911;8720.607572659246;5098.949010509921;5300.0459917214885;5302.443243459458;5315.920261196085;5320.201969704536;3405.182756138509;3572.4819901114192;3632.400653892105;3644.69020133289;3659.249853779969;891.589868660417;1073.8872418396113;1108.2408550143177;1117.5250518529674;1123;11215.271007384494;11303.943480282629;11355.829662555068;11359.54561817518;11370.033547148503;4346.1525002624185;4420.182266280748;4584.472785629695;4593.058423023273;4602.125596136723;8936.46381217129;8943.508107054044;8946.463212207289;8948.284137269004;8951.462912225288;4458.684688017042;5158.174692049254;5160.380754309507;5170.922436163461;5173.120569503193;4325.134973005412;4328.5455027222315;4488.887777805516;4740.952590474051;4829.6860704054125;3735.6451137141926;3759.4548790425233;3831.731778775459;3833.9808300958316;3854.9807250964127;1162.8662703789003;1184.2716729211472;1184.8815118488083;1195.390350921019;1195.7010747313172;839.3746658739157;856.6787454704486;858.7976832434641;922.2069020642242;961.5144352102144;4888.706677599355;5803.941960580424;5975.312839023481;6026.6986650667295;6051.304100028465;925.7500474871881;927.4806108579218;942.8727121838601;944.7670789619475;968.4528241543505;3573.76770509916;3920;3971.4042893566198;4092.4884389451317;4093.324601440729;10248.64129755455;10679.839802402816;10688.412137332365;10692.251542391958;10739.463026848627;10700.839487407695;10703.678889633324;10710.03609675133;10710.357378557312;10718.839217411745;7927.682892684344;8031.518108913367;8129.593520323961;8221.301189398848;8249.257566818895;5754.079347304411;5838.1826544282985;5849.736761845812;5855.3266374367495;6008.309044459768;9759.804803904024;9880.110790028644;9966.651167209113;10010.499475026074;10066.899331006596;9080.500572468578;9596.13634772846;9969.35199212049;9984.800303994025;10073.84889226677;5938.8218353449465;7316.634168291671;7448.553086814808;7553.962230188814;7567.356774674215;6688.2977287386075;8211.260986511243;8248.340132789483;8262.504249745034;8335.958320208603;7482.513636613603;7549.660265288185;7553.433492487976;7567.735129270449;7633.160352361125;7308.524945878501;7350.926490735024;7435.144958329862;7477.102747670315;7562.584057876869;6843.076184715;6970.093887794555;7054.08296921397;7065.858682826212;7093.184283807428;4910.116179087799;5042.747862606929;5049.091163590589;5128.3076784633595;5253.448387919213;4446.555344465657;5048.570871475966;5074.847754567369;5108.948910510996;5280.339957505312 17:21:54 INFO - PROCESS | 1648 | |4;prototype.html;264.8463890943252;294.7111830406203;300.7819330985036;310.37769272608415;312.1167097115164;4449.288113901776;5282.313299271094;5289.4710528947135;5289.86775330616;5331.520163185317;4548.681592288541;4890.437599676038;4962.354893863801;4982.1566020572345;5129.563987061095;3557.2609090999913;3690.2435000824835;3814.87461198947;3982.38273067675;4009.358502639578;3779.376402893533;3974.8410063597566;4007.8998025049314;4015.0363912660896;4020.718549701522;3684.299983003221;3723.590405055442;3741.596901162064;3826.7129965252716;4016.297147999103;3935.1342704605086;3946.309395855714;3964.702647301464;3979.900502487432;3996.4014394242245;2685.462907418524;2858.9857050714822;3429.2798512312443;3435.055359776057;3453.723702103826;4895.290182923456;5183.989122121188;5195.168772996302;5204.557612602934;5215.061288967984;4169.291220492493;4216.894577635584;4317.985273460734;4557.225271703818;4571.405717256735;5468.453154684539;5508.862278443031;5521.558275337963;5562;5575.776968921238;3227.3383956288903;3285.1951271938287;3551.964480355189;3835.1562656215588;3883.1262965832743;5134.460881607452;5198.220266959948;5676.290463692038;5713.942860571424;5765.596408251424;3538.0270425633157;3580.6598373154507;3854.807259637007;3870.9806450967567;3871.2838124946975;3907.616354024043;3942.48747662802;3956.723029387973;3963.742356746802;4024.5371782245415;4298.871033868989;4445.666575006822;4526.298423744325;4538.704984176018;4604.815807367735;4439.866803995886;5469.206964990054;5471.398146203915;5536.52939500147;5585.134304182777;1192.5170306026073;1221.6090850927797;1285.7235694325766;1298.2794549025193;1355.606874006527;738.6602163005078;837.2799392522426;844.1937949258444;895.6059333893066;900.8918929728475;4202.936955945664;4272.978635106867;5156.74216289184;5200.453952335026;5309.893802124013;4762.76186190696;5119.286778007662;5616.410276920865;5662.348829884533;5665.915011274834;4326.437563116775;4826.758662066883;4838.226183576816;5097.898042039138;5125.051865404912;2565.5766798478044;2973.9851300743358;2996.565498002821;3028.6668466468673;3066.7853250272265;5294.655847369909;5537.418571050062;6017.939820601738;6023.27720673522;6071.635701857903;5036.345275114211;5499.917501237484;5896.056630939029;5929.229200204032;5944.919278252577;4766.046790641885;4780.928286075712;5118.436971933084;5146.974265128651;5988.431099045671;5125.026245013435;5417.268668729671;5480.232767412645;5771.826845194651;5779.306483222041;4953.356063711694;5041.773120209526;5676.716164191691;5705.771769129188;5724.942750572441;4982.302477653059;5016.573591244785;5971.58198926071;5982.910256346246;5997.91003134962;3247.5453436518433;3617.819109044485;3741.923232605821;3771.3971562086554;3776.3580191368083;4963.329950456787;5699.6865172414255;5709.9714501427225;5715.914261286167;5716.971415142898;5584.162375643686;5609.046462101371;5610.270664813548;5614.971925140511;5661.971690141688;4900.485449027943;5182.844514664566;5509.787846673726;5558.388577256418;5580.330360356783;4898.24077268024;4985.875353116201;5105.2342148678;5221.929504451502;5222.2688823565495;4448.310511870665;4463.308187230984;4670.556297151765;5452.345718513804;5519.60544265266;4375.190589740908;4413.7351758894565;4813.278008298783;5094.617903657167;5375.4087050425205 17:21:54 INFO - PROCESS | 1648 | |5;yui.html;669.9698513566889;715.2275542414193;732.7398773435431;732.7398773435434;781.5545139270616;15778.763318550216;16592.25334859931;16621.83378166218;16631.66736665266;16685.58286042848;14768.409263629443;14821.036632618872;14995.775063374058;15014.399424023026;15077.246137693099;858.6307887608326;887.8712586674928;897.5826240798027;899.2536194958202;900.3157600223811;874.8512752832002;874.8643960186181;876.8246350729879;878;878.4729162502512;924.4453328003211;926.1942110363992;926.3098991251527;926.393212445849;929.0523665860818;921.2998121427696;922.2437601167046;922.3635691372998;923.1507013547485;924.1590152960773;682.9214640316377;683.0539702512007;683.1528904158857;683.2996178916601;683.5693513086724;15685.372585096582;15715;15721;15726.606834829106;15755;14031.719365612631;14436.783448248285;14436.927815360961;14551.490697825631;14705;10342.224333175041;11890.405479726065;11956.440255281335;12120.636380908587;12366.072544559194;3787.829547670334;3830;3968.4840970673627;3996.8800935971967;4039.2931237033695;130767.34616326858;130796;132013;134355;135083;11191.888081119085;11327.716807079723;11373.601923932789;11747.88252117485;11770;22852.31443056711;22901.19845805389;22941.082356705545;22949.31152065407;22963.7703622965;8079.878801817978;8165.183481651906;8265.380096492787;8309.45988510757;8433.241008309282;4518.390017347681;4554.362389265505;4569.200389931749;4630.328602352707;4748.216544270226;3612.6026137124895;3743.513343265358;3744.4570537271943;3745.1573395985683;3745.400735882246;540.6969204217817;595.4458862369213;610.8961476548998;620.7734177025353;623.0280762011187;648.5557393185683;657.0641855286892;681.5127184063418;699.6396855619361;705.6154395854272;8725.67043683309;9063.546822658973;9368.531573421302;9636.373635713797;9720;568.4599630351183;621.6363427395024;651.8989556618732;679.8776220280298;698.8148140742708;3562.8218589070443;3673.1000904778502;4750.61995040396;4780.163471392562;4811.109944660249;156380.21809891105;165400;166711;167008.16495917444;170252;108159;109761;113210.43394782973;119660;138501;71209.64395177992;100511.49744251232;104585.49559548443;110180.449097754;113370;9429.57566909477;9693.757656058655;9987.400755954375;10044.397336159855;10221.131203847755;54633.72683136559;59675.40324596698;60388;60891.39108608857;61759.80856473511;86226.70659940231;88533;91265.54367228122;91488;92179.17312403194;13058.281794501394;13179.868201317864;13224.867751322363;13396.866031339561;13446.529371471952;15028.624284392978;15193.544193674208;15327.38690452414;15511.689766204838;15691.372345106069;13657;14135.858641413866;14316.427342906583;14407.207603581903;14645.70708585801;13889.02776805614;14005.089669171472;14301.141931484144;14373.99382043247;14467.348969296618;13827.30863456803;13981.370838312097;14103.139236973211;14141.575752727435;14286.428542858584;11024.779504409706;11212.383318917538;11271.774564508827;11823.940880295544;12010.279383237033;10856.76325003649;11428.48571814287;11493.770124597628;11629.941850290694;11661.941690291833 17:21:54 INFO - PROCESS | 1648 | __end_tp_report 17:21:54 INFO - PROCESS | 1648 | __start_cc_report 17:21:54 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1452 17:21:54 INFO - PROCESS | 1648 | __end_cc_report 17:21:54 INFO - PROCESS | 1648 | __startTimestamp1472516514916__endTimestamp 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | ------- Summary: start ------- 17:21:54 INFO - PROCESS | 1648 | Number of tests: 6 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5795.83 Median:6080.45 stddev:672.50 (11.1%) stddev-sans-first:646.11 17:21:54 INFO - PROCESS | 1648 | Values: 3328.7 3368.0 3378.8 3386.9 3403.9 5657.6 5726.8 5771.8 5883.4 5883.5 5724.6 5735.5 5775.9 5782.7 5818.3 5108.5 5162.5 5180.3 5186.3 5187.3 5160.6 5274.2 5279.2 5282.3 5294.8 5255.3 5279.5 5283.3 5290.8 5294.0 4249.7 5049.3 5111.7 5179.9 5203.2 5038.7 5044.9 5067.8 5088.5 5107.5 5786.1 5817.9 5831.7 5838.8 5852.9 6117.6 6175.9 6228.3 6256.7 6258.9 6212.6 6218.6 6232.1 6254.5 6261.2 5943.9 5958.2 5963.0 5969.4 5972.9 6185.6 6255.0 6287.4 6391.7 6393.9 6250.0 6271.8 6276.2 6287.0 6299.1 6295.1 6315.3 6336.2 6344.4 6352.6 5939.5 6122.4 6169.8 6222.3 6265.8 6270.1 6282.1 6318.5 6322.5 6337.9 4986.8 4998.5 5004.5 5005.6 5005.8 4348.4 4351.7 4425.0 4462.8 4486.0 5739.5 5843.3 5852.5 5853.9 5857.3 6248.4 6254.2 6270.0 6284.7 6284.9 5639.6 5687.8 5741.7 5847.9 5848.4 5454.1 5515.6 5521.1 5521.5 5539.8 6383.5 6420.5 6420.6 6431.3 6434.9 6209.3 6256.6 6289.4 6312.2 6315.5 6340.2 6420.0 6428.4 6438.4 6461.2 6267.9 6273.5 6274.2 6295.2 6295.9 6213.3 6251.9 6299.8 6307.8 6325.0 6178.9 6203.4 6275.9 6277.5 6283.7 6228.6 6259.7 6261.4 6263.4 6264.0 5791.1 6066.2 6066.8 6084.6 6086.9 5226.7 6095.2 6120.3 6155.6 6261.3 6028.9 6072.3 6080.5 6099.4 6102.0 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:69146.96 Median:38411.21 stddev:153188.90 (398.8%) stddev-sans-first:153531.82 17:21:54 INFO - PROCESS | 1648 | Values: 455.4 486.8 506.0 531.6 560.4 30233.4 33578.7 39641.0 40965.0 42859.9 42161.4 42398.0 42417.4 42499.0 42505.1 9632.7 9829.9 9844.6 9851.9 9854.2 9722.9 9795.3 9814.2 9817.7 9839.5 9814.4 9836.5 9862.9 9868.9 9875.4 9757.3 9818.0 9818.6 9826.2 9836.9 9728.9 9796.2 9800.0 9804.8 9812.2 39792.2 40148.0 40183.0 40193.6 40247.6 3013.9 3451.8 3500.9 3604.7 4078.5 31000.7 31556.9 31855.2 31901.0 32140.9 3270.9 3280.4 3322.6 3424.0 3461.3 495901.4 496380.0 498581.0 499266.5 500636.0 32752.3 33637.8 33807.0 35019.8 59043.4 26494.9 34776.0 35357.0 35408.5 35445.5 16664.3 16859.7 16901.6 16941.0 16952.7 12904.9 13028.0 13033.9 13038.3 13046.8 18712.4 18957.8 18974.8 18975.0 18976.6 7941.2 8039.4 8046.8 8055.2 8058.1 812.5 882.0 1006.6 1026.7 1056.3 28936.3 29847.6 30159.7 30428.7 30678.1 1060.8 1064.3 1066.1 1081.5 1167.0 15578.5 15635.8 15752.8 15771.4 16090.0 654187.0 660752.0 660800.0 660884.0 662479.0 57757.0 58300.4 58323.1 58357.8 58360.7 1238.3 1275.1 1298.1 1312.8 1369.3 29149.9 30414.8 31045.1 31188.7 31796.8 50367.7 51349.7 51405.2 51473.0 51585.0 451267.0 569334.0 569557.0 570192.1 571859.0 35704.0 36636.0 42052.1 42652.0 43191.6 37766.6 45110.4 45313.8 45914.0 46511.3 37203.4 37333.8 37661.1 38230.0 38731.6 37079.3 38163.4 38377.6 39171.0 40320.4 37773.2 37996.4 38151.2 38444.8 38781.6 31413.7 33726.7 34689.8 35524.3 35540.8 23743.7 25140.4 25329.2 25429.7 26758.5 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:33115.92 Median:14637.40 stddev:87000.46 (594.4%) stddev-sans-first:87209.77 17:21:54 INFO - PROCESS | 1648 | Values: 370.6 374.4 380.6 387.9 395.4 15736.7 16692.2 19782.0 22540.5 22637.9 20370.3 20472.6 21284.6 21917.5 22068.8 5896.0 6006.2 6077.8 6154.4 6259.4 6098.7 6131.2 6148.4 6154.0 6154.7 4083.6 4246.3 6482.6 6798.8 6865.7 6679.2 6689.3 6769.4 6840.6 6860.8 5837.6 5840.4 5848.4 5856.8 5877.1 25505.0 26438.3 26482.3 26589.1 26946.3 2309.5 2562.5 2685.7 2776.3 2855.6 14809.4 15137.5 15153.9 15296.5 15408.2 1802.9 1828.4 1861.2 1894.1 1941.7 109711.0 114004.0 114461.0 114652.5 114824.0 10453.6 10868.3 10987.3 11013.6 11159.0 13692.7 13701.3 13701.9 13704.4 13706.0 8458.3 8601.5 8618.0 8634.4 8735.3 8738.4 8745.6 8775.9 8819.0 8939.0 6428.7 6566.2 6574.2 6587.9 6689.0 1762.8 1797.2 1800.4 1802.2 1808.2 783.2 800.7 821.9 827.0 859.7 16441.0 19375.6 20495.1 20556.9 20975.0 715.4 849.3 854.2 864.4 881.5 10308.6 10819.0 10891.5 10933.1 10943.7 485666.0 491287.0 494698.0 546326.0 551760.0 128971.0 137695.0 139042.0 140555.3 141864.3 898.9 987.8 991.0 1032.2 1035.6 15717.8 16073.6 19394.7 19865.4 20115.6 43620.0 55154.4 56987.7 57258.0 57643.4 74340.6 80940.0 82323.4 83137.0 84429.6 7901.0 8101.6 8137.4 8173.5 8326.3 12516.9 12956.6 12973.2 13163.0 13365.9 10626.8 10984.1 11118.9 11244.8 11375.5 10961.1 10967.6 11024.7 11301.0 11427.8 11372.7 11573.0 11634.8 11674.5 11711.6 7914.7 7983.1 7984.8 8031.3 8089.5 7663.0 7877.7 10810.7 11065.7 11145.2 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5604.18 Median:5103.95 stddev:3119.92 (61.1%) stddev-sans-first:3112.33 17:21:54 INFO - PROCESS | 1648 | Values: 1355.2 1412.9 1515.9 1695.4 1892.2 7651.2 8458.5 8649.9 8737.3 8789.3 7428.5 8590.6 8663.8 8674.3 8680.0 2307.3 2334.5 2338.1 2340.8 2340.8 2328.6 2362.7 2379.5 2384.9 2389.9 2344.3 2367.2 2371.0 2379.1 2385.5 2311.5 2336.9 2351.2 2353.5 2357.9 2302.9 2335.3 2345.1 2348.5 2352.8 8599.7 8614.3 8683.3 8720.4 8720.6 5098.9 5300.0 5302.4 5315.9 5320.2 3405.2 3572.5 3632.4 3644.7 3659.2 891.6 1073.9 1108.2 1117.5 1123.0 11215.3 11303.9 11355.8 11359.5 11370.0 4346.2 4420.2 4584.5 4593.1 4602.1 8936.5 8943.5 8946.5 8948.3 8951.5 4458.7 5158.2 5160.4 5170.9 5173.1 4325.1 4328.5 4488.9 4741.0 4829.7 3735.6 3759.5 3831.7 3834.0 3855.0 1162.9 1184.3 1184.9 1195.4 1195.7 839.4 856.7 858.8 922.2 961.5 4888.7 5803.9 5975.3 6026.7 6051.3 925.8 927.5 942.9 944.8 968.5 3573.8 3920.0 3971.4 4092.5 4093.3 10248.6 10679.8 10688.4 10692.3 10739.5 10700.8 10703.7 10710.0 10710.4 10718.8 7927.7 8031.5 8129.6 8221.3 8249.3 5754.1 5838.2 5849.7 5855.3 6008.3 9759.8 9880.1 9966.7 10010.5 10066.9 9080.5 9596.1 9969.4 9984.8 10073.8 5938.8 7316.6 7448.6 7554.0 7567.4 6688.3 8211.3 8248.3 8262.5 8336.0 7482.5 7549.7 7553.4 7567.7 7633.2 7308.5 7350.9 7435.1 7477.1 7562.6 6843.1 6970.1 7054.1 7065.9 7093.2 4910.1 5042.7 5049.1 5128.3 5253.4 4446.6 5048.6 5074.8 5108.9 5280.3 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4410.72 Median:4926.92 stddev:1367.89 (27.8%) stddev-sans-first:1335.87 17:21:54 INFO - PROCESS | 1648 | Values: 264.8 294.7 300.8 310.4 312.1 4449.3 5282.3 5289.5 5289.9 5331.5 4548.7 4890.4 4962.4 4982.2 5129.6 3557.3 3690.2 3814.9 3982.4 4009.4 3779.4 3974.8 4007.9 4015.0 4020.7 3684.3 3723.6 3741.6 3826.7 4016.3 3935.1 3946.3 3964.7 3979.9 3996.4 2685.5 2859.0 3429.3 3435.1 3453.7 4895.3 5184.0 5195.2 5204.6 5215.1 4169.3 4216.9 4318.0 4557.2 4571.4 5468.5 5508.9 5521.6 5562.0 5575.8 3227.3 3285.2 3552.0 3835.2 3883.1 5134.5 5198.2 5676.3 5713.9 5765.6 3538.0 3580.7 3854.8 3871.0 3871.3 3907.6 3942.5 3956.7 3963.7 4024.5 4298.9 4445.7 4526.3 4538.7 4604.8 4439.9 5469.2 5471.4 5536.5 5585.1 1192.5 1221.6 1285.7 1298.3 1355.6 738.7 837.3 844.2 895.6 900.9 4202.9 4273.0 5156.7 5200.5 5309.9 4762.8 5119.3 5616.4 5662.3 5665.9 4326.4 4826.8 4838.2 5097.9 5125.1 2565.6 2974.0 2996.6 3028.7 3066.8 5294.7 5537.4 6017.9 6023.3 6071.6 5036.3 5499.9 5896.1 5929.2 5944.9 4766.0 4780.9 5118.4 5147.0 5988.4 5125.0 5417.3 5480.2 5771.8 5779.3 4953.4 5041.8 5676.7 5705.8 5724.9 4982.3 5016.6 5971.6 5982.9 5997.9 3247.5 3617.8 3741.9 3771.4 3776.4 4963.3 5699.7 5710.0 5715.9 5717.0 5584.2 5609.0 5610.3 5615.0 5662.0 4900.5 5182.8 5509.8 5558.4 5580.3 4898.2 4985.9 5105.2 5221.9 5222.3 4448.3 4463.3 4670.6 5452.3 5519.6 4375.2 4413.7 4813.3 5094.6 5375.4 17:21:54 INFO - PROCESS | 1648 | 17:21:54 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:25489.41 Median:13263.30 stddev:41181.08 (310.5%) stddev-sans-first:41254.43 17:21:54 INFO - PROCESS | 1648 | Values: 670.0 715.2 732.7 732.7 781.6 15778.8 16592.3 16621.8 16631.7 16685.6 14768.4 14821.0 14995.8 15014.4 15077.2 858.6 887.9 897.6 899.3 900.3 874.9 874.9 876.8 878.0 878.5 924.4 926.2 926.3 926.4 929.1 921.3 922.2 922.4 923.2 924.2 682.9 683.1 683.2 683.3 683.6 15685.4 15715.0 15721.0 15726.6 15755.0 14031.7 14436.8 14436.9 14551.5 14705.0 10342.2 11890.4 11956.4 12120.6 12366.1 3787.8 3830.0 3968.5 3996.9 4039.3 130767.3 130796.0 132013.0 134355.0 135083.0 11191.9 11327.7 11373.6 11747.9 11770.0 22852.3 22901.2 22941.1 22949.3 22963.8 8079.9 8165.2 8265.4 8309.5 8433.2 4518.4 4554.4 4569.2 4630.3 4748.2 3612.6 3743.5 3744.5 3745.2 3745.4 540.7 595.4 610.9 620.8 623.0 648.6 657.1 681.5 699.6 705.6 8725.7 9063.5 9368.5 9636.4 9720.0 568.5 621.6 651.9 679.9 698.8 3562.8 3673.1 4750.6 4780.2 4811.1 156380.2 165400.0 166711.0 167008.2 170252.0 108159.0 109761.0 113210.4 119660.0 138501.0 71209.6 100511.5 104585.5 110180.4 113370.0 9429.6 9693.8 9987.4 10044.4 10221.1 54633.7 59675.4 60388.0 60891.4 61759.8 86226.7 88533.0 91265.5 91488.0 92179.2 13058.3 13179.9 13224.9 13396.9 13446.5 15028.6 15193.5 15327.4 15511.7 15691.4 13657.0 14135.9 14316.4 14407.2 14645.7 13889.0 14005.1 14301.1 14374.0 14467.3 13827.3 13981.4 14103.1 14141.6 14286.4 11024.8 11212.4 11271.8 11823.9 12010.3 10856.8 11428.5 11493.8 11629.9 11661.9 17:21:54 INFO - PROCESS | 1648 | -------- Summary: end -------- 17:21:54 INFO - PROCESS | 1648 | 17:21:55 INFO - TEST-INFO | 1648: exit 0 17:21:55 INFO - TEST-OK | dromaeo_css | took 1118238ms 17:21:55 INFO - TEST-START | kraken 17:21:55 INFO - operating with platform_type : mac_ 17:21:55 INFO - Initialising browser for kraken test... 17:21:55 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpc5c2Pn/profile http://localhost:49266/getInfo.html) 17:21:57 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 17:21:57 INFO - PROCESS | 1654 | colorDepth:24 17:21:57 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 17:21:57 INFO - PROCESS | 1654 | __metrics 17:21:57 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:21:57 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:58 INFO - TEST-INFO | 1654: exit 0 17:21:58 INFO - Browser initialized. 17:21:58 INFO - Running cycle 1/1 for kraken test... 17:21:58 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpc5c2Pn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:22:10 INFO - PROCESS | 1655 | RSS: Main: 231669760 17:22:10 INFO - PROCESS | 1655 | 17:22:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 17:22:14 INFO - PROCESS | 1655 | RSS: Main: 297238528 17:22:14 INFO - PROCESS | 1655 | 17:22:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 17:22:17 INFO - PROCESS | 1655 | RSS: Main: 294006784 17:22:17 INFO - PROCESS | 1655 | 17:22:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 17:22:21 INFO - PROCESS | 1655 | RSS: Main: 384688128 17:22:21 INFO - PROCESS | 1655 | 17:22:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 17:22:24 INFO - PROCESS | 1655 | RSS: Main: 283062272 17:22:24 INFO - PROCESS | 1655 | 17:22:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:22:26 INFO - PROCESS | 1655 | RSS: Main: 287666176 17:22:26 INFO - PROCESS | 1655 | 17:22:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 17:22:30 INFO - PROCESS | 1655 | RSS: Main: 325111808 17:22:30 INFO - PROCESS | 1655 | 17:22:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 17:22:33 INFO - PROCESS | 1655 | RSS: Main: 326127616 17:22:33 INFO - PROCESS | 1655 | 17:22:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 17:22:37 INFO - PROCESS | 1655 | RSS: Main: 324284416 17:22:37 INFO - PROCESS | 1655 | 17:22:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:22:39 INFO - PROCESS | 1655 | RSS: Main: 300666880 17:22:39 INFO - PROCESS | 1655 | 17:22:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:22:42 INFO - PROCESS | 1655 | RSS: Main: 300756992 17:22:42 INFO - PROCESS | 1655 | 17:22:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:22:44 INFO - PROCESS | 1655 | RSS: Main: 305909760 17:22:44 INFO - PROCESS | 1655 | 17:22:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:22:48 INFO - PROCESS | 1655 | RSS: Main: 313741312 17:22:48 INFO - PROCESS | 1655 | 17:22:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:22:52 INFO - PROCESS | 1655 | RSS: Main: 316215296 17:22:52 INFO - PROCESS | 1655 | 17:22:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 17:22:54 INFO - PROCESS | 1655 | RSS: Main: 359682048 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | __start_tp_report 17:22:54 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 17:22:54 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 17:22:54 INFO - PROCESS | 1655 | |i|pagename|runs| 17:22:54 INFO - PROCESS | 1655 | |0;ai-astar;95;114;106;113;114;112;116;114;112;112 17:22:54 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;114;130;157;107;132;121;108;155;108 17:22:54 INFO - PROCESS | 1655 | |2;audio-dft;184;185;195;258;197;183;178;179;182;182 17:22:54 INFO - PROCESS | 1655 | |3;audio-fft;74;75;73;151;57;58;58;92;90;56 17:22:54 INFO - PROCESS | 1655 | |4;audio-oscillator;82;90;136;69;71;70;114;91;98;149 17:22:54 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;87;90;86;89;86;90;87;90;85 17:22:54 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;94;95;96;94;101;94;95;94;95 17:22:54 INFO - PROCESS | 1655 | |7;imaging-desaturate;85;84;86;84;85;86;85;86;84;84 17:22:54 INFO - PROCESS | 1655 | |8;json-parse-financial;56;55;56;54;54;54;55;54;54;54 17:22:54 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;66;68;73;81;75;72;68;96;101;82 17:22:54 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;85;88;86;87;84;84;86;89;86 17:22:54 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;142;148;152;139;139;135;160;146;219;133 17:22:54 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;168;168;170;170;169;170;170;168;168;168 17:22:54 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;68;69;73;76;67;68;67;67;67 17:22:54 INFO - PROCESS | 1655 | __end_tp_report 17:22:54 INFO - PROCESS | 1655 | __start_cc_report 17:22:54 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2119 17:22:54 INFO - PROCESS | 1655 | __end_cc_report 17:22:54 INFO - PROCESS | 1655 | __startTimestamp1472516574087__endTimestamp 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | ------- Summary: start ------- 17:22:54 INFO - PROCESS | 1655 | Number of tests: 14 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:110.80 Median:114.00 stddev:6.14 (5.4%) stddev-sans-first:2.79 17:22:54 INFO - PROCESS | 1655 | Values: 95.0 114.0 106.0 113.0 114.0 112.0 116.0 114.0 112.0 112.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:131.00 stddev:19.06 (14.5%) stddev-sans-first:19.47 17:22:54 INFO - PROCESS | 1655 | Values: 142.0 114.0 130.0 157.0 107.0 132.0 121.0 108.0 155.0 108.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:192.30 Median:184.50 stddev:23.92 (13.0%) stddev-sans-first:25.18 17:22:54 INFO - PROCESS | 1655 | Values: 184.0 185.0 195.0 258.0 197.0 183.0 178.0 179.0 182.0 182.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:78.40 Median:73.50 stddev:28.76 (39.1%) stddev-sans-first:30.46 17:22:54 INFO - PROCESS | 1655 | Values: 74.0 75.0 73.0 151.0 57.0 58.0 58.0 92.0 90.0 56.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:76.50 stddev:27.92 (36.5%) stddev-sans-first:29.08 17:22:54 INFO - PROCESS | 1655 | Values: 82.0 90.0 136.0 69.0 71.0 70.0 114.0 91.0 98.0 149.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.91 (2.1%) stddev-sans-first:1.99 17:22:54 INFO - PROCESS | 1655 | Values: 89.0 87.0 90.0 86.0 89.0 86.0 90.0 87.0 90.0 85.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:94.50 stddev:2.15 (2.3%) stddev-sans-first:2.24 17:22:54 INFO - PROCESS | 1655 | Values: 94.0 94.0 95.0 96.0 94.0 101.0 94.0 95.0 94.0 95.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 17:22:54 INFO - PROCESS | 1655 | Values: 85.0 84.0 86.0 84.0 85.0 86.0 85.0 86.0 84.0 84.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.50 stddev:0.84 (1.5%) stddev-sans-first:0.73 17:22:54 INFO - PROCESS | 1655 | Values: 56.0 55.0 56.0 54.0 54.0 54.0 55.0 54.0 54.0 54.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:74.00 stddev:11.98 (16.2%) stddev-sans-first:11.86 17:22:54 INFO - PROCESS | 1655 | Values: 66.0 68.0 73.0 81.0 75.0 72.0 68.0 96.0 101.0 82.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.00 stddev:1.60 (1.9%) stddev-sans-first:1.69 17:22:54 INFO - PROCESS | 1655 | Values: 86.0 85.0 88.0 86.0 87.0 84.0 84.0 86.0 89.0 86.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:147.00 stddev:25.14 (17.1%) stddev-sans-first:26.44 17:22:54 INFO - PROCESS | 1655 | Values: 142.0 148.0 152.0 139.0 139.0 135.0 160.0 146.0 219.0 133.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.50 stddev:0.99 (0.6%) stddev-sans-first:1.00 17:22:54 INFO - PROCESS | 1655 | Values: 168.0 168.0 170.0 170.0 169.0 170.0 170.0 168.0 168.0 168.0 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:68.00 stddev:3.06 (4.5%) stddev-sans-first:3.22 17:22:54 INFO - PROCESS | 1655 | Values: 68.0 68.0 69.0 73.0 76.0 67.0 68.0 67.0 67.0 67.0 17:22:54 INFO - PROCESS | 1655 | -------- Summary: end -------- 17:22:54 INFO - PROCESS | 1655 | 17:22:54 INFO - TEST-INFO | 1655: exit 0 17:22:54 INFO - TEST-OK | kraken | took 59387ms 17:22:54 INFO - SUITE-END | took 1177s 17:22:55 INFO - Completed test suite (00:19:37) 17:22:55 INFO - javascript benchmark 17:22:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.6504916983718, 3367.966320336796, 3378.7634865559407, 3386.949195762064, 3403.9319213615713, 5657.575681823859, 5726.799562015331, 5771.769129234831, 5883.352831188566, 5883.47048765611, 5724.570657200717, 5735.483806457418, 5775.855603609912, 5782.681952492611, 5818.301803783552, 5108.4636113208135, 5162.483751624826, 5180.300659410969, 5186.32577764891, 5187.325647665798, 5160.56135228506, 5274.208868669692, 5279.234510995903, 5282.339707536558, 5294.841154765363, 5255.264263003182, 5279.472052794728, 5283.339582552181, 5290.761915713783, 5294.020606187868, 4249.702520823544, 5049.318342023853, 5111.693298402108, 5179.896402071938, 5203.245529398254, 5038.697678139323, 5044.949550504484, 5067.847964561069, 5088.491150884919, 5107.540321371057, 5786.132080187964, 5817.912731309034, 5831.708414579254, 5838.824835254949, 5852.941470585282, 6117.5717699761035, 6175.938240617581, 6228.252609686821, 6256.6871656417, 6258.874822503524, 6212.627242365473, 6218.626882387026, 6232.065190221458, 6254.530910181709, 6261.186045814061, 5943.940560594425, 5958.19564358806, 5962.986292330314, 5969.432903874168, 5972.940270597239, 6185.597936134137, 6255.030470277115, 6287.402696743802, 6391.680415979183, 6393.904091438632, 6249.96875015622, 6271.811845644638, 6276.152719382825, 6287.025511045796, 6299.118123462627, 6295.087212354275, 6315.305316415191, 6336.20797400325, 6344.397282258178, 6352.587081839758, 5939.46544810969, 6122.418370254819, 6169.784057557964, 6222.315545290015, 6265.843353916189, 6270.122182894398, 6282.057691346335, 6318.494520438354, 6322.494200464043, 6337.904931426032, 4986.750662466862, 4998.525140111684, 5004.549590536869, 5005.599552035828, 5005.7997680092385, 4348.412964249786, 4351.717138385995, 4425.004374015821, 4462.754548499799, 4485.955140448554, 5739.540836733051, 5843.298804143445, 5852.473277405054, 5853.88292234153, 5857.26784151981, 6248.375162483715, 6254.249490061222, 6270.0, 6284.654344011032, 6284.937150628527, 5639.6334238274385, 5687.800926967537, 5741.741621627037, 5847.94152058474, 5848.356680765112, 5454.127339625641, 5515.641483303572, 5521.061419558685, 5521.503064724194, 5539.806106786243, 6383.489320854321, 6420.454261387835, 6420.550561460653, 6431.260405053479, 6434.903476447764, 6209.285932117864, 6256.624602523863, 6289.40250676185, 6312.210973628296, 6315.4947604192475, 6340.207474065742, 6420.0048992406255, 6428.357164283534, 6438.3883531064475, 6461.19235095613, 6267.9373206267355, 6273.52948528853, 6274.215723034621, 6295.18162638854, 6295.874082518232, 6213.316535181036, 6251.874962500816, 6299.779507717208, 6307.810765677037, 6325.019621958604, 6178.876422471615, 6203.379662033761, 6275.905861411991, 6277.529185311028, 6283.654399008098, 6228.56400051992, 6259.749610015549, 6261.405166509175, 6263.3736626337, 6263.9664455364355, 5791.13133030032, 6066.15073889647, 6066.757329706762, 6084.6349219047, 6086.908696369469, 5226.712530810842, 6095.238095238095, 6120.265568131853, 6155.569110162246, 6261.279952805304, 6028.90956635642, 6072.301685306246, 6080.452759251689, 6099.420555047263, 6102.0], "value": 5750.711590615698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.38295609449193, 486.8271763523949, 505.9797608095676, 531.5720844720001, 560.3807792389407, 30233.395332093372, 33578.66421335786, 39641.0, 40964.99999999996, 42859.92850178747, 42161.36757948626, 42398.0, 42417.36373954393, 42499.0, 42505.14989700204, 9632.662856799996, 9829.852552211687, 9844.556994935208, 9851.90148098517, 9854.211663066937, 9722.854157187612, 9795.314327997043, 9814.165795907356, 9817.705468835911, 9839.508024598777, 9814.362066465692, 9836.458994755285, 9862.90137098627, 9868.851967220497, 9875.358101723401, 9757.268204884662, 9817.950910245474, 9818.625392445063, 9826.16477599405, 9836.852447213298, 9728.90271097287, 9796.167325777318, 9799.951000245024, 9804.754881127958, 9812.215022798231, 39792.20415591672, 40147.99630009244, 40183.0, 40193.59806401928, 40247.59752402468, 3013.9397212055637, 3451.758376913617, 3500.859965601373, 3604.6575575320303, 4078.490188726409, 31000.689993100004, 31556.895508657086, 31855.203619909455, 31901.0, 32140.87506937246, 3270.869165233388, 3280.3767284215924, 3322.5514555534937, 3424.0, 3461.2904354607244, 495901.4098590148, 496380.0, 498581.0, 499266.503667483, 500636.0, 32752.344953100805, 33637.83181084079, 33806.985790426326, 35019.824900875334, 59043.40956590465, 26494.86752566225, 34776.0, 35357.0, 35408.46887296693, 35445.468317975254, 16664.33342666304, 16859.662806743796, 16901.577460563585, 16941.0, 16952.74570881438, 12904.935475322563, 13028.022898282667, 13033.869661303455, 13038.282894440898, 13046.804297935538, 18712.438626841216, 18957.81042189588, 18974.81025189758, 18975.0, 18976.620467590572, 7941.166177551389, 8039.35685145187, 8046.839063218703, 8055.1542088079905, 8058.11360750317, 812.5206128384282, 881.9514926679094, 1006.5772375602264, 1026.6663334416196, 1056.313396292416, 28936.27659308492, 29847.552286715716, 30159.69840301569, 30428.695713043027, 30678.079657610306, 1060.7560261139981, 1064.2869277584036, 1066.1417558865187, 1081.5349399758022, 1166.9708257293635, 15578.454754083552, 15635.765463518057, 15752.842471575366, 15771.44799931997, 16090.0, 654187.0, 660752.0, 660800.0, 660884.0, 662479.0, 57757.0, 58300.41699583035, 58323.12515312274, 58357.83284334374, 58360.70819645959, 1238.3127364312893, 1275.1265383212715, 1298.1172802494395, 1312.7899536074183, 1369.274284629148, 29149.85425072861, 30414.84792576023, 31045.0686479406, 31188.68811311858, 31796.841015794773, 50367.74816125896, 51349.74325128351, 51405.22892156546, 51473.0, 51585.0, 451267.0, 569334.0, 569557.0, 570192.1490392522, 571859.0, 35704.0, 36636.0, 42052.056356056004, 42652.0, 43191.56808431875, 37766.62233377631, 45110.421135260105, 45313.773431132635, 45914.0, 46511.302330464394, 37203.44194837025, 37333.81333093426, 37661.05847353838, 38230.044248894, 38731.61268387393, 37079.25841483209, 38163.42754858624, 38377.6162238374, 39171.0, 40320.39519407153, 37773.24453510969, 37996.43005354867, 38151.23697525978, 38444.807775960944, 38781.64264250737, 31413.68586314199, 33726.66273337235, 34689.82655086708, 35524.28951421009, 35540.756073537304, 23743.71609672721, 25140.371490712878, 25329.240122796346, 25429.745702543478, 26758.464830703666], "value": 19743.008143801733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.5793923896377, 374.4420812988647, 380.6250842919724, 387.9069023434373, 395.38714991762777, 15736.685266294697, 16692.165391730425, 19782.0, 22540.54918901623, 22637.886810565964, 20370.287039953604, 20472.590548189026, 21284.57430851382, 21917.452063698416, 22068.779312206872, 5896.0, 6006.159137720722, 6077.78727744529, 6154.3537928517535, 6259.405356491126, 6098.725557349908, 6131.233595800525, 6148.415900489446, 6154.046122850943, 6154.692265386735, 4083.5712250213746, 4246.3205887058075, 6482.6434546099945, 6798.796036118924, 6865.725370985155, 6679.198496180441, 6689.297623749533, 6769.390754832039, 6840.623765692885, 6860.759873404432, 5837.620554663976, 5840.3575606683235, 5848.444397782247, 5856.76572937082, 5877.089051197071, 25505.0, 26438.339041523923, 26482.33794155142, 26589.069382571517, 26946.326341841414, 2309.480366917447, 2562.525932702456, 2685.731426857318, 2776.319801648589, 2855.6430446194227, 14809.407623695039, 15137.470188543348, 15153.939224254307, 15296.464623738117, 15408.152551609657, 1802.9368972086045, 1828.3509354179341, 1861.1810803246528, 1894.1381671339507, 1941.68932970724, 109711.0, 114004.0, 114461.0, 114652.52190954197, 114824.0, 10453.634122805664, 10868.34789912592, 10987.285826421257, 11013.559457621606, 11159.0, 13692.726145477034, 13701.314934253447, 13701.862981370257, 13704.3833027514, 13706.0, 8458.323334133254, 8601.46256854217, 8618.0, 8634.352423568258, 8735.34484913634, 8738.388312818042, 8745.60644770974, 8775.912240877637, 8818.95590522056, 8939.016708162097, 6428.678566071678, 6566.212054553389, 6574.178227721535, 6587.901181482282, 6688.996650502366, 1762.8149044350414, 1797.19126393121, 1800.396867049527, 1802.2070289072767, 1808.21342715919, 783.158105037076, 800.6797281087612, 821.8561751693429, 826.995865020683, 859.6932662353188, 16441.0, 19375.612487750164, 20495.077721502566, 20556.944305569323, 20975.0, 715.4348065028673, 849.2569002123142, 854.1799872122707, 864.4251572704208, 881.457903389411, 10308.639197628047, 10819.0, 10891.509882055327, 10933.070688991365, 10943.726406839893, 485666.0, 491287.0, 494698.0, 546326.0, 551760.0, 128971.0, 137695.0, 139042.0, 140555.29722351322, 141864.29067854802, 898.8651702244584, 987.8221920054316, 990.9653162139434, 1032.1639472027682, 1035.5857656937135, 15717.842821572096, 16073.598160046093, 19394.739341943292, 19865.404037878885, 20115.59768804645, 43620.0, 55154.44845551654, 56987.71506142443, 57258.0, 57643.423565763806, 74340.62829685817, 80940.04959206498, 82323.39807659718, 83137.0, 84429.57785211035, 7900.960495197717, 8101.594920254081, 8137.430379873352, 8173.468724532886, 8326.29226515753, 12516.87483125157, 12956.611301660589, 12973.221606703628, 13163.0, 13365.933170334087, 10626.787464250827, 10984.121270298518, 11118.944405278246, 11244.831327530257, 11375.54497820078, 10961.12311015101, 10967.616133435291, 11024.724381890195, 11301.0, 11427.828582571101, 11372.658820235407, 11573.0, 11634.767304653691, 11674.497650704925, 11711.590094346657, 7914.722984695508, 7983.081945576101, 7984.84030319402, 8031.317338026333, 8089.514629122036, 7663.042119735033, 7877.724279650414, 10810.729731756768, 11065.731525037509, 11145.164112691418], "value": 9732.08479679855, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.2410650035974, 1412.8799052080572, 1515.8938874278801, 1695.4150817967798, 1892.2241880828872, 7651.196624354446, 8458.492490450577, 8649.870251946219, 8737.30101591871, 8789.2968562515, 7428.517146385481, 8590.61342239599, 8663.783405414852, 8674.34942379321, 8680.0, 2307.2962746362355, 2334.4980829121737, 2338.0881456231996, 2340.7659234076627, 2340.8127349811975, 2328.6157783965627, 2362.6692263083182, 2379.512199998996, 2384.9284521464297, 2389.8566086034807, 2344.3201471573307, 2367.2306500387444, 2370.95258094838, 2379.095943541452, 2385.463270764073, 2311.5030268492187, 2336.929892103231, 2351.1535847095033, 2353.5175289065874, 2357.9410514737096, 2302.8714451823976, 2335.346103091137, 2345.085416687476, 2348.495073559193, 2352.788249057593, 8599.656013759442, 8614.310855131636, 8683.348748843862, 8720.389572729911, 8720.607572659246, 5098.949010509921, 5300.0459917214885, 5302.443243459458, 5315.920261196085, 5320.201969704536, 3405.182756138509, 3572.4819901114192, 3632.400653892105, 3644.69020133289, 3659.249853779969, 891.589868660417, 1073.8872418396113, 1108.2408550143177, 1117.5250518529674, 1123.0, 11215.271007384494, 11303.943480282629, 11355.829662555068, 11359.54561817518, 11370.033547148503, 4346.1525002624185, 4420.182266280748, 4584.472785629695, 4593.058423023273, 4602.125596136723, 8936.46381217129, 8943.508107054044, 8946.463212207289, 8948.284137269004, 8951.462912225288, 4458.684688017042, 5158.174692049254, 5160.380754309507, 5170.922436163461, 5173.120569503193, 4325.134973005412, 4328.5455027222315, 4488.887777805516, 4740.952590474051, 4829.6860704054125, 3735.6451137141926, 3759.4548790425233, 3831.731778775459, 3833.9808300958316, 3854.9807250964127, 1162.8662703789003, 1184.2716729211472, 1184.8815118488083, 1195.390350921019, 1195.7010747313172, 839.3746658739157, 856.6787454704486, 858.7976832434641, 922.2069020642242, 961.5144352102144, 4888.706677599355, 5803.941960580424, 5975.312839023481, 6026.6986650667295, 6051.304100028465, 925.7500474871881, 927.4806108579218, 942.8727121838601, 944.7670789619475, 968.4528241543505, 3573.76770509916, 3920.0, 3971.4042893566198, 4092.4884389451317, 4093.324601440729, 10248.64129755455, 10679.839802402816, 10688.412137332365, 10692.251542391958, 10739.463026848627, 10700.839487407695, 10703.678889633324, 10710.03609675133, 10710.357378557312, 10718.839217411745, 7927.682892684344, 8031.518108913367, 8129.593520323961, 8221.301189398848, 8249.257566818895, 5754.079347304411, 5838.1826544282985, 5849.736761845812, 5855.3266374367495, 6008.309044459768, 9759.804803904024, 9880.110790028644, 9966.651167209113, 10010.499475026074, 10066.899331006596, 9080.500572468578, 9596.13634772846, 9969.35199212049, 9984.800303994025, 10073.84889226677, 5938.8218353449465, 7316.634168291671, 7448.553086814808, 7553.962230188814, 7567.356774674215, 6688.2977287386075, 8211.260986511243, 8248.340132789483, 8262.504249745034, 8335.958320208603, 7482.513636613603, 7549.660265288185, 7553.433492487976, 7567.735129270449, 7633.160352361125, 7308.524945878501, 7350.926490735024, 7435.144958329862, 7477.102747670315, 7562.584057876869, 6843.076184715, 6970.093887794555, 7054.08296921397, 7065.858682826212, 7093.184283807428, 4910.116179087799, 5042.747862606929, 5049.091163590589, 5128.3076784633595, 5253.448387919213, 4446.555344465657, 5048.570871475966, 5074.847754567369, 5108.948910510996, 5280.339957505312], "value": 4514.532036685992, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.8463890943252, 294.7111830406203, 300.7819330985036, 310.37769272608415, 312.1167097115164, 4449.288113901776, 5282.313299271094, 5289.4710528947135, 5289.86775330616, 5331.520163185317, 4548.681592288541, 4890.437599676038, 4962.354893863801, 4982.1566020572345, 5129.563987061095, 3557.2609090999913, 3690.2435000824835, 3814.87461198947, 3982.38273067675, 4009.358502639578, 3779.376402893533, 3974.8410063597566, 4007.8998025049314, 4015.0363912660896, 4020.718549701522, 3684.299983003221, 3723.590405055442, 3741.596901162064, 3826.7129965252716, 4016.297147999103, 3935.1342704605086, 3946.309395855714, 3964.702647301464, 3979.900502487432, 3996.4014394242245, 2685.462907418524, 2858.9857050714822, 3429.2798512312443, 3435.055359776057, 3453.723702103826, 4895.290182923456, 5183.989122121188, 5195.168772996302, 5204.557612602934, 5215.061288967984, 4169.291220492493, 4216.894577635584, 4317.985273460734, 4557.225271703818, 4571.405717256735, 5468.453154684539, 5508.862278443031, 5521.558275337963, 5562.0, 5575.776968921238, 3227.3383956288903, 3285.1951271938287, 3551.964480355189, 3835.1562656215588, 3883.1262965832743, 5134.460881607452, 5198.220266959948, 5676.290463692038, 5713.942860571424, 5765.596408251424, 3538.0270425633157, 3580.6598373154507, 3854.807259637007, 3870.9806450967567, 3871.2838124946975, 3907.616354024043, 3942.48747662802, 3956.723029387973, 3963.742356746802, 4024.5371782245415, 4298.871033868989, 4445.666575006822, 4526.298423744325, 4538.704984176018, 4604.815807367735, 4439.866803995886, 5469.206964990054, 5471.398146203915, 5536.52939500147, 5585.134304182777, 1192.5170306026073, 1221.6090850927797, 1285.7235694325766, 1298.2794549025193, 1355.606874006527, 738.6602163005078, 837.2799392522426, 844.1937949258444, 895.6059333893066, 900.8918929728475, 4202.936955945664, 4272.978635106867, 5156.74216289184, 5200.453952335026, 5309.893802124013, 4762.76186190696, 5119.286778007662, 5616.410276920865, 5662.348829884533, 5665.915011274834, 4326.437563116775, 4826.758662066883, 4838.226183576816, 5097.898042039138, 5125.051865404912, 2565.5766798478044, 2973.9851300743358, 2996.565498002821, 3028.6668466468673, 3066.7853250272265, 5294.655847369909, 5537.418571050062, 6017.939820601738, 6023.27720673522, 6071.635701857903, 5036.345275114211, 5499.917501237484, 5896.056630939029, 5929.229200204032, 5944.919278252577, 4766.046790641885, 4780.928286075712, 5118.436971933084, 5146.974265128651, 5988.431099045671, 5125.026245013435, 5417.268668729671, 5480.232767412645, 5771.826845194651, 5779.306483222041, 4953.356063711694, 5041.773120209526, 5676.716164191691, 5705.771769129188, 5724.942750572441, 4982.302477653059, 5016.573591244785, 5971.58198926071, 5982.910256346246, 5997.91003134962, 3247.5453436518433, 3617.819109044485, 3741.923232605821, 3771.3971562086554, 3776.3580191368083, 4963.329950456787, 5699.6865172414255, 5709.9714501427225, 5715.914261286167, 5716.971415142898, 5584.162375643686, 5609.046462101371, 5610.270664813548, 5614.971925140511, 5661.971690141688, 4900.485449027943, 5182.844514664566, 5509.787846673726, 5558.388577256418, 5580.330360356783, 4898.24077268024, 4985.875353116201, 5105.2342148678, 5221.929504451502, 5222.2688823565495, 4448.310511870665, 4463.308187230984, 4670.556297151765, 5452.345718513804, 5519.60544265266, 4375.190589740908, 4413.7351758894565, 4813.278008298783, 5094.617903657167, 5375.4087050425205], "value": 3974.010328206859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.9698513566889, 715.2275542414193, 732.7398773435431, 732.7398773435434, 781.5545139270616, 15778.763318550216, 16592.25334859931, 16621.83378166218, 16631.66736665266, 16685.58286042848, 14768.409263629443, 14821.036632618872, 14995.775063374058, 15014.399424023026, 15077.246137693099, 858.6307887608326, 887.8712586674928, 897.5826240798027, 899.2536194958202, 900.3157600223811, 874.8512752832002, 874.8643960186181, 876.8246350729879, 878.0, 878.4729162502512, 924.4453328003211, 926.1942110363992, 926.3098991251527, 926.393212445849, 929.0523665860818, 921.2998121427696, 922.2437601167046, 922.3635691372998, 923.1507013547485, 924.1590152960773, 682.9214640316377, 683.0539702512007, 683.1528904158857, 683.2996178916601, 683.5693513086724, 15685.372585096582, 15715.0, 15721.0, 15726.606834829106, 15755.0, 14031.719365612631, 14436.783448248285, 14436.927815360961, 14551.490697825631, 14705.0, 10342.224333175041, 11890.405479726065, 11956.440255281335, 12120.636380908587, 12366.072544559194, 3787.829547670334, 3830.0, 3968.4840970673627, 3996.8800935971967, 4039.2931237033695, 130767.34616326858, 130796.0, 132013.0, 134355.0, 135083.0, 11191.888081119085, 11327.716807079723, 11373.601923932789, 11747.88252117485, 11770.0, 22852.31443056711, 22901.19845805389, 22941.082356705545, 22949.31152065407, 22963.7703622965, 8079.878801817978, 8165.183481651906, 8265.380096492787, 8309.45988510757, 8433.241008309282, 4518.390017347681, 4554.362389265505, 4569.200389931749, 4630.328602352707, 4748.216544270226, 3612.6026137124895, 3743.513343265358, 3744.4570537271943, 3745.1573395985683, 3745.400735882246, 540.6969204217817, 595.4458862369213, 610.8961476548998, 620.7734177025353, 623.0280762011187, 648.5557393185683, 657.0641855286892, 681.5127184063418, 699.6396855619361, 705.6154395854272, 8725.67043683309, 9063.546822658973, 9368.531573421302, 9636.373635713797, 9720.0, 568.4599630351183, 621.6363427395024, 651.8989556618732, 679.8776220280298, 698.8148140742708, 3562.8218589070443, 3673.1000904778502, 4750.61995040396, 4780.163471392562, 4811.109944660249, 156380.21809891105, 165400.0, 166711.0, 167008.16495917444, 170252.0, 108159.0, 109761.0, 113210.43394782973, 119660.0, 138501.0, 71209.64395177992, 100511.49744251232, 104585.49559548443, 110180.449097754, 113370.0, 9429.57566909477, 9693.757656058655, 9987.400755954375, 10044.397336159855, 10221.131203847755, 54633.72683136559, 59675.40324596698, 60388.0, 60891.39108608857, 61759.80856473511, 86226.70659940231, 88533.0, 91265.54367228122, 91488.0, 92179.17312403194, 13058.281794501394, 13179.868201317864, 13224.867751322363, 13396.866031339561, 13446.529371471952, 15028.624284392978, 15193.544193674208, 15327.38690452414, 15511.689766204838, 15691.372345106069, 13657.0, 14135.858641413866, 14316.427342906583, 14407.207603581903, 14645.70708585801, 13889.02776805614, 14005.089669171472, 14301.141931484144, 14373.99382043247, 14467.348969296618, 13827.30863456803, 13981.370838312097, 14103.139236973211, 14141.575752727435, 14286.428542858584, 11024.779504409706, 11212.383318917538, 11271.774564508827, 11823.940880295544, 12010.279383237033, 10856.76325003649, 11428.48571814287, 11493.770124597628, 11629.941850290694, 11661.941690291833], "value": 8014.020123819275, "unit": "score"}], "name": "dromaeo_css", "value": 7359.474107991559, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 114.0, 106.0, 113.0, 114.0, 112.0, 116.0, 114.0, 112.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 130.0, 157.0, 107.0, 132.0, 121.0, 108.0, 155.0, 108.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 195.0, 258.0, 197.0, 183.0, 178.0, 179.0, 182.0, 182.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 151.0, 57.0, 58.0, 58.0, 92.0, 90.0, 56.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 136.0, 69.0, 71.0, 70.0, 114.0, 91.0, 98.0, 149.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 86.0, 89.0, 86.0, 90.0, 87.0, 90.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 96.0, 94.0, 101.0, 94.0, 95.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 84.0, 85.0, 86.0, 85.0, 86.0, 84.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 73.0, 81.0, 75.0, 72.0, 68.0, 96.0, 101.0, 82.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 86.0, 87.0, 84.0, 84.0, 86.0, 89.0, 86.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 148.0, 152.0, 139.0, 139.0, 135.0, 160.0, 146.0, 219.0, 133.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 170.0, 170.0, 169.0, 170.0, 170.0, 168.0, 168.0, 168.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 73.0, 76.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1482.0, "alertThreshold": 2.0}]} 17:22:55 INFO - Return code: 0 17:22:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:22:55 INFO - # TBPL SUCCESS # 17:22:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:22:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:22:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:22:55 INFO - 2016-08-29 17:22:55.416 screenresolution[1659:9707] starting screenresolution argv=screenresolution get 17:22:55 INFO - 2016-08-29 17:22:55.419 screenresolution[1659:9707] Display 0: 1600x1200x32@60 17:22:55 INFO - 2016-08-29 17:22:55.425 screenresolution[1660:9710] starting screenresolution argv=screenresolution list 17:22:55 INFO - Available Modes on Display 0 17:22:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:22:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:22:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:22:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:22:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:22:55 INFO - Intel Iris: 17:22:55 INFO - Chipset Model: Intel Iris 17:22:55 INFO - Type: GPU 17:22:55 INFO - Bus: Built-In 17:22:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:22:55 INFO - Vendor: Intel (0x8086) 17:22:55 INFO - Device ID: 0x0a2e 17:22:55 INFO - Revision ID: 0x0009 17:22:55 INFO - Displays: 17:22:55 INFO - Display: 17:22:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:22:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:22:55 INFO - Display Serial Number: 1600x1200 60 17:22:55 INFO - Main Display: Yes 17:22:55 INFO - Mirror: Off 17:22:55 INFO - Online: Yes 17:22:55 INFO - Rotation: Supported 17:22:55 INFO - Return code: 0 17:22:55 INFO - Running post-action listener: _resource_record_post_action 17:22:55 INFO - [mozharness: 2016-08-30 00:22:55.658769Z] Finished run-tests step (success) 17:22:55 INFO - Running post-run listener: _resource_record_post_run 17:22:55 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37946368; Write bytes: 706665472; Read time: 392; Write time: 1914 17:22:55 INFO - TinderboxPrint: CPU usage
53.5% 17:22:55 INFO - TinderboxPrint: I/O read bytes / time
37,946,368 / 392 17:22:55 INFO - TinderboxPrint: I/O write bytes / time
706,665,472 / 1,914 17:22:55 INFO - TinderboxPrint: CPU idle
2,256.7 (46.5%) 17:22:55 INFO - TinderboxPrint: CPU system
972.1 (20.0%) 17:22:55 INFO - TinderboxPrint: CPU user
1,624.8 (33.5%) 17:22:55 INFO - TinderboxPrint: Swap in / out
444,043,264 / 0 17:22:55 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389120; Write bytes: 409749504; Read time: 18; Write time: 749 17:22:55 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 34477056; Write bytes: 295351296; Read time: 350; Write time: 1160 17:22:56 INFO - Running post-run listener: _upload_blobber_files 17:22:56 INFO - Blob upload gear active. 17:22:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:22:56 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 17:22:56 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:22:56 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:22:56 INFO - (blobuploader) - INFO - Open directory for files ... 17:22:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:22:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:22:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:57 INFO - (blobuploader) - INFO - Done attempting. 17:22:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:22:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:22:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:58 INFO - (blobuploader) - INFO - Done attempting. 17:22:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:23:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:23:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:23:01 INFO - (blobuploader) - INFO - Done attempting. 17:23:01 INFO - (blobuploader) - INFO - Iteration through files over. 17:23:01 INFO - Return code: 0 17:23:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:23:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:23:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d622f55392270b35257379baea2a0781ade6ef170c865b97708be67ce52c3b9f75fb03c81fa97e36cf06bb2524a64766a5dbcfa145471bf6c060a1b8ca79a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ce0e7c727d6cedbfd463132c87e7cbe0acc93a185a58a3f96bed7703cbf2c25efba95e571176bc8d7619935c03c81f438da72d77e69a979c4a5a143cdb1bc1ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bd9980d651990ec0a9414fb4ffbbaf033e59bcb0c32a10ad86a26855d53de646499b8d775db1f8b8b66580074a6cf00b63f5118ec9ef906e81d935f561f4565"} 17:23:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:23:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:23:01 INFO - Contents: 17:23:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d622f55392270b35257379baea2a0781ade6ef170c865b97708be67ce52c3b9f75fb03c81fa97e36cf06bb2524a64766a5dbcfa145471bf6c060a1b8ca79a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ce0e7c727d6cedbfd463132c87e7cbe0acc93a185a58a3f96bed7703cbf2c25efba95e571176bc8d7619935c03c81f438da72d77e69a979c4a5a143cdb1bc1ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bd9980d651990ec0a9414fb4ffbbaf033e59bcb0c32a10ad86a26855d53de646499b8d775db1f8b8b66580074a6cf00b63f5118ec9ef906e81d935f561f4565"} 17:23:01 INFO - Running post-run listener: copy_logs_to_upload_dir 17:23:01 INFO - Copying logs to upload dir... 17:23:01 INFO - mkdir: /builds/slave/test/build/upload/logs 17:23:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.751789 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-29 17:23:01.395879) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 17:23:01.399480) ========= 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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d622f55392270b35257379baea2a0781ade6ef170c865b97708be67ce52c3b9f75fb03c81fa97e36cf06bb2524a64766a5dbcfa145471bf6c060a1b8ca79a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ce0e7c727d6cedbfd463132c87e7cbe0acc93a185a58a3f96bed7703cbf2c25efba95e571176bc8d7619935c03c81f438da72d77e69a979c4a5a143cdb1bc1ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bd9980d651990ec0a9414fb4ffbbaf033e59bcb0c32a10ad86a26855d53de646499b8d775db1f8b8b66580074a6cf00b63f5118ec9ef906e81d935f561f4565"} build_url:https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010977 build_url: 'https://queue.taskcluster.net/v1/task/TPr3gPTpR4W1z9EfmBVw8Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d622f55392270b35257379baea2a0781ade6ef170c865b97708be67ce52c3b9f75fb03c81fa97e36cf06bb2524a64766a5dbcfa145471bf6c060a1b8ca79a07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ce0e7c727d6cedbfd463132c87e7cbe0acc93a185a58a3f96bed7703cbf2c25efba95e571176bc8d7619935c03c81f438da72d77e69a979c4a5a143cdb1bc1ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6bd9980d651990ec0a9414fb4ffbbaf033e59bcb0c32a10ad86a26855d53de646499b8d775db1f8b8b66580074a6cf00b63f5118ec9ef906e81d935f561f4565"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 17:23:01.442121) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:23:01.442624) ========= 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.UkUjSgqHSK/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.A2DnOqiKW1/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005187 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 17:23:01.497644) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 17:23:01.498346) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 17:23:01.499050) ========= ========= Total master_lag: 0.32 =========