builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0103 starttime: 1482317491.24 results: success (0) buildid: 20161221004017 builduid: 485e0cb4427e4d65b293442f61cf69a1 revision: 81a1f25491a65bdf41584a261a3d44607af9ac95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:31.240706) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:31.241170) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:31.241465) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:31.280443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-12-21 02:51:31.280760) ========= 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.hIXIezdXaR/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.QbtL6GBeWK/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 02:51:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.8M=0.001s 2016-12-21 02:51:31 (14.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.347297 ========= master_lag: 4.93 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-12-21 02:51:36.560366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:36.560708) ========= 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.hIXIezdXaR/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.QbtL6GBeWK/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.027889 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:36.615313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-12-21 02:51:36.615684) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 81a1f25491a65bdf41584a261a3d44607af9ac95 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 81a1f25491a65bdf41584a261a3d44607af9ac95 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hIXIezdXaR/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.QbtL6GBeWK/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 02:51:36,680 truncating revision to first 12 chars 2016-12-21 02:51:36,681 Setting DEBUG logging. 2016-12-21 02:51:36,681 attempt 1/10 2016-12-21 02:51:36,681 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/81a1f25491a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 02:51:37,747 unpacking tar archive at: mozilla-aurora-81a1f25491a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.336898 ========= master_lag: 3.72 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-12-21 02:51:41.677489) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:41.677813) ========= script_repo_revision: 81a1f25491a65bdf41584a261a3d44607af9ac95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:41.678235) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:41.678509) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 02:51:41.833468) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-21 02:51:41.833822) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hIXIezdXaR/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.QbtL6GBeWK/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:51:42 INFO - MultiFileLogger online at 20161221 02:51:42 in /builds/slave/test 02:51:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:51:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:51:42 INFO - {'append_to_log': False, 02:51:42 INFO - 'base_work_dir': '/builds/slave/test', 02:51:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:51:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:51:42 INFO - 'branch': 'Mozilla-Aurora', 02:51:42 INFO - 'buildbot_json_path': 'buildprops.json', 02:51:42 INFO - 'config_files': ('talos/mac_config.py',), 02:51:42 INFO - 'default_actions': ('clobber', 02:51:42 INFO - 'read-buildbot-config', 02:51:42 INFO - 'download-and-extract', 02:51:42 INFO - 'populate-webroot', 02:51:42 INFO - 'create-virtualenv', 02:51:42 INFO - 'install', 02:51:42 INFO - 'run-tests'), 02:51:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:51:42 INFO - 'download_minidump_stackwalk': True, 02:51:42 INFO - 'download_symbols': 'ondemand', 02:51:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:51:42 INFO - 'tooltool.py': '/tools/tooltool.py', 02:51:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:51:42 INFO - '/tools/misc-python/virtualenv.py')}, 02:51:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:51:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:51:42 INFO - 'installer_path': 'installer.exe', 02:51:42 INFO - 'log_level': 'info', 02:51:42 INFO - 'log_name': 'talos', 02:51:42 INFO - 'log_to_console': True, 02:51:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:51:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:51:42 INFO - 'opt_config_files': (), 02:51:42 INFO - 'pip_index': False, 02:51:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:51:42 INFO - 'cmd': ('bash', 02:51:42 INFO - '-c', 02:51:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:51:42 INFO - 'enabled': True, 02:51:42 INFO - 'halt_on_failure': False, 02:51:42 INFO - 'name': 'check_screen_resolution'},), 02:51:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:51:42 INFO - 'cmd': ('bash', 02:51:42 INFO - '-c', 02:51:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:51:42 INFO - 'enabled': True, 02:51:42 INFO - 'halt_on_failure': False, 02:51:42 INFO - 'name': 'check_screen_resolution'},), 02:51:42 INFO - 'run_cmd_checks_enabled': True, 02:51:42 INFO - 'sps_profile': False, 02:51:42 INFO - 'sps_profile_interval': 0, 02:51:42 INFO - 'suite': 'dromaeojs', 02:51:42 INFO - 'system_bits': '32', 02:51:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:51:42 INFO - 'title': 't-yosemite-r7-0103', 02:51:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:51:42 INFO - 'use_talos_json': True, 02:51:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:51:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:51:42 INFO - 'work_dir': 'build'} 02:51:42 INFO - [mozharness: 2016-12-21 10:51:42.012115Z] Running clobber step. 02:51:42 INFO - Running pre-action listener: _resource_record_pre_action 02:51:42 INFO - Running main action method: clobber 02:51:42 INFO - rmtree: /builds/slave/test/build 02:51:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:51:44 INFO - Running post-action listener: _resource_record_post_action 02:51:44 INFO - [mozharness: 2016-12-21 10:51:44.614021Z] Finished clobber step (success) 02:51:44 INFO - [mozharness: 2016-12-21 10:51:44.614145Z] Running read-buildbot-config step. 02:51:44 INFO - Running pre-action listener: _resource_record_pre_action 02:51:44 INFO - Running main action method: read_buildbot_config 02:51:44 INFO - Using buildbot properties: 02:51:44 INFO - { 02:51:44 INFO - "project": "", 02:51:44 INFO - "product": "firefox", 02:51:44 INFO - "script_repo_revision": "production", 02:51:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:51:44 INFO - "repository": "", 02:51:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:51:44 INFO - "buildid": "20161221004017", 02:51:44 INFO - "stage_platform": "macosx64", 02:51:44 INFO - "basedir": "/builds/slave/test", 02:51:44 INFO - "nightly_build": "True", 02:51:44 INFO - "buildnumber": 3, 02:51:44 INFO - "slavename": "t-yosemite-r7-0103", 02:51:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:51:44 INFO - "platform": "yosemite_r7", 02:51:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:51:44 INFO - "branch": "mozilla-aurora", 02:51:44 INFO - "repo_path": "releases/mozilla-aurora", 02:51:44 INFO - "revision": "81a1f25491a65bdf41584a261a3d44607af9ac95", 02:51:44 INFO - "pgo_build": "False", 02:51:44 INFO - "builduid": "485e0cb4427e4d65b293442f61cf69a1", 02:51:44 INFO - "slavebuilddir": "test" 02:51:44 INFO - } 02:51:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:51:44 INFO - Running post-action listener: _resource_record_post_action 02:51:44 INFO - [mozharness: 2016-12-21 10:51:44.617629Z] Finished read-buildbot-config step (success) 02:51:44 INFO - [mozharness: 2016-12-21 10:51:44.617745Z] Running download-and-extract step. 02:51:44 INFO - Running pre-action listener: _resource_record_pre_action 02:51:44 INFO - Running main action method: download_and_extract 02:51:44 INFO - mkdir: /builds/slave/test/build/tests 02:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:51:44 INFO - trying https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:51:44 INFO - Downloading https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:51:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:51:45 INFO - Downloaded 1199 bytes. 02:51:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:51:45 INFO - Using the following test package requirements: 02:51:45 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:51:45 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:51:45 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:51:45 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'jsshell-mac.zip'], 02:51:45 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:51:45 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:51:45 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:51:45 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:51:45 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:51:45 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:51:45 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:51:45 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:51:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:51:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:51:45 INFO - Fetch https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:51:46 INFO - Http code: 200 02:51:46 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 02:51:46 INFO - x-amz-cf-id: fJ79rYebTPpCp_3wVy7pflFED1GSJWChSuf7O9_4ROgV98wTtB4yiQ== 02:51:46 INFO - x-amz-version-id: scEn5FqEBbulACd3IqSJqyyJU0f3yJJ. 02:51:46 INFO - Expected file size: 16267616 02:51:46 INFO - Obtained file size: 16267616 02:51:53 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:51:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:51:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:51:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:51:53 INFO - Http code: 200 02:51:53 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 02:51:53 INFO - x-amz-cf-id: Zuxn2Clxj4OLfu_9udGQ_4pVgGEnFZv6CDefGTPVwQBbLv7ZZT90QA== 02:51:53 INFO - x-amz-version-id: scEn5FqEBbulACd3IqSJqyyJU0f3yJJ. 02:51:54 INFO - Expected file size: 16267616 02:51:54 INFO - Obtained file size: 16267616 02:52:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:52:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:52:00 INFO - Fetch https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:52:01 INFO - Http code: 200 02:52:01 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 02:52:01 INFO - x-amz-cf-id: ZNjaNyMJJtvuwIwwEYP9QAyAWle3DktJgFSmXxfACgQXvoQn8N2xlQ== 02:52:01 INFO - x-amz-version-id: s70g2s_vCsjDGlU1GXbSkFLWLYVQL9mU 02:52:01 INFO - Expected file size: 14409038 02:52:01 INFO - Obtained file size: 14409038 02:52:02 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')]} 02:52:02 INFO - trying https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:52:02 INFO - Downloading https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:52:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:52:04 INFO - Downloaded 109789259 bytes. 02:52:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:52:04 INFO - mkdir: /builds/slave/test/properties 02:52:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:52:04 INFO - Writing to file /builds/slave/test/properties/build_url 02:52:04 INFO - Contents: 02:52:04 INFO - build_url:https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:52:05 INFO - Running post-action listener: _resource_record_post_action 02:52:05 INFO - Running post-action listener: set_extra_try_arguments 02:52:05 INFO - [mozharness: 2016-12-21 10:52:05.473246Z] Finished download-and-extract step (success) 02:52:05 INFO - [mozharness: 2016-12-21 10:52:05.473351Z] Running populate-webroot step. 02:52:05 INFO - Running pre-action listener: _resource_record_pre_action 02:52:05 INFO - Running main action method: populate_webroot 02:52:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:52:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:52:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:52:05 INFO - '--tppagecycles', 02:52:05 INFO - '7'], 02:52:05 INFO - 'tests': ['tsvgm']}}, 02:52:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:52:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:52:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:52:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:52:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['damp', 'tps']}, 02:52:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'tests': ['damp', 'tps']}, 02:52:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['dromaeo_dom']}, 02:52:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:52:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['basic_compositor_video']}, 02:52:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:52:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['a11yr', 02:52:05 INFO - 'ts_paint', 02:52:05 INFO - 'tpaint', 02:52:05 INFO - 'sessionrestore', 02:52:05 INFO - 'sessionrestore_no_auto_restore', 02:52:05 INFO - 'tabpaint']}, 02:52:05 INFO - 'other-e10s': {'tests': ['a11yr', 02:52:05 INFO - 'ts_paint', 02:52:05 INFO - 'tpaint', 02:52:05 INFO - 'sessionrestore', 02:52:05 INFO - 'sessionrestore_no_auto_restore', 02:52:05 INFO - 'tabpaint']}, 02:52:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:52:05 INFO - 'ts_paint', 02:52:05 INFO - 'tpaint', 02:52:05 INFO - 'sessionrestore', 02:52:05 INFO - 'sessionrestore_no_auto_restore', 02:52:05 INFO - 'tabpaint']}, 02:52:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:52:05 INFO - 'ts_paint', 02:52:05 INFO - 'tpaint', 02:52:05 INFO - 'sessionrestore', 02:52:05 INFO - 'sessionrestore_no_auto_restore', 02:52:05 INFO - 'tabpaint']}, 02:52:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['a11yr', 02:52:05 INFO - 'ts_paint', 02:52:05 INFO - 'tpaint', 02:52:05 INFO - 'sessionrestore', 02:52:05 INFO - 'sessionrestore_no_auto_restore', 02:52:05 INFO - 'tabpaint']}, 02:52:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['a11yr', 02:52:05 INFO - 'ts_paint', 02:52:05 INFO - 'tpaint', 02:52:05 INFO - 'sessionrestore', 02:52:05 INFO - 'sessionrestore_no_auto_restore', 02:52:05 INFO - 'tabpaint']}, 02:52:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['tsvgx', 02:52:05 INFO - 'tsvgr_opacity', 02:52:05 INFO - 'tart', 02:52:05 INFO - 'tscrollx', 02:52:05 INFO - 'cart']}, 02:52:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:52:05 INFO - 'tsvgr_opacity', 02:52:05 INFO - 'tart', 02:52:05 INFO - 'tscrollx', 02:52:05 INFO - 'cart']}, 02:52:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'talos_options': ['--disable-e10s'], 02:52:05 INFO - 'tests': ['tp5o']}, 02:52:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'tests': ['tp5o']}, 02:52:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'talos_options': ['--disable-e10s', 02:52:05 INFO - '--xperf_path', 02:52:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:05 INFO - 'tests': ['tp5n']}, 02:52:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:05 INFO - 'talos_options': ['--xperf_path', 02:52:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:05 INFO - 'tests': ['tp5n']}}, 02:52:05 INFO - 'talos.zip': {'path': '', 02:52:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:52:05 INFO - Running post-action listener: _resource_record_post_action 02:52:05 INFO - [mozharness: 2016-12-21 10:52:05.492528Z] Finished populate-webroot step (success) 02:52:05 INFO - [mozharness: 2016-12-21 10:52:05.492670Z] Running create-virtualenv step. 02:52:05 INFO - Running pre-action listener: _resource_record_pre_action 02:52:05 INFO - Running main action method: create_virtualenv 02:52:05 INFO - Creating virtualenv /builds/slave/test/build/venv 02:52:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:52:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:52:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:52:05 INFO - Using real prefix '/tools/python27' 02:52:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:52:06 INFO - Installing distribute.............................................................................................................................................................................................done. 02:52:09 INFO - Installing pip.................done. 02:52:09 INFO - Return code: 0 02:52:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:52:09 INFO - Reading from file tmpfile_stdout 02:52:09 INFO - Output received: 02:52:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:52:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:09 INFO - 'HOME': '/Users/cltbld', 02:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:09 INFO - 'LOGNAME': 'cltbld', 02:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:09 INFO - 'MOZ_NO_REMOTE': '1', 02:52:09 INFO - 'NO_EM_RESTART': '1', 02:52:09 INFO - 'PAGER': '/bin/cat', 02:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:09 INFO - 'PWD': '/builds/slave/test', 02:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:09 INFO - 'SHELL': '/bin/bash', 02:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:09 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:09 INFO - 'USER': 'cltbld', 02:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:09 INFO - 'XPC_FLAGS': '0x0', 02:52:09 INFO - 'XPC_SERVICE_NAME': '0', 02:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:52:09 INFO - Cleaning up... 02:52:09 INFO - Return code: 0 02:52:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:09 INFO - 'HOME': '/Users/cltbld', 02:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:09 INFO - 'LOGNAME': 'cltbld', 02:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:09 INFO - 'MOZ_NO_REMOTE': '1', 02:52:09 INFO - 'NO_EM_RESTART': '1', 02:52:09 INFO - 'PAGER': '/bin/cat', 02:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:09 INFO - 'PWD': '/builds/slave/test', 02:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:09 INFO - 'SHELL': '/bin/bash', 02:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:09 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:09 INFO - 'USER': 'cltbld', 02:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:09 INFO - 'XPC_FLAGS': '0x0', 02:52:09 INFO - 'XPC_SERVICE_NAME': '0', 02:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:10 INFO - Downloading/unpacking psutil>=3.1.1 02:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:52:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:52:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:52:13 INFO - Installing collected packages: psutil 02:52:13 INFO - Running setup.py install for psutil 02:52:13 INFO - building 'psutil._psutil_osx' extension 02:52:13 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 02:52:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:52:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:52:14 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 02:52:14 INFO - building 'psutil._psutil_posix' extension 02:52:14 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 02:52:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:52:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:52:14 INFO - ^ 02:52:14 INFO - 1 warning generated. 02:52:14 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 02:52:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:52:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:52:14 INFO - Successfully installed psutil 02:52:14 INFO - Cleaning up... 02:52:14 INFO - Return code: 0 02:52:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:52:14 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')]} 02:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:14 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')]} 02:52:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:14 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 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:14 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 02:52:14 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 02:52:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:14 INFO - 'HOME': '/Users/cltbld', 02:52:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:14 INFO - 'LOGNAME': 'cltbld', 02:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:14 INFO - 'MOZ_NO_REMOTE': '1', 02:52:14 INFO - 'NO_EM_RESTART': '1', 02:52:14 INFO - 'PAGER': '/bin/cat', 02:52:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:14 INFO - 'PWD': '/builds/slave/test', 02:52:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:14 INFO - 'SHELL': '/bin/bash', 02:52:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:14 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:14 INFO - 'USER': 'cltbld', 02:52:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:14 INFO - 'XPC_FLAGS': '0x0', 02:52:14 INFO - 'XPC_SERVICE_NAME': '0', 02:52:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:52:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:52:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:52:17 INFO - Installing collected packages: mozsystemmonitor 02:52:17 INFO - Running setup.py install for mozsystemmonitor 02:52:18 INFO - Successfully installed mozsystemmonitor 02:52:18 INFO - Cleaning up... 02:52:18 INFO - Return code: 0 02:52:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:52: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')]} 02:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52: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')]} 02:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:52:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:18 INFO - 'HOME': '/Users/cltbld', 02:52:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:18 INFO - 'LOGNAME': 'cltbld', 02:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:18 INFO - 'MOZ_NO_REMOTE': '1', 02:52:18 INFO - 'NO_EM_RESTART': '1', 02:52:18 INFO - 'PAGER': '/bin/cat', 02:52:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:18 INFO - 'PWD': '/builds/slave/test', 02:52:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:18 INFO - 'SHELL': '/bin/bash', 02:52:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:18 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:18 INFO - 'USER': 'cltbld', 02:52:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:18 INFO - 'XPC_FLAGS': '0x0', 02:52:18 INFO - 'XPC_SERVICE_NAME': '0', 02:52:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:18 INFO - Downloading/unpacking jsonschema==2.5.1 02:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:52:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:21 INFO - Downloading functools32-3.2.3-2.tar.gz 02:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:52:21 INFO - warning: no files found matching '*.txt' 02:52:21 INFO - no previously-included directories found matching 'build' 02:52:21 INFO - no previously-included directories found matching 'dist' 02:52:21 INFO - no previously-included directories found matching '.git*' 02:52:21 INFO - Installing collected packages: jsonschema, functools32 02:52:21 INFO - Running setup.py install for functools32 02:52:21 INFO - warning: no files found matching '*.txt' 02:52:21 INFO - no previously-included directories found matching 'build' 02:52:21 INFO - no previously-included directories found matching 'dist' 02:52:21 INFO - no previously-included directories found matching '.git*' 02:52:22 INFO - Successfully installed jsonschema functools32 02:52:22 INFO - Cleaning up... 02:52:22 INFO - Return code: 0 02:52:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:22 INFO - 'HOME': '/Users/cltbld', 02:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:22 INFO - 'LOGNAME': 'cltbld', 02:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:22 INFO - 'MOZ_NO_REMOTE': '1', 02:52:22 INFO - 'NO_EM_RESTART': '1', 02:52:22 INFO - 'PAGER': '/bin/cat', 02:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:22 INFO - 'PWD': '/builds/slave/test', 02:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:22 INFO - 'SHELL': '/bin/bash', 02:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:22 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:22 INFO - 'USER': 'cltbld', 02:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:22 INFO - 'XPC_FLAGS': '0x0', 02:52:22 INFO - 'XPC_SERVICE_NAME': '0', 02:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:52:22 INFO - Cleaning up... 02:52:22 INFO - Return code: 0 02:52:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:22 INFO - 'HOME': '/Users/cltbld', 02:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:22 INFO - 'LOGNAME': 'cltbld', 02:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:22 INFO - 'MOZ_NO_REMOTE': '1', 02:52:22 INFO - 'NO_EM_RESTART': '1', 02:52:22 INFO - 'PAGER': '/bin/cat', 02:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:22 INFO - 'PWD': '/builds/slave/test', 02:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:22 INFO - 'SHELL': '/bin/bash', 02:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:22 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:22 INFO - 'USER': 'cltbld', 02:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:22 INFO - 'XPC_FLAGS': '0x0', 02:52:22 INFO - 'XPC_SERVICE_NAME': '0', 02:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:23 INFO - Downloading/unpacking blobuploader==1.2.4 02:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:25 INFO - Downloading blobuploader-1.2.4.tar.gz 02:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:52:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:52:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:26 INFO - Downloading docopt-0.6.1.tar.gz 02:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:52:27 INFO - Installing collected packages: blobuploader, requests, docopt 02:52:27 INFO - Running setup.py install for blobuploader 02:52:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:52:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:52:27 INFO - Running setup.py install for requests 02:52:27 INFO - Running setup.py install for docopt 02:52:27 INFO - Successfully installed blobuploader requests docopt 02:52:27 INFO - Cleaning up... 02:52:27 INFO - Return code: 0 02:52:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:52:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:27 INFO - 'HOME': '/Users/cltbld', 02:52:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:27 INFO - 'LOGNAME': 'cltbld', 02:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:27 INFO - 'MOZ_NO_REMOTE': '1', 02:52:27 INFO - 'NO_EM_RESTART': '1', 02:52:27 INFO - 'PAGER': '/bin/cat', 02:52:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:27 INFO - 'PWD': '/builds/slave/test', 02:52:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:27 INFO - 'SHELL': '/bin/bash', 02:52:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:27 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:27 INFO - 'USER': 'cltbld', 02:52:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:27 INFO - 'XPC_FLAGS': '0x0', 02:52:27 INFO - 'XPC_SERVICE_NAME': '0', 02:52:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rxeZUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-WlICQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-4h0A75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-xBTZcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-s4s8vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-wjiK2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-id3niz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-kWXMTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:28 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-9_Glc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-SUEOG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-mq1g26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-9JF5uZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-fmreTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-uZj_If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-QDojdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-NqjplZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:29 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-nVmRol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:52:29 INFO - Running setup.py install for manifestparser 02:52:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:52:30 INFO - Running setup.py install for mozcrash 02:52:30 INFO - Running setup.py install for mozdebug 02:52:30 INFO - Running setup.py install for mozdevice 02:52:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:52:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:52:30 INFO - Running setup.py install for mozfile 02:52:30 INFO - Running setup.py install for mozhttpd 02:52:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:52:30 INFO - Running setup.py install for mozinfo 02:52:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:52:30 INFO - Running setup.py install for mozInstall 02:52:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Running setup.py install for mozleak 02:52:31 INFO - Running setup.py install for mozlog 02:52:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Running setup.py install for moznetwork 02:52:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Running setup.py install for mozprocess 02:52:31 INFO - Running setup.py install for mozprofile 02:52:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:52:31 INFO - Running setup.py install for mozrunner 02:52:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:52:32 INFO - Running setup.py install for mozscreenshot 02:52:32 INFO - Running setup.py install for moztest 02:52:32 INFO - Running setup.py install for mozversion 02:52:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:52:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:52:32 INFO - Cleaning up... 02:52:32 INFO - Return code: 0 02:52:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:32 INFO - 'HOME': '/Users/cltbld', 02:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:32 INFO - 'LOGNAME': 'cltbld', 02:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:32 INFO - 'MOZ_NO_REMOTE': '1', 02:52:32 INFO - 'NO_EM_RESTART': '1', 02:52:32 INFO - 'PAGER': '/bin/cat', 02:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:32 INFO - 'PWD': '/builds/slave/test', 02:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:32 INFO - 'SHELL': '/bin/bash', 02:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:32 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:32 INFO - 'USER': 'cltbld', 02:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:32 INFO - 'XPC_FLAGS': '0x0', 02:52:32 INFO - 'XPC_SERVICE_NAME': '0', 02:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:32 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Q1ypre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:32 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-DIdAqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:32 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-LTOg1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:33 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)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-rJ3yNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:33 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)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Emk39g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-D67CFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-DilRKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-oJ4Kr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-YP56DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-mllEfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-_tOUNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:33 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-BDV7St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:34 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)) 02:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:34 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-2z5cY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:34 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-iIu1F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:34 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-9rw0zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:34 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jUOflD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:34 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)) 02:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:34 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-3g6UaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:52:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:37 INFO - Downloading blessings-1.6.tar.gz 02:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:52:37 INFO - Installing collected packages: blessings 02:52:37 INFO - Running setup.py install for blessings 02:52:37 INFO - Successfully installed blessings 02:52:37 INFO - Cleaning up... 02:52:37 INFO - Return code: 0 02:52:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:52:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:37 INFO - Reading from file tmpfile_stdout 02:52:37 INFO - Current package versions: 02:52:37 INFO - blessings == 1.6 02:52:37 INFO - blobuploader == 1.2.4 02:52:37 INFO - docopt == 0.6.1 02:52:37 INFO - functools32 == 3.2.3-2 02:52:37 INFO - jsonschema == 2.5.1 02:52:37 INFO - manifestparser == 1.1 02:52:37 INFO - mozInstall == 1.12 02:52:37 INFO - mozcrash == 1.0 02:52:37 INFO - mozdebug == 0.1 02:52:37 INFO - mozdevice == 0.48 02:52:37 INFO - mozfile == 1.2 02:52:37 INFO - mozhttpd == 0.7 02:52:37 INFO - mozinfo == 0.9 02:52:37 INFO - mozleak == 0.1 02:52:37 INFO - mozlog == 3.4 02:52:37 INFO - moznetwork == 0.27 02:52:37 INFO - mozprocess == 0.23 02:52:37 INFO - mozprofile == 0.28 02:52:37 INFO - mozrunner == 6.13 02:52:37 INFO - mozscreenshot == 0.1 02:52:37 INFO - mozsystemmonitor == 0.3 02:52:37 INFO - moztest == 0.7 02:52:37 INFO - mozversion == 1.4 02:52:37 INFO - psutil == 3.1.1 02:52:37 INFO - requests == 1.2.3 02:52:37 INFO - wsgiref == 0.1.2 02:52:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:37 INFO - 'HOME': '/Users/cltbld', 02:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:37 INFO - 'LOGNAME': 'cltbld', 02:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:37 INFO - 'MOZ_NO_REMOTE': '1', 02:52:37 INFO - 'NO_EM_RESTART': '1', 02:52:37 INFO - 'PAGER': '/bin/cat', 02:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:37 INFO - 'PWD': '/builds/slave/test', 02:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:37 INFO - 'SHELL': '/bin/bash', 02:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:37 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:37 INFO - 'USER': 'cltbld', 02:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:37 INFO - 'XPC_FLAGS': '0x0', 02:52:37 INFO - 'XPC_SERVICE_NAME': '0', 02:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:52:38 INFO - Cleaning up... 02:52:38 INFO - Return code: 0 02:52:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f64f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f5c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6a9cbb0b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, '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.hIXIezdXaR/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.QbtL6GBeWK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 02:52:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:52:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:52:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:38 INFO - 'HOME': '/Users/cltbld', 02:52:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:38 INFO - 'LOGNAME': 'cltbld', 02:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:38 INFO - 'MOZ_NO_REMOTE': '1', 02:52:38 INFO - 'NO_EM_RESTART': '1', 02:52:38 INFO - 'PAGER': '/bin/cat', 02:52:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:38 INFO - 'PWD': '/builds/slave/test', 02:52:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:38 INFO - 'SHELL': '/bin/bash', 02:52:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:52:38 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:52:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:38 INFO - 'USER': 'cltbld', 02:52:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:38 INFO - 'XPC_FLAGS': '0x0', 02:52:38 INFO - 'XPC_SERVICE_NAME': '0', 02:52:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:52:38 INFO - Cleaning up... 02:52:38 INFO - Return code: 0 02:52:38 INFO - Running post-action listener: _resource_record_post_action 02:52:38 INFO - Running post-action listener: _start_resource_monitoring 02:52:38 INFO - Starting resource monitoring. 02:52:38 INFO - [mozharness: 2016-12-21 10:52:38.424296Z] Finished create-virtualenv step (success) 02:52:38 INFO - [mozharness: 2016-12-21 10:52:38.425252Z] Running install step. 02:52:38 INFO - Running pre-action listener: _resource_record_pre_action 02:52:38 INFO - Running main action method: install 02:52:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:38 INFO - Reading from file tmpfile_stdout 02:52:38 INFO - Detecting whether we're running mozinstall >=1.0... 02:52:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:52:38 INFO - Reading from file tmpfile_stdout 02:52:38 INFO - Output received: 02:52:38 INFO - Usage: mozinstall [options] installer 02:52:38 INFO - Options: 02:52:38 INFO - -h, --help show this help message and exit 02:52:38 INFO - -d DEST, --destination=DEST 02:52:38 INFO - Directory to install application into. [default: 02:52:38 INFO - "/builds/slave/test"] 02:52:38 INFO - --app=APP Application being installed. [default: firefox] 02:52:38 INFO - mkdir: /builds/slave/test/build/application 02:52:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:53:09 INFO - Reading from file tmpfile_stdout 02:53:09 INFO - Output received: 02:53:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:53:09 INFO - Running post-action listener: _resource_record_post_action 02:53:09 INFO - [mozharness: 2016-12-21 10:53:09.906635Z] Finished install step (success) 02:53:09 INFO - [mozharness: 2016-12-21 10:53:09.906811Z] Running run-tests step. 02:53:09 INFO - Running pre-action listener: _resource_record_pre_action 02:53:09 INFO - Running main action method: run_tests 02:53:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:53:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:53:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:53:09 INFO - 2016-12-21 02:53:09.921 screenresolution[1625:7389] starting screenresolution argv=screenresolution get 02:53:09 INFO - 2016-12-21 02:53:09.924 screenresolution[1625:7389] Display 0: 1600x1200x32@60 02:53:09 INFO - 2016-12-21 02:53:09.930 screenresolution[1626:7392] starting screenresolution argv=screenresolution list 02:53:09 INFO - Available Modes on Display 0 02:53:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:53:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:53:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:53:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:53:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:53:10 INFO - Intel Iris: 02:53:10 INFO - Chipset Model: Intel Iris 02:53:10 INFO - Type: GPU 02:53:10 INFO - Bus: Built-In 02:53:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:53:10 INFO - Vendor: Intel (0x8086) 02:53:10 INFO - Device ID: 0x0a2e 02:53:10 INFO - Revision ID: 0x0009 02:53:10 INFO - Displays: 02:53:10 INFO - Display: 02:53:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:53:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:53:10 INFO - Display Serial Number: 1600x1200 60 02:53:10 INFO - Main Display: Yes 02:53:10 INFO - Mirror: Off 02:53:10 INFO - Online: Yes 02:53:10 INFO - Rotation: Supported 02:53:10 INFO - Return code: 0 02:53:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:53:10 INFO - Python 2.7.3 02:53:10 INFO - Return code: 0 02:53:10 INFO - grabbing minidump binary from tooltool 02:53:10 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')]} 02:53:10 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 0x10f463d30>, '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 0x10f2c9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f45bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:53:10 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 02:53:10 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 02:53:10 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 02:53:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:53:10 INFO - Return code: 0 02:53:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:53:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:53:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:53:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:53:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:53:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 02:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --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 02:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hIXIezdXaR/Render', 02:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:10 INFO - 'HOME': '/Users/cltbld', 02:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:10 INFO - 'LOGNAME': 'cltbld', 02:53:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:53:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:10 INFO - 'MOZ_NO_REMOTE': '1', 02:53:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:53:10 INFO - 'NO_EM_RESTART': '1', 02:53:10 INFO - 'PAGER': '/bin/cat', 02:53:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:10 INFO - 'PWD': '/builds/slave/test', 02:53:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:10 INFO - 'SHELL': '/bin/bash', 02:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbtL6GBeWK/Listeners', 02:53:10 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 02:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:10 INFO - 'USER': 'cltbld', 02:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:10 INFO - 'XPC_FLAGS': '0x0', 02:53:10 INFO - 'XPC_SERVICE_NAME': '0', 02:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 02:53:10 INFO - mozversion application_buildid: 20161221004017 02:53:10 INFO - mozversion application_changeset: 81a1f25491a65bdf41584a261a3d44607af9ac95 02:53:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:53:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:53:10 INFO - mozversion application_name: Firefox 02:53:10 INFO - mozversion application_remotingname: firefox-dev 02:53:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:53:10 INFO - mozversion application_vendor: Mozilla 02:53:10 INFO - mozversion application_version: 52.0a2 02:53:10 INFO - mozversion platform_buildid: 20161221004017 02:53:10 INFO - mozversion platform_changeset: 81a1f25491a65bdf41584a261a3d44607af9ac95 02:53:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:53:10 INFO - mozversion platform_version: 52.0a2 02:53:10 INFO - using testdate: 1482317590 02:53:10 INFO - actual date: 1482317590 02:53:10 INFO - starting webserver on 'localhost:49243' 02:53:10 INFO - SUITE-START | Running 2 tests 02:53:10 INFO - TEST-START | dromaeo_css 02:53:10 INFO - operating with platform_type : mac_ 02:53:10 INFO - Initialising browser for dromaeo_css test... 02:53:10 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpNLrtSf/profile http://localhost:49243/getInfo.html) 02:53:13 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 02:53:13 INFO - PROCESS | 1636 | colorDepth:24 02:53:13 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 02:53:13 INFO - PROCESS | 1636 | __metrics 02:53:13 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:53:13 INFO - PROCESS | 1636 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:53:14 INFO - TEST-INFO | 1636: exit 0 02:53:14 INFO - Browser initialized. 02:53:14 INFO - Running cycle 1/1 for dromaeo_css test... 02:53:14 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpNLrtSf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:53:26 INFO - PROCESS | 1642 | RSS: Main: 223068160 02:53:26 INFO - PROCESS | 1642 | 02:54:18 INFO - PROCESS | 1642 | 1482317658280 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] 02:56:31 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 02:56:33 INFO - PROCESS | 1642 | RSS: Main: 363487232 02:56:33 INFO - PROCESS | 1642 | 02:59:36 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 02:59:36 INFO - PROCESS | 1642 | RSS: Main: 348762112 02:59:36 INFO - PROCESS | 1642 | 03:02:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 03:02:39 INFO - PROCESS | 1642 | RSS: Main: 325349376 03:02:39 INFO - PROCESS | 1642 | 03:05:42 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 03:05:42 INFO - PROCESS | 1642 | RSS: Main: 332128256 03:05:42 INFO - PROCESS | 1642 | 03:08:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 03:08:46 INFO - PROCESS | 1642 | RSS: Main: 311898112 03:08:46 INFO - PROCESS | 1642 | 03:11:49 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 03:11:50 INFO - PROCESS | 1642 | RSS: Main: 456773632 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | __start_tp_report 03:11:50 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 03:11:50 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 03:11:50 INFO - PROCESS | 1642 | |i|pagename|runs| 03:11:50 INFO - PROCESS | 1642 | |0;dojo.html;3033.5298028805537;3326.8502917368714;3331.633520312766;3340.265141668832;3384.712299454546;5642.633228840127;5745.971270143644;5758.452946970031;5763.942360576399;5767.567432442563;5683.3464151622575;5764.991126552857;5769.5672824538115;5772.220750198722;5780.682062486561;5175.689458632475;5202.635815492917;5204.167333226675;5233.18885572735;5252.080885844981;5093.745312734367;5159.2003239497935;5162.354705661793;5231.398389185254;5297.993381257568;5144.3826740791155;5180.922286165711;5205.60957928155;5230.032443997872;5245.344331958505;5215.687058776467;5292.814751483718;5299.49654782795;5300.337457817773;5311.097113490678;5162.586993040548;5211.088059589594;5223.242629818693;5247.842564723064;5249.816256431007;5563;5609;5630.436956304377;5659.886802263973;5678.261825962599;6114.270941054807;6141.723622437001;6177.567570270083;6179.227596550431;6225.968870155621;6237.532185086136;6278.37216278373;6279.120923070774;6282.465990390822;6287.654179020152;4808.447028591757;5649.350324712628;5701.828945131652;5755.827325180251;5829.8251052468495;6286.465650419766;6302.621842689453;6313.589616674902;6317.747290108436;6321.304656487782;5444.611624035925;6140.324564297924;6146.969265153735;6158.661273629993;6162.907556386658;5872.11918212263;6104.38956104386;6116.223169985209;6129.4618143007265;6134.4793678840115;6153.446189842935;6230.221222347207;6251.3123556408755;6255.6872156392;6258.186435763321;6197.752089916353;6224.657643829632;6270.153529273581;6289.591176573508;6299.685015749286;4844.709317440965;4874.975625121851;4947.554720075211;4959.752012399365;4980.526849949249;4437.755923424178;4448.599626033672;4450.287953927356;4463.866084017484;4479.664025198123;5736.397678243724;5745.425457454305;5753.3958934311295;5753.597248192586;5758.654480731169;5800.5939584229545;6223.222097237845;6234.127222188898;6235.844103897348;6251.3123556408755;5241.633085683966;5361.5710743141235;5746.080627099644;5749.8275051748515;5750.511206547407;5357.598180136505;5373.758180881791;5380.892382152335;5384.973075134678;5386;6242;6243.406876346739;6260.718267677966;6274.403931626488;6278.372162783868;6251.156093927261;6312.147860038836;6313.779017734358;6328.557001010069;6329.398707122816;4972.7762250699525;6060.969695151496;6268.843278918063;6286.717097730521;6332.905006424815;5207.218917162456;5983.341832398346;6047.727852246748;6060.212172417734;6060.939390606038;5025.74871256443;6189.412005859436;6203.503719702503;6255.874882502233;6259.436650701458;5941.287045554561;6051.818445446644;6101.9389806103145;6121.877562448815;6150.138980542638;5225.294585231097;5726.255586773693;5878.030125029493;6125.540584456273;6128.142060111499;5813.6221145625395;5824.184614153937;5831.591788574759;5954.136650185612;5986.55100867428;6118.010103775157;6123.4961516426065;6125.204929265209;6127.372786728296;6134.681512609229;5875.148103525051;5920.289565252025;5937.732802023833;5937.960856850121;5939.613925094855 03:11:50 INFO - PROCESS | 1642 | |1;ext.html;456.4317424806116;463.69164505603754;511.6827566908517;533.4292307231265;539.9190121481779;30938.76244950202;39364.40953385698;40470.99999999993;40670.593294067054;40728;41585.37621935673;41954.37068443976;41956.160876782444;41994.79002604983;42144.36783448251;9232.26141908649;9404.576794044251;9405.858912116324;9408.576614052385;9415.952920235424;9371.29715271354;9484.478353690543;9484.478353690543;9489.667861624845;9501.049895010512;9450.432974021547;9503;9508.096730810597;9512.714618561455;9536.141747242747;9412.388194767318;9458.148766611039;9458.57436415363;9465.810683786354;9476.526173691287;7894.605269736548;9323.160915517636;9370.922343930399;9425;9429.858552121723;32050.83974580112;38721.80639096787;38949.415758763644;38952.41571376432;39057.41413878794;3241.773075884689;3357.6978071973394;3710.2023065041144;3755.1550901047317;3785.9242815143543;27659.86170069157;28201;29170.854145729136;30276.697233027608;30536.69463305361;3131.2171957010746;3166.9683303166903;3294.7199488043543;3302.0424077017638;3333.3166700826273;478512;485240;486991.99999999645;487010;505968;53730.462695372546;56584.151237731465;56957;65004;66504.6674766623;66194.00708989368;69716;69738.65130674314;69821.65089174522;69826.65086674535;16053.759193612104;16272.75590866138;16280.674386512203;16295.266712997944;16304.266308016167;12263.325517096646;12381.62855114348;12383.938080309721;12390.256584604953;12405.821446962524;19416.805831941783;19886;19892.50268743293;19916.402507924784;19954.501137471678;7893.921060789318;8096;8105.594720263964;8173.591320433955;8350.95613048369;861.3668953319306;957.3824882950458;959.318883592643;1026;1069.978600427987;29876.701232987827;29925.67634525798;29928.850355748516;30320.241993950327;30374.54438183429;858.656537385051;1033.405791669793;1058.751193469534;1059.708580140452;1094.1137678480297;14547.78178327326;15011.774823377658;15043.022203556733;15139.730039979573;15396.923015384851;668757;675959;676526;677948;678136;55553;55986.72006639941;55990;56004.5998850032;56016.43983560194;1206.975860482803;1277.3485522383467;1282.33959510852;1307.3332600373687;1363.7340718559785;28469.00358487443;30411.54382684217;30551.08346749601;31195;31302.686973129978;48072.7596362016;48486;48574;48635;48639.75680121718;537585;541014.8340357865;541213;542455;542470;26718.19845404641;27373;27479.587806182524;28241.01156459514;41924;46462;46563.76718116523;46660;47049;47237.7638111821;38099.42850857184;38487.807560963134;38802.6119738799;39350.40974385444;40039.39940900831;38047.810744993236;38174.427383588714;38932.41601376038;39354;39419.60580394274;37552.24895502129;37684.62315376811;37709.622903771706;37744.24511509809;38018.619813802616;26524.469510610066;32666.510002349507;33560;33715.65137394477;34104.65895341015;24664.013439462222;25219.495610087328;25363.87318063398;25955;26413.471730565667 03:11:50 INFO - PROCESS | 1642 | |2;jquery.html;352.85532931498057;367.86940636074195;371.53743589231414;382.86599690108454;401.9135885784556;14638.92680536597;15167.089974601518;19989.300374486877;20344.389668309937;20770.896145519288;18697.808063373617;18960.33638822638;19003.619927601474;19176.71234931477;20248.08883600238;5708.343540492845;5994.6403215807195;6045.546584006217;6064;6066.302375226839;5576.553875689935;5860.619059761123;5943.286805583336;5986.910196347058;6050.03199488082;5015.899682006358;5968.582199246054;5974.641521508702;5978.8206353809455;5981.222441082653;5907.6750778707155;5962.642241465504;6529;6644.60132392058;6736.393724564764;5007.599392048627;5950.1372301016545;5952.345242023374;5961.970190149065;5963;13220.33898305081;13561.186328820302;13672;13673.974451916147;13690.315484225848;2198.2416066457045;2220.3006053093222;2346.6128088865316;2823.378856651533;2856.7857410694073;7937.880931786028;8610.913890861137;10047.045530674573;10197.18422526196;10515.369077855277;1787.9554555053048;1804.6480936217442;1818.8999605021718;1888.357958294173;1926.7687877454796;110683;119180;123068.38465807703;125069;129666.70333296641;11278.492467838967;11562.421878906021;11656.18406711522;11670.591529296604;11862.22895511789;12870.806937895939;13616.523421680391;13628.86371136276;13659.931700341633;14433.422663093359;8348.499090054616;8479.364047696448;8507;8572.914270857336;8625.913740862636;8318.459300145592;8345.165483451607;8384.916150838413;8558.35812314079;8563.529005904735;4447.955520444754;4529.728216306966;4562.338460923148;4586.339922844742;4592.357070010201;1843.5298998755231;1900.8194221548931;1915.8658893877296;1928.1130679887128;1953.091812307284;342.9967345514361;387.72277821357864;391.2722336454193;833.829065041665;890.6125835261682;11190.713067997285;11779;11849.843048436527;11946.641600751991;12108.576199832964;430.7523174174862;435.83656128951645;442.5574425574426;453.4626467635862;457.1246063787809;5859.355470898197;5978.641281523035;5998.610090344114;6144.938550614436;6164.722587483574;570006;623113;630950;637595;638577;142572;144857;150250;150988.2450587762;151294;956.5408603870181;996.0885789502669;1025.7845852370936;1029.3978022856713;1032.0453580438066;18489.445316640522;19304.227830886608;19830.107645155713;20155.69766453475;20330;58200;58309;59285.11072333832;59820.70089649524;60418;66606.00090998736;67823.98264026143;82294.58852705699;84170.316593669;85343.57328213319;6300.9684951574945;8006.479578827591;8071.8699382087725;8232.958835205785;8606.698765543426;12079.879201208227;13003.154794938677;13067.803982940073;13541;13603.455861765418;11220.495077721358;11265;11377.030185588601;11470.885291146982;11623.59317423886;11013.83479247796;11482.885171148515;11509.079273658255;11569.479373428385;11600.30398176112;7892.4869883457395;10298.433586152834;11014.889851101387;11564.363959982282;11861.40692965331;8107.473014254055;8263.793405164919;8343.165683431609;8535.01847287545;8550.615222314886;9643.951780241054;11731.530738770354;11885.940570297093;11904.642860714192;11957.880421195676 03:11:50 INFO - PROCESS | 1642 | |3;mootools.html;1319.4260496683949;1368.1517459175313;1457.548160070378;1606.7188242057643;1826.4611939477845;7389.889151662729;7764.107127680311;8066.677332906676;8338;8351.039630442501;8213.26080652741;8308.418410711252;8312.376571757111;8319.251267385935;8323.791905202374;2220.877851718155;2243.7868402501817;2246.9327069641954;2248.3142641494337;2249.7750224977535;1984.9702254466195;2181.6618424144285;2253.3352660965056;2254.830887683422;2260.3106052653934;2182.3452964110784;2255;2257.0971611355426;2262.909483620653;2321.0599707118645;2262.751097379287;2281.2129815213725;2305.5158416732506;2306.5041016181435;2307.896144673485;2291.51878105598;2322.9070837166664;2325.267540724666;2331.1258278145697;2335.1476711000532;8229.094799572042;8305.837182794414;8315;8317.376196785266;8324.583770811436;4957.182064959277;5150.459201783834;5165.612579056586;5169.353830771153;5189;3250.2686895448574;3471.704905083071;3515.2793677296095;3549.361114999299;3551.129973156593;1028.7059856520002;1049.5959055763585;1073.0289088375032;1074.2963358999868;1085.0560012788844;10717.249792514527;10737.677869663923;10834.404107773991;10842.674719758419;10851.837222441669;4204.957950420518;4323.762193079411;4443.422355093817;4447.332900064951;4457.197704413172;8577.485350878967;8596.527191004554;8611.095834937241;8619.22426981562;8642.56787160652;5038.47096054909;5040.647154699209;5047.091523525801;5065.417476990119;5065.848024559269;4102.179564087194;4256.382824490432;4385.736855788663;4397.340398940185;4669.486356500782;3593.443016332473;3624.3113808376324;3690.6493883081066;3694.649008344203;3702.3705969985167;1005.969820905374;1141.8458508101467;1144.8454458648143;1145.66202970124;1152.6138743520846;788.251161396664;853.9786505337416;895.861141523052;901.2519608724742;907.3195103672246;5372.623916325898;5525.62191906452;5598.776048957996;5685.715714214272;5760.308762948389;419.59928268503506;430.184799804369;449.35741889098904;449.49880882815427;450.1290003842575;3599.2441587266967;3720.3861362875364;3724.646158614927;3780.697544196458;3874.4575759393706;9887.159591434809;10197.286189966631;10313;10319;10321.74195645115;9842.655507057361;10014.599416023277;10265.538050787733;10266.6406675766;10267.024632659886;7813.218678132142;7901.762947111596;7920.722774702857;7922.049354077548;7941.444098913021;5324.334458192726;5479.17812328138;5499.257600223918;5562.749676264659;5572.275604171432;9325.766855828659;9331.486768227813;9391.060893910555;9524.190443812353;9558.61765529371;9381.530923453663;9573.138417542455;9586.952065239908;9637.759056023655;9717.222622190066;6543.854825405439;6626.072349871119;6641.302663220486;6737.562058466184;6740.73037078531;7724.6910123594425;7903.762887113396;7904.881426778488;7909.960450197712;7912.169222231582;6452.677366131768;6714.294999025032;7032.156141262876;7106.111736032996;7287.927120728725;6935.3411425914455;6946.826329341807;6977.825554361181;7043.894341584983;7060.752873649398;6240.344763799917;6263;6284.4344009039405;6384.425401713868;6411.326810684997;4790.161721698759;4797.160496913096;4851.733154676385;4859.635527335393;4926.679765815211;4842.612590992782;4859.781309841136;4934.087193869145;4958.504149585013;4983.903541220926 03:11:50 INFO - PROCESS | 1642 | |4;prototype.html;295.5773244260706;301.394197662698;308.57262691172724;311.1521104988907;313.9011211468387;5253.50458532197;5310.468953104692;5371.731413429323;5391.218273350367;5395.325584301962;4952.801887924487;4963.840729282764;5068.923966140511;5149.382074151098;5322.574194064475;3855.3638649622917;3909.2181563687377;3969.9404508932316;4008.4388185654034;4018.839246430139;3361.7982921024695;3977.2840888640035;3985.561588225293;3996.920061598766;4054.1688953764406;3859.4017927221325;3967.682585393176;3989.98005009976;4028.1943611277716;4033.838646454138;3770.3401904666575;3979.960200397988;3989.7207195496326;3992.6207010334115;4012.4382586437778;3032.4996375598;3049.039552540965;3314.585676790401;3425.400554902882;3499.6500349965054;4446.510883802779;4461.196984542781;4855.490173531799;5072.289879416885;5128.666636668605;4361.912761744779;4544.159615355787;4558.58972692456;4575.313702944552;4588.632909367243;4565.543445655441;5464.562834973192;5481.862953426156;5492.462110609036;5513.338399392059;3224.0977823084495;3537.6108628050893;3689.3912504436735;3790.5451345838674;3876.3216437123674;5403.243545903577;5418.322709661293;5640.407757185478;5658.151277308396;5793.362730099686;3123.2504198992533;3273.7708360415018;3888.319544079775;3895.0397697749913;3946.1910308387287;2953.4536110819568;4006.5192176938954;4016.156607112539;4034.293998550242;4041.6968727345566;3821.464994900716;4190.832366705358;4605.861824145281;4626.629869610423;4630.768461576908;5519.365272993577;5523.696196709219;5537.535796570487;5540.750666220029;5611.9438805612235;1132.8501570905537;1204.1811568133578;1241.391718058145;1273.2678709741936;1275.5344299330864;757.7764559454974;786.4062632712265;800.7917941335295;838.6581469648636;840.4579046514964;4811.659058445123;4891.706497610155;4900.975495122501;4923.261510773413;4962.8759281018265;4428.472177098895;4741.431028276629;4847.103285892122;5384.6769193848495;5398.541124004425;4220.683448741357;4685.9531404686195;4835.274708793639;4899.848535594133;4979.526944940225;2452.871679027667;2481.925542233736;2654.7788017511775;2715.212588349396;2800.4399120176045;5110.7955681773055;5405.351397797891;5407.215953686772;5441.415327719381;5484.753186106555;4488.169688607618;4912.828051018198;5001.124803159505;5187.273781670569;5440.238366628675;4460.3532487789735;4823.686460380064;4989.850304490871;5248.868778280497;5412.322180124017;4971.676841005323;5100.770465328995;5213.869653258699;5285.63000589955;5358.464153584766;4552.339910712994;4646.09510100857;5247.699823178694;5254.395744489432;5291.512988895213;4813.95186048149;4911.347872148999;5001.899962000813;5036.798528058836;5205.73971301441;3096.8916087936814;3317.535545023747;3383.2725963917874;3571.803445845671;3585.6772890439984;5037.035556133184;5281.80450827244;5288.3918349390315;5298.311219541589;5307.805743707635;4413.602775750198;4901.362822833152;5083.974580127076;5103;5113.53978141969;5079.92380114306;5096.668716533562;5151;5172.646445867046;5174.301469301596;4982.975085124551;5220.869478263074;5330.813421530228;5463.836084917459;5479.013937404045;5116.923246151385;5408.648437851527;5417.8103766367985;5442.700651464207;5492.050322135584;4634.304854271886;4956.355673762532;5332.253484512249;5410.458954104558;5440.939016891826 03:11:50 INFO - PROCESS | 1642 | |5;yui.html;628.3371043549056;764.1562938419751;780.4458834227698;786.5320134519959;788.2078511096347;13602.863971360284;14360.353784079716;14591.635209119777;14611.196384198865;14644.926775366122;12701.555445559385;12886.613401597968;13004.47982080718;13049.804252936214;13222.471101155943;664.9900251496232;825.108882406999;828.5525816059321;829.2039641943742;831.4720152703047;681.8125015620694;686.7218776395542;811.4482152136545;829.0921441022072;840.3613253926999;856.6744637037917;872.4198408058666;884.1600479544427;885.0485727842563;893.5175005497024;877.5305211711703;878.0473186592554;879.7976465412927;880.5553195636163;883.5935469683953;508.28332051806774;521.8199721096254;522.305333905908;522.8745101175729;524.1587252459823;12277.508899644003;12633.810492842615;12693.682657933625;12874.098813083088;12879.806802897963;13434.664133396744;13713.794293085612;13788.310584470837;13920.234387108705;14008.439662413492;9455.385399948982;11021.669349919515;11084.390358530358;11283.548658053667;11597.13021523389;3580.820958952042;3695.1685870679153;3720.776753394805;3807.124361396894;3822.3502004659276;91538.08461915363;92075;94978.99999999932;95438;95589;10382.377057376581;10416.479176041168;10536.63121790749;10550.47247637615;10579.682609521727;16580;19274.42176734672;20024.59950800976;20184.49538761543;20361;7252.347288744038;7256.8911466328045;7451.478396512301;7585.582792946441;7603.505772124794;3734.8982050295226;4248.362745588125;4254.723442976197;4291.978540107342;4324.524302326741;3322.069820450278;3364.2094107884627;3386.508956201337;3453.188500702333;3456.3087382523595;541.4342012596845;580.6342004537115;650.8080116365684;687.3676217879563;694.2259380790382;550.3324925475815;561.5732043646858;642.399356601579;661.3320546248324;665.9134312539339;7454.781652843031;8516.233538981398;9100.635974560944;9320.72037838866;9392;516.9612279079009;598.6497898729292;665.0622622102812;671.70445004198;689.4105539763531;4111.958880411157;4466.486354069324;4516.187086324493;4562.292844609091;4908.852734417973;116320;117825.8217417832;121169.39415302867;121949;126579.03815290247;68623.97064044043;81491;82007.7698834518;85589.71615425774;89550.10449895548;56543.71728141333;60467.69766151141;84348.15651843403;85016.99999999876;86257;9398.81202375935;9630;9697.806043879224;9712.660056897974;9731.41611503312;54228.457715422344;55680.443195567525;55958;57299.71350143389;58379.540511487554;75486.49027019538;77868;78348.60825696064;78748;78894.6055269743;9966.152877005246;12647.747045059232;12698.365081745691;12881.871181288067;13358.198508089547;13928.51250206238;14244.801394595941;14438.711225775636;15042.275480221664;15197.164155971528;12727.681807954876;13017.284049377375;13178.670533236746;13440.865591343962;13459.932700336827;12545.623631291075;13232.735345292847;13316.40076196554;13451.46194152262;13769.449222031006;12321.01431885465;12921.612351629467;13008.349582520646;13084.28036458004;13427.328633568477;10560.049595536439;10730.356178629307;10914.94211773147;11197.944010280222;11223.943880280547;11376.601818936297;11415.258008229439;11669.474873630537;11690.00634946005;11697.356645384585 03:11:50 INFO - PROCESS | 1642 | __end_tp_report 03:11:50 INFO - PROCESS | 1642 | __start_cc_report 03:11:50 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1516 03:11:50 INFO - PROCESS | 1642 | __end_cc_report 03:11:50 INFO - PROCESS | 1642 | __startTimestamp1482318710162__endTimestamp 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | ------- Summary: start ------- 03:11:50 INFO - PROCESS | 1642 | Number of tests: 6 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5691.72 Median:5824.18 stddev:644.23 (11.1%) stddev-sans-first:611.53 03:11:50 INFO - PROCESS | 1642 | Values: 3033.5 3326.9 3331.6 3340.3 3384.7 5642.6 5746.0 5758.5 5763.9 5767.6 5683.3 5765.0 5769.6 5772.2 5780.7 5175.7 5202.6 5204.2 5233.2 5252.1 5093.7 5159.2 5162.4 5231.4 5298.0 5144.4 5180.9 5205.6 5230.0 5245.3 5215.7 5292.8 5299.5 5300.3 5311.1 5162.6 5211.1 5223.2 5247.8 5249.8 5563.0 5609.0 5630.4 5659.9 5678.3 6114.3 6141.7 6177.6 6179.2 6226.0 6237.5 6278.4 6279.1 6282.5 6287.7 4808.4 5649.4 5701.8 5755.8 5829.8 6286.5 6302.6 6313.6 6317.7 6321.3 5444.6 6140.3 6147.0 6158.7 6162.9 5872.1 6104.4 6116.2 6129.5 6134.5 6153.4 6230.2 6251.3 6255.7 6258.2 6197.8 6224.7 6270.2 6289.6 6299.7 4844.7 4875.0 4947.6 4959.8 4980.5 4437.8 4448.6 4450.3 4463.9 4479.7 5736.4 5745.4 5753.4 5753.6 5758.7 5800.6 6223.2 6234.1 6235.8 6251.3 5241.6 5361.6 5746.1 5749.8 5750.5 5357.6 5373.8 5380.9 5385.0 5386.0 6242.0 6243.4 6260.7 6274.4 6278.4 6251.2 6312.1 6313.8 6328.6 6329.4 4972.8 6061.0 6268.8 6286.7 6332.9 5207.2 5983.3 6047.7 6060.2 6060.9 5025.7 6189.4 6203.5 6255.9 6259.4 5941.3 6051.8 6101.9 6121.9 6150.1 5225.3 5726.3 5878.0 6125.5 6128.1 5813.6 5824.2 5831.6 5954.1 5986.6 6118.0 6123.5 6125.2 6127.4 6134.7 5875.1 5920.3 5937.7 5938.0 5939.6 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:70145.31 Median:39729.50 stddev:153429.90 (386.2%) stddev-sans-first:153771.09 03:11:50 INFO - PROCESS | 1642 | Values: 456.4 463.7 511.7 533.4 539.9 30938.8 39364.4 40471.0 40670.6 40728.0 41585.4 41954.4 41956.2 41994.8 42144.4 9232.3 9404.6 9405.9 9408.6 9416.0 9371.3 9484.5 9484.5 9489.7 9501.0 9450.4 9503.0 9508.1 9512.7 9536.1 9412.4 9458.1 9458.6 9465.8 9476.5 7894.6 9323.2 9370.9 9425.0 9429.9 32050.8 38721.8 38949.4 38952.4 39057.4 3241.8 3357.7 3710.2 3755.2 3785.9 27659.9 28201.0 29170.9 30276.7 30536.7 3131.2 3167.0 3294.7 3302.0 3333.3 478512.0 485240.0 486992.0 487010.0 505968.0 53730.5 56584.2 56957.0 65004.0 66504.7 66194.0 69716.0 69738.7 69821.7 69826.7 16053.8 16272.8 16280.7 16295.3 16304.3 12263.3 12381.6 12383.9 12390.3 12405.8 19416.8 19886.0 19892.5 19916.4 19954.5 7893.9 8096.0 8105.6 8173.6 8351.0 861.4 957.4 959.3 1026.0 1070.0 29876.7 29925.7 29928.9 30320.2 30374.5 858.7 1033.4 1058.8 1059.7 1094.1 14547.8 15011.8 15043.0 15139.7 15396.9 668757.0 675959.0 676526.0 677948.0 678136.0 55553.0 55986.7 55990.0 56004.6 56016.4 1207.0 1277.3 1282.3 1307.3 1363.7 28469.0 30411.5 30551.1 31195.0 31302.7 48072.8 48486.0 48574.0 48635.0 48639.8 537585.0 541014.8 541213.0 542455.0 542470.0 26718.2 27373.0 27479.6 28241.0 41924.0 46462.0 46563.8 46660.0 47049.0 47237.8 38099.4 38487.8 38802.6 39350.4 40039.4 38047.8 38174.4 38932.4 39354.0 39419.6 37552.2 37684.6 37709.6 37744.2 38018.6 26524.5 32666.5 33560.0 33715.7 34104.7 24664.0 25219.5 25363.9 25955.0 26413.5 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:35640.48 Median:360.36 stddev:104197.31 (28914.6%) stddev-sans-first:104455.92 03:11:50 INFO - PROCESS | 1642 | Values: 352.9 367.9 371.5 382.9 401.9 14638.9 15167.1 19989.3 20344.4 20770.9 18697.8 18960.3 19003.6 19176.7 20248.1 5708.3 5994.6 6045.5 6064.0 6066.3 5576.6 5860.6 5943.3 5986.9 6050.0 5015.9 5968.6 5974.6 5978.8 5981.2 5907.7 5962.6 6529.0 6644.6 6736.4 5007.6 5950.1 5952.3 5962.0 5963.0 13220.3 13561.2 13672.0 13674.0 13690.3 2198.2 2220.3 2346.6 2823.4 2856.8 7937.9 8610.9 10047.0 10197.2 10515.4 1788.0 1804.6 1818.9 1888.4 1926.8 110683.0 119180.0 123068.4 125069.0 129666.7 11278.5 11562.4 11656.2 11670.6 11862.2 12870.8 13616.5 13628.9 13659.9 14433.4 8348.5 8479.4 8507.0 8572.9 8625.9 8318.5 8345.2 8384.9 8558.4 8563.5 4448.0 4529.7 4562.3 4586.3 4592.4 1843.5 1900.8 1915.9 1928.1 1953.1 343.0 387.7 391.3 833.8 890.6 11190.7 11779.0 11849.8 11946.6 12108.6 430.8 435.8 442.6 453.5 457.1 5859.4 5978.6 5998.6 6144.9 6164.7 570006.0 623113.0 630950.0 637595.0 638577.0 142572.0 144857.0 150250.0 150988.2 151294.0 956.5 996.1 1025.8 1029.4 1032.0 18489.4 19304.2 19830.1 20155.7 20330.0 58200.0 58309.0 59285.1 59820.7 60418.0 66606.0 67824.0 82294.6 84170.3 85343.6 6301.0 8006.5 8071.9 8233.0 8606.7 12079.9 13003.2 13067.8 13541.0 13603.5 11220.5 11265.0 11377.0 11470.9 11623.6 11013.8 11482.9 11509.1 11569.5 11600.3 7892.5 10298.4 11014.9 11564.4 11861.4 8107.5 8263.8 8343.2 8535.0 8550.6 9644.0 11731.5 11885.9 11904.6 11957.9 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5358.56 Median:4945.63 stddev:2995.39 (60.6%) stddev-sans-first:2988.41 03:11:50 INFO - PROCESS | 1642 | Values: 1319.4 1368.2 1457.5 1606.7 1826.5 7389.9 7764.1 8066.7 8338.0 8351.0 8213.3 8308.4 8312.4 8319.3 8323.8 2220.9 2243.8 2246.9 2248.3 2249.8 1985.0 2181.7 2253.3 2254.8 2260.3 2182.3 2255.0 2257.1 2262.9 2321.1 2262.8 2281.2 2305.5 2306.5 2307.9 2291.5 2322.9 2325.3 2331.1 2335.1 8229.1 8305.8 8315.0 8317.4 8324.6 4957.2 5150.5 5165.6 5169.4 5189.0 3250.3 3471.7 3515.3 3549.4 3551.1 1028.7 1049.6 1073.0 1074.3 1085.1 10717.2 10737.7 10834.4 10842.7 10851.8 4205.0 4323.8 4443.4 4447.3 4457.2 8577.5 8596.5 8611.1 8619.2 8642.6 5038.5 5040.6 5047.1 5065.4 5065.8 4102.2 4256.4 4385.7 4397.3 4669.5 3593.4 3624.3 3690.6 3694.6 3702.4 1006.0 1141.8 1144.8 1145.7 1152.6 788.3 854.0 895.9 901.3 907.3 5372.6 5525.6 5598.8 5685.7 5760.3 419.6 430.2 449.4 449.5 450.1 3599.2 3720.4 3724.6 3780.7 3874.5 9887.2 10197.3 10313.0 10319.0 10321.7 9842.7 10014.6 10265.5 10266.6 10267.0 7813.2 7901.8 7920.7 7922.0 7941.4 5324.3 5479.2 5499.3 5562.7 5572.3 9325.8 9331.5 9391.1 9524.2 9558.6 9381.5 9573.1 9587.0 9637.8 9717.2 6543.9 6626.1 6641.3 6737.6 6740.7 7724.7 7903.8 7904.9 7910.0 7912.2 6452.7 6714.3 7032.2 7106.1 7287.9 6935.3 6946.8 6977.8 7043.9 7060.8 6240.3 6263.0 6284.4 6384.4 6411.3 4790.2 4797.2 4851.7 4859.6 4926.7 4842.6 4859.8 4934.1 4958.5 4983.9 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4312.15 Median:4900.41 stddev:1312.46 (26.8%) stddev-sans-first:1281.05 03:11:50 INFO - PROCESS | 1642 | Values: 295.6 301.4 308.6 311.2 313.9 5253.5 5310.5 5371.7 5391.2 5395.3 4952.8 4963.8 5068.9 5149.4 5322.6 3855.4 3909.2 3969.9 4008.4 4018.8 3361.8 3977.3 3985.6 3996.9 4054.2 3859.4 3967.7 3990.0 4028.2 4033.8 3770.3 3980.0 3989.7 3992.6 4012.4 3032.5 3049.0 3314.6 3425.4 3499.7 4446.5 4461.2 4855.5 5072.3 5128.7 4361.9 4544.2 4558.6 4575.3 4588.6 4565.5 5464.6 5481.9 5492.5 5513.3 3224.1 3537.6 3689.4 3790.5 3876.3 5403.2 5418.3 5640.4 5658.2 5793.4 3123.3 3273.8 3888.3 3895.0 3946.2 2953.5 4006.5 4016.2 4034.3 4041.7 3821.5 4190.8 4605.9 4626.6 4630.8 5519.4 5523.7 5537.5 5540.8 5611.9 1132.9 1204.2 1241.4 1273.3 1275.5 757.8 786.4 800.8 838.7 840.5 4811.7 4891.7 4901.0 4923.3 4962.9 4428.5 4741.4 4847.1 5384.7 5398.5 4220.7 4686.0 4835.3 4899.8 4979.5 2452.9 2481.9 2654.8 2715.2 2800.4 5110.8 5405.4 5407.2 5441.4 5484.8 4488.2 4912.8 5001.1 5187.3 5440.2 4460.4 4823.7 4989.9 5248.9 5412.3 4971.7 5100.8 5213.9 5285.6 5358.5 4552.3 4646.1 5247.7 5254.4 5291.5 4814.0 4911.3 5001.9 5036.8 5205.7 3096.9 3317.5 3383.3 3571.8 3585.7 5037.0 5281.8 5288.4 5298.3 5307.8 4413.6 4901.4 5084.0 5103.0 5113.5 5079.9 5096.7 5151.0 5172.6 5174.3 4983.0 5220.9 5330.8 5463.8 5479.0 5116.9 5408.6 5417.8 5442.7 5492.1 4634.3 4956.4 5332.3 5410.5 5440.9 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20448.58 Median:4539.24 stddev:30257.88 (666.6%) stddev-sans-first:30306.16 03:11:50 INFO - PROCESS | 1642 | Values: 628.3 764.2 780.4 786.5 788.2 13602.9 14360.4 14591.6 14611.2 14644.9 12701.6 12886.6 13004.5 13049.8 13222.5 665.0 825.1 828.6 829.2 831.5 681.8 686.7 811.4 829.1 840.4 856.7 872.4 884.2 885.0 893.5 877.5 878.0 879.8 880.6 883.6 508.3 521.8 522.3 522.9 524.2 12277.5 12633.8 12693.7 12874.1 12879.8 13434.7 13713.8 13788.3 13920.2 14008.4 9455.4 11021.7 11084.4 11283.5 11597.1 3580.8 3695.2 3720.8 3807.1 3822.4 91538.1 92075.0 94979.0 95438.0 95589.0 10382.4 10416.5 10536.6 10550.5 10579.7 16580.0 19274.4 20024.6 20184.5 20361.0 7252.3 7256.9 7451.5 7585.6 7603.5 3734.9 4248.4 4254.7 4292.0 4324.5 3322.1 3364.2 3386.5 3453.2 3456.3 541.4 580.6 650.8 687.4 694.2 550.3 561.6 642.4 661.3 665.9 7454.8 8516.2 9100.6 9320.7 9392.0 517.0 598.6 665.1 671.7 689.4 4112.0 4466.5 4516.2 4562.3 4908.9 116320.0 117825.8 121169.4 121949.0 126579.0 68624.0 81491.0 82007.8 85589.7 89550.1 56543.7 60467.7 84348.2 85017.0 86257.0 9398.8 9630.0 9697.8 9712.7 9731.4 54228.5 55680.4 55958.0 57299.7 58379.5 75486.5 77868.0 78348.6 78748.0 78894.6 9966.2 12647.7 12698.4 12881.9 13358.2 13928.5 14244.8 14438.7 15042.3 15197.2 12727.7 13017.3 13178.7 13440.9 13459.9 12545.6 13232.7 13316.4 13451.5 13769.4 12321.0 12921.6 13008.3 13084.3 13427.3 10560.0 10730.4 10914.9 11197.9 11223.9 11376.6 11415.3 11669.5 11690.0 11697.4 03:11:50 INFO - PROCESS | 1642 | -------- Summary: end -------- 03:11:50 INFO - PROCESS | 1642 | 03:11:50 INFO - TEST-INFO | 1642: exit 0 03:11:50 INFO - TEST-OK | dromaeo_css | took 1120332ms 03:11:50 INFO - TEST-START | kraken 03:11:50 INFO - operating with platform_type : mac_ 03:11:50 INFO - Initialising browser for kraken test... 03:11:50 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpOjapqY/profile http://localhost:49243/getInfo.html) 03:11:53 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 03:11:53 INFO - PROCESS | 1649 | colorDepth:24 03:11:53 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 03:11:53 INFO - PROCESS | 1649 | __metrics 03:11:53 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:11:53 INFO - PROCESS | 1649 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:11:54 INFO - TEST-INFO | 1649: exit 0 03:11:54 INFO - Browser initialized. 03:11:54 INFO - Running cycle 1/1 for kraken test... 03:11:54 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpOjapqY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:06 INFO - PROCESS | 1651 | RSS: Main: 222515200 03:12:06 INFO - PROCESS | 1651 | 03:12:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 03:12:10 INFO - PROCESS | 1651 | RSS: Main: 272265216 03:12:10 INFO - PROCESS | 1651 | 03:12:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 03:12:13 INFO - PROCESS | 1651 | RSS: Main: 283095040 03:12:13 INFO - PROCESS | 1651 | 03:12:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 03:12:17 INFO - PROCESS | 1651 | RSS: Main: 379752448 03:12:17 INFO - PROCESS | 1651 | 03:12:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 03:12:20 INFO - PROCESS | 1651 | RSS: Main: 282890240 03:12:20 INFO - PROCESS | 1651 | 03:12:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:12:22 INFO - PROCESS | 1651 | RSS: Main: 284422144 03:12:22 INFO - PROCESS | 1651 | 03:12:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 03:12:26 INFO - PROCESS | 1651 | RSS: Main: 324677632 03:12:26 INFO - PROCESS | 1651 | 03:12:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 03:12:29 INFO - PROCESS | 1651 | RSS: Main: 326918144 03:12:29 INFO - PROCESS | 1651 | 03:12:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 03:12:33 INFO - PROCESS | 1651 | RSS: Main: 322195456 03:12:33 INFO - PROCESS | 1651 | 03:12:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:12:35 INFO - PROCESS | 1651 | RSS: Main: 299585536 03:12:35 INFO - PROCESS | 1651 | 03:12:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:12:37 INFO - PROCESS | 1651 | RSS: Main: 299929600 03:12:37 INFO - PROCESS | 1651 | 03:12:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:12:40 INFO - PROCESS | 1651 | RSS: Main: 304832512 03:12:40 INFO - PROCESS | 1651 | 03:12:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:12:44 INFO - PROCESS | 1651 | RSS: Main: 318955520 03:12:44 INFO - PROCESS | 1651 | 03:12:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:12:47 INFO - PROCESS | 1651 | RSS: Main: 312430592 03:12:47 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 03:12:49 INFO - PROCESS | 1651 | RSS: Main: 356638720 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | __start_tp_report 03:12:49 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 03:12:49 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 03:12:49 INFO - PROCESS | 1651 | |i|pagename|runs| 03:12:49 INFO - PROCESS | 1651 | |0;ai-astar;115;114;116;114;111;111;113;112;113;113 03:12:49 INFO - PROCESS | 1651 | |1;audio-beat-detection;150;138;141;143;112;110;109;109;141;112 03:12:49 INFO - PROCESS | 1651 | |2;audio-dft;185;182;182;185;185;192;264;202;192;185 03:12:49 INFO - PROCESS | 1651 | |3;audio-fft;80;59;58;89;60;59;58;58;82;58 03:12:49 INFO - PROCESS | 1651 | |4;audio-oscillator;87;69;95;68;68;69;89;67;70;94 03:12:49 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;87;86;135;125;87;84;89;86;88;86 03:12:49 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;96;94;94;94;94;94;94;93;95 03:12:49 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;100;124;122;82;82;85;91;84;65 03:12:49 INFO - PROCESS | 1651 | |8;json-parse-financial;55;53;53;53;58;58;57;56;57;57 03:12:49 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;70;70;82;79;79;102;97;72;73;73 03:12:49 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;84;86;84;85;84;82;83;96;103;101 03:12:49 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;142;144;140;139;150;157;141;141;188;140 03:12:49 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;165;165;164;165;167;174;228;195;163;165 03:12:49 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;68;67;66;67;67;66;65;66;65 03:12:49 INFO - PROCESS | 1651 | __end_tp_report 03:12:49 INFO - PROCESS | 1651 | __start_cc_report 03:12:49 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2015 03:12:49 INFO - PROCESS | 1651 | __end_cc_report 03:12:49 INFO - PROCESS | 1651 | __startTimestamp1482318769901__endTimestamp 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | ------- Summary: start ------- 03:12:49 INFO - PROCESS | 1651 | Number of tests: 14 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:1.62 (1.4%) stddev-sans-first:1.58 03:12:49 INFO - PROCESS | 1651 | Values: 115.0 114.0 116.0 114.0 111.0 111.0 113.0 112.0 113.0 113.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:139.50 stddev:17.26 (12.4%) stddev-sans-first:16.08 03:12:49 INFO - PROCESS | 1651 | Values: 150.0 138.0 141.0 143.0 112.0 110.0 109.0 109.0 141.0 112.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:195.40 Median:188.50 stddev:24.86 (13.2%) stddev-sans-first:26.09 03:12:49 INFO - PROCESS | 1651 | Values: 185.0 182.0 182.0 185.0 185.0 192.0 264.0 202.0 192.0 185.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.10 Median:59.50 stddev:12.34 (20.7%) stddev-sans-first:12.02 03:12:49 INFO - PROCESS | 1651 | Values: 80.0 59.0 58.0 89.0 60.0 59.0 58.0 58.0 82.0 58.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:11.98 (15.3%) stddev-sans-first:12.22 03:12:49 INFO - PROCESS | 1651 | Values: 87.0 69.0 95.0 68.0 68.0 69.0 89.0 67.0 70.0 94.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:86.50 stddev:18.49 (21.4%) stddev-sans-first:19.36 03:12:49 INFO - PROCESS | 1651 | Values: 87.0 86.0 135.0 125.0 87.0 84.0 89.0 86.0 88.0 86.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.88 (0.9%) stddev-sans-first:0.83 03:12:49 INFO - PROCESS | 1651 | Values: 93.0 96.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 95.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:83.00 stddev:18.46 (22.2%) stddev-sans-first:19.45 03:12:49 INFO - PROCESS | 1651 | Values: 86.0 100.0 124.0 122.0 82.0 82.0 85.0 91.0 84.0 65.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:57.00 stddev:2.06 (3.6%) stddev-sans-first:2.17 03:12:49 INFO - PROCESS | 1651 | Values: 55.0 53.0 53.0 53.0 58.0 58.0 57.0 56.0 57.0 57.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:76.00 stddev:11.26 (14.8%) stddev-sans-first:11.38 03:12:49 INFO - PROCESS | 1651 | Values: 70.0 70.0 82.0 79.0 79.0 102.0 97.0 72.0 73.0 73.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:84.00 stddev:7.98 (9.5%) stddev-sans-first:8.28 03:12:49 INFO - PROCESS | 1651 | Values: 84.0 86.0 84.0 85.0 84.0 82.0 83.0 96.0 103.0 101.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:143.00 stddev:15.07 (10.5%) stddev-sans-first:15.81 03:12:49 INFO - PROCESS | 1651 | Values: 142.0 144.0 140.0 139.0 150.0 157.0 141.0 141.0 188.0 140.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:166.00 stddev:20.92 (12.6%) stddev-sans-first:21.87 03:12:49 INFO - PROCESS | 1651 | Values: 165.0 165.0 164.0 165.0 167.0 174.0 228.0 195.0 163.0 165.0 03:12:49 INFO - PROCESS | 1651 | 03:12:49 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.00 03:12:49 INFO - PROCESS | 1651 | Values: 67.0 68.0 67.0 66.0 67.0 67.0 66.0 65.0 66.0 65.0 03:12:49 INFO - PROCESS | 1651 | -------- Summary: end -------- 03:12:49 INFO - PROCESS | 1651 | 03:12:51 INFO - TEST-INFO | 1651: exit 0 03:12:51 INFO - TEST-OK | kraken | took 60239ms 03:12:51 INFO - SUITE-END | took 1180s 03:12:51 INFO - Completed test suite (00:19:41) 03:12:51 INFO - javascript benchmark 03:12:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.5298028805537, 3326.8502917368714, 3331.633520312766, 3340.265141668832, 3384.712299454546, 5642.633228840127, 5745.971270143644, 5758.452946970031, 5763.942360576399, 5767.567432442563, 5683.3464151622575, 5764.991126552857, 5769.5672824538115, 5772.220750198722, 5780.682062486561, 5175.689458632475, 5202.635815492917, 5204.167333226675, 5233.18885572735, 5252.080885844981, 5093.745312734367, 5159.2003239497935, 5162.354705661793, 5231.398389185254, 5297.993381257568, 5144.3826740791155, 5180.922286165711, 5205.60957928155, 5230.032443997872, 5245.344331958505, 5215.687058776467, 5292.814751483718, 5299.49654782795, 5300.337457817773, 5311.097113490678, 5162.586993040548, 5211.088059589594, 5223.242629818693, 5247.842564723064, 5249.816256431007, 5563.0, 5609.0, 5630.436956304377, 5659.886802263973, 5678.261825962599, 6114.270941054807, 6141.723622437001, 6177.567570270083, 6179.227596550431, 6225.968870155621, 6237.532185086136, 6278.37216278373, 6279.120923070774, 6282.465990390822, 6287.654179020152, 4808.447028591757, 5649.350324712628, 5701.828945131652, 5755.827325180251, 5829.8251052468495, 6286.465650419766, 6302.621842689453, 6313.589616674902, 6317.747290108436, 6321.304656487782, 5444.611624035925, 6140.324564297924, 6146.969265153735, 6158.661273629993, 6162.907556386658, 5872.11918212263, 6104.38956104386, 6116.223169985209, 6129.4618143007265, 6134.4793678840115, 6153.446189842935, 6230.221222347207, 6251.3123556408755, 6255.6872156392, 6258.186435763321, 6197.752089916353, 6224.657643829632, 6270.153529273581, 6289.591176573508, 6299.685015749286, 4844.709317440965, 4874.975625121851, 4947.554720075211, 4959.752012399365, 4980.526849949249, 4437.755923424178, 4448.599626033672, 4450.287953927356, 4463.866084017484, 4479.664025198123, 5736.397678243724, 5745.425457454305, 5753.3958934311295, 5753.597248192586, 5758.654480731169, 5800.5939584229545, 6223.222097237845, 6234.127222188898, 6235.844103897348, 6251.3123556408755, 5241.633085683966, 5361.5710743141235, 5746.080627099644, 5749.8275051748515, 5750.511206547407, 5357.598180136505, 5373.758180881791, 5380.892382152335, 5384.973075134678, 5386.0, 6242.0, 6243.406876346739, 6260.718267677966, 6274.403931626488, 6278.372162783868, 6251.156093927261, 6312.147860038836, 6313.779017734358, 6328.557001010069, 6329.398707122816, 4972.7762250699525, 6060.969695151496, 6268.843278918063, 6286.717097730521, 6332.905006424815, 5207.218917162456, 5983.341832398346, 6047.727852246748, 6060.212172417734, 6060.939390606038, 5025.74871256443, 6189.412005859436, 6203.503719702503, 6255.874882502233, 6259.436650701458, 5941.287045554561, 6051.818445446644, 6101.9389806103145, 6121.877562448815, 6150.138980542638, 5225.294585231097, 5726.255586773693, 5878.030125029493, 6125.540584456273, 6128.142060111499, 5813.6221145625395, 5824.184614153937, 5831.591788574759, 5954.136650185612, 5986.55100867428, 6118.010103775157, 6123.4961516426065, 6125.204929265209, 6127.372786728296, 6134.681512609229, 5875.148103525051, 5920.289565252025, 5937.732802023833, 5937.960856850121, 5939.613925094855], "value": 5651.48568060929, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.4317424806116, 463.69164505603754, 511.6827566908517, 533.4292307231265, 539.9190121481779, 30938.76244950202, 39364.40953385698, 40470.99999999993, 40670.593294067054, 40728.0, 41585.37621935673, 41954.37068443976, 41956.160876782444, 41994.79002604983, 42144.36783448251, 9232.26141908649, 9404.576794044251, 9405.858912116324, 9408.576614052385, 9415.952920235424, 9371.29715271354, 9484.478353690543, 9484.478353690543, 9489.667861624845, 9501.049895010512, 9450.432974021547, 9503.0, 9508.096730810597, 9512.714618561455, 9536.141747242747, 9412.388194767318, 9458.148766611039, 9458.57436415363, 9465.810683786354, 9476.526173691287, 7894.605269736548, 9323.160915517636, 9370.922343930399, 9425.0, 9429.858552121723, 32050.83974580112, 38721.80639096787, 38949.415758763644, 38952.41571376432, 39057.41413878794, 3241.773075884689, 3357.6978071973394, 3710.2023065041144, 3755.1550901047317, 3785.9242815143543, 27659.86170069157, 28201.0, 29170.854145729136, 30276.697233027608, 30536.69463305361, 3131.2171957010746, 3166.9683303166903, 3294.7199488043543, 3302.0424077017638, 3333.3166700826273, 478512.0, 485240.0, 486991.99999999645, 487010.0, 505968.0, 53730.462695372546, 56584.151237731465, 56957.0, 65004.0, 66504.6674766623, 66194.00708989368, 69716.0, 69738.65130674314, 69821.65089174522, 69826.65086674535, 16053.759193612104, 16272.75590866138, 16280.674386512203, 16295.266712997944, 16304.266308016167, 12263.325517096646, 12381.62855114348, 12383.938080309721, 12390.256584604953, 12405.821446962524, 19416.805831941783, 19886.0, 19892.50268743293, 19916.402507924784, 19954.501137471678, 7893.921060789318, 8096.0, 8105.594720263964, 8173.591320433955, 8350.95613048369, 861.3668953319306, 957.3824882950458, 959.318883592643, 1026.0, 1069.978600427987, 29876.701232987827, 29925.67634525798, 29928.850355748516, 30320.241993950327, 30374.54438183429, 858.656537385051, 1033.405791669793, 1058.751193469534, 1059.708580140452, 1094.1137678480297, 14547.78178327326, 15011.774823377658, 15043.022203556733, 15139.730039979573, 15396.923015384851, 668757.0, 675959.0, 676526.0, 677948.0, 678136.0, 55553.0, 55986.72006639941, 55990.0, 56004.5998850032, 56016.43983560194, 1206.975860482803, 1277.3485522383467, 1282.33959510852, 1307.3332600373687, 1363.7340718559785, 28469.00358487443, 30411.54382684217, 30551.08346749601, 31195.0, 31302.686973129978, 48072.7596362016, 48486.0, 48574.0, 48635.0, 48639.75680121718, 537585.0, 541014.8340357865, 541213.0, 542455.0, 542470.0, 26718.19845404641, 27373.0, 27479.587806182524, 28241.01156459514, 41924.0, 46462.0, 46563.76718116523, 46660.0, 47049.0, 47237.7638111821, 38099.42850857184, 38487.807560963134, 38802.6119738799, 39350.40974385444, 40039.39940900831, 38047.810744993236, 38174.427383588714, 38932.41601376038, 39354.0, 39419.60580394274, 37552.24895502129, 37684.62315376811, 37709.622903771706, 37744.24511509809, 38018.619813802616, 26524.469510610066, 32666.510002349507, 33560.0, 33715.65137394477, 34104.65895341015, 24664.013439462222, 25219.495610087328, 25363.87318063398, 25955.0, 26413.471730565667], "value": 19889.40794056537, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.85532931498057, 367.86940636074195, 371.53743589231414, 382.86599690108454, 401.9135885784556, 14638.92680536597, 15167.089974601518, 19989.300374486877, 20344.389668309937, 20770.896145519288, 18697.808063373617, 18960.33638822638, 19003.619927601474, 19176.71234931477, 20248.08883600238, 5708.343540492845, 5994.6403215807195, 6045.546584006217, 6064.0, 6066.302375226839, 5576.553875689935, 5860.619059761123, 5943.286805583336, 5986.910196347058, 6050.03199488082, 5015.899682006358, 5968.582199246054, 5974.641521508702, 5978.8206353809455, 5981.222441082653, 5907.6750778707155, 5962.642241465504, 6529.0, 6644.60132392058, 6736.393724564764, 5007.599392048627, 5950.1372301016545, 5952.345242023374, 5961.970190149065, 5963.0, 13220.33898305081, 13561.186328820302, 13672.0, 13673.974451916147, 13690.315484225848, 2198.2416066457045, 2220.3006053093222, 2346.6128088865316, 2823.378856651533, 2856.7857410694073, 7937.880931786028, 8610.913890861137, 10047.045530674573, 10197.18422526196, 10515.369077855277, 1787.9554555053048, 1804.6480936217442, 1818.8999605021718, 1888.357958294173, 1926.7687877454796, 110683.0, 119180.0, 123068.38465807703, 125069.0, 129666.70333296641, 11278.492467838967, 11562.421878906021, 11656.18406711522, 11670.591529296604, 11862.22895511789, 12870.806937895939, 13616.523421680391, 13628.86371136276, 13659.931700341633, 14433.422663093359, 8348.499090054616, 8479.364047696448, 8507.0, 8572.914270857336, 8625.913740862636, 8318.459300145592, 8345.165483451607, 8384.916150838413, 8558.35812314079, 8563.529005904735, 4447.955520444754, 4529.728216306966, 4562.338460923148, 4586.339922844742, 4592.357070010201, 1843.5298998755231, 1900.8194221548931, 1915.8658893877296, 1928.1130679887128, 1953.091812307284, 342.9967345514361, 387.72277821357864, 391.2722336454193, 833.829065041665, 890.6125835261682, 11190.713067997285, 11779.0, 11849.843048436527, 11946.641600751991, 12108.576199832964, 430.7523174174862, 435.83656128951645, 442.5574425574426, 453.4626467635862, 457.1246063787809, 5859.355470898197, 5978.641281523035, 5998.610090344114, 6144.938550614436, 6164.722587483574, 570006.0, 623113.0, 630950.0, 637595.0, 638577.0, 142572.0, 144857.0, 150250.0, 150988.2450587762, 151294.0, 956.5408603870181, 996.0885789502669, 1025.7845852370936, 1029.3978022856713, 1032.0453580438066, 18489.445316640522, 19304.227830886608, 19830.107645155713, 20155.69766453475, 20330.0, 58200.0, 58309.0, 59285.11072333832, 59820.70089649524, 60418.0, 66606.00090998736, 67823.98264026143, 82294.58852705699, 84170.316593669, 85343.57328213319, 6300.9684951574945, 8006.479578827591, 8071.8699382087725, 8232.958835205785, 8606.698765543426, 12079.879201208227, 13003.154794938677, 13067.803982940073, 13541.0, 13603.455861765418, 11220.495077721358, 11265.0, 11377.030185588601, 11470.885291146982, 11623.59317423886, 11013.83479247796, 11482.885171148515, 11509.079273658255, 11569.479373428385, 11600.30398176112, 7892.4869883457395, 10298.433586152834, 11014.889851101387, 11564.363959982282, 11861.40692965331, 8107.473014254055, 8263.793405164919, 8343.165683431609, 8535.01847287545, 8550.615222314886, 9643.951780241054, 11731.530738770354, 11885.940570297093, 11904.642860714192, 11957.880421195676], "value": 8879.946628428339, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.4260496683949, 1368.1517459175313, 1457.548160070378, 1606.7188242057643, 1826.4611939477845, 7389.889151662729, 7764.107127680311, 8066.677332906676, 8338.0, 8351.039630442501, 8213.26080652741, 8308.418410711252, 8312.376571757111, 8319.251267385935, 8323.791905202374, 2220.877851718155, 2243.7868402501817, 2246.9327069641954, 2248.3142641494337, 2249.7750224977535, 1984.9702254466195, 2181.6618424144285, 2253.3352660965056, 2254.830887683422, 2260.3106052653934, 2182.3452964110784, 2255.0, 2257.0971611355426, 2262.909483620653, 2321.0599707118645, 2262.751097379287, 2281.2129815213725, 2305.5158416732506, 2306.5041016181435, 2307.896144673485, 2291.51878105598, 2322.9070837166664, 2325.267540724666, 2331.1258278145697, 2335.1476711000532, 8229.094799572042, 8305.837182794414, 8315.0, 8317.376196785266, 8324.583770811436, 4957.182064959277, 5150.459201783834, 5165.612579056586, 5169.353830771153, 5189.0, 3250.2686895448574, 3471.704905083071, 3515.2793677296095, 3549.361114999299, 3551.129973156593, 1028.7059856520002, 1049.5959055763585, 1073.0289088375032, 1074.2963358999868, 1085.0560012788844, 10717.249792514527, 10737.677869663923, 10834.404107773991, 10842.674719758419, 10851.837222441669, 4204.957950420518, 4323.762193079411, 4443.422355093817, 4447.332900064951, 4457.197704413172, 8577.485350878967, 8596.527191004554, 8611.095834937241, 8619.22426981562, 8642.56787160652, 5038.47096054909, 5040.647154699209, 5047.091523525801, 5065.417476990119, 5065.848024559269, 4102.179564087194, 4256.382824490432, 4385.736855788663, 4397.340398940185, 4669.486356500782, 3593.443016332473, 3624.3113808376324, 3690.6493883081066, 3694.649008344203, 3702.3705969985167, 1005.969820905374, 1141.8458508101467, 1144.8454458648143, 1145.66202970124, 1152.6138743520846, 788.251161396664, 853.9786505337416, 895.861141523052, 901.2519608724742, 907.3195103672246, 5372.623916325898, 5525.62191906452, 5598.776048957996, 5685.715714214272, 5760.308762948389, 419.59928268503506, 430.184799804369, 449.35741889098904, 449.49880882815427, 450.1290003842575, 3599.2441587266967, 3720.3861362875364, 3724.646158614927, 3780.697544196458, 3874.4575759393706, 9887.159591434809, 10197.286189966631, 10313.0, 10319.0, 10321.74195645115, 9842.655507057361, 10014.599416023277, 10265.538050787733, 10266.6406675766, 10267.024632659886, 7813.218678132142, 7901.762947111596, 7920.722774702857, 7922.049354077548, 7941.444098913021, 5324.334458192726, 5479.17812328138, 5499.257600223918, 5562.749676264659, 5572.275604171432, 9325.766855828659, 9331.486768227813, 9391.060893910555, 9524.190443812353, 9558.61765529371, 9381.530923453663, 9573.138417542455, 9586.952065239908, 9637.759056023655, 9717.222622190066, 6543.854825405439, 6626.072349871119, 6641.302663220486, 6737.562058466184, 6740.73037078531, 7724.6910123594425, 7903.762887113396, 7904.881426778488, 7909.960450197712, 7912.169222231582, 6452.677366131768, 6714.294999025032, 7032.156141262876, 7106.111736032996, 7287.927120728725, 6935.3411425914455, 6946.826329341807, 6977.825554361181, 7043.894341584983, 7060.752873649398, 6240.344763799917, 6263.0, 6284.4344009039405, 6384.425401713868, 6411.326810684997, 4790.161721698759, 4797.160496913096, 4851.733154676385, 4859.635527335393, 4926.679765815211, 4842.612590992782, 4859.781309841136, 4934.087193869145, 4958.504149585013, 4983.903541220926], "value": 4250.508824114598, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.5773244260706, 301.394197662698, 308.57262691172724, 311.1521104988907, 313.9011211468387, 5253.50458532197, 5310.468953104692, 5371.731413429323, 5391.218273350367, 5395.325584301962, 4952.801887924487, 4963.840729282764, 5068.923966140511, 5149.382074151098, 5322.574194064475, 3855.3638649622917, 3909.2181563687377, 3969.9404508932316, 4008.4388185654034, 4018.839246430139, 3361.7982921024695, 3977.2840888640035, 3985.561588225293, 3996.920061598766, 4054.1688953764406, 3859.4017927221325, 3967.682585393176, 3989.98005009976, 4028.1943611277716, 4033.838646454138, 3770.3401904666575, 3979.960200397988, 3989.7207195496326, 3992.6207010334115, 4012.4382586437778, 3032.4996375598, 3049.039552540965, 3314.585676790401, 3425.400554902882, 3499.6500349965054, 4446.510883802779, 4461.196984542781, 4855.490173531799, 5072.289879416885, 5128.666636668605, 4361.912761744779, 4544.159615355787, 4558.58972692456, 4575.313702944552, 4588.632909367243, 4565.543445655441, 5464.562834973192, 5481.862953426156, 5492.462110609036, 5513.338399392059, 3224.0977823084495, 3537.6108628050893, 3689.3912504436735, 3790.5451345838674, 3876.3216437123674, 5403.243545903577, 5418.322709661293, 5640.407757185478, 5658.151277308396, 5793.362730099686, 3123.2504198992533, 3273.7708360415018, 3888.319544079775, 3895.0397697749913, 3946.1910308387287, 2953.4536110819568, 4006.5192176938954, 4016.156607112539, 4034.293998550242, 4041.6968727345566, 3821.464994900716, 4190.832366705358, 4605.861824145281, 4626.629869610423, 4630.768461576908, 5519.365272993577, 5523.696196709219, 5537.535796570487, 5540.750666220029, 5611.9438805612235, 1132.8501570905537, 1204.1811568133578, 1241.391718058145, 1273.2678709741936, 1275.5344299330864, 757.7764559454974, 786.4062632712265, 800.7917941335295, 838.6581469648636, 840.4579046514964, 4811.659058445123, 4891.706497610155, 4900.975495122501, 4923.261510773413, 4962.8759281018265, 4428.472177098895, 4741.431028276629, 4847.103285892122, 5384.6769193848495, 5398.541124004425, 4220.683448741357, 4685.9531404686195, 4835.274708793639, 4899.848535594133, 4979.526944940225, 2452.871679027667, 2481.925542233736, 2654.7788017511775, 2715.212588349396, 2800.4399120176045, 5110.7955681773055, 5405.351397797891, 5407.215953686772, 5441.415327719381, 5484.753186106555, 4488.169688607618, 4912.828051018198, 5001.124803159505, 5187.273781670569, 5440.238366628675, 4460.3532487789735, 4823.686460380064, 4989.850304490871, 5248.868778280497, 5412.322180124017, 4971.676841005323, 5100.770465328995, 5213.869653258699, 5285.63000589955, 5358.464153584766, 4552.339910712994, 4646.09510100857, 5247.699823178694, 5254.395744489432, 5291.512988895213, 4813.95186048149, 4911.347872148999, 5001.899962000813, 5036.798528058836, 5205.73971301441, 3096.8916087936814, 3317.535545023747, 3383.2725963917874, 3571.803445845671, 3585.6772890439984, 5037.035556133184, 5281.80450827244, 5288.3918349390315, 5298.311219541589, 5307.805743707635, 4413.602775750198, 4901.362822833152, 5083.974580127076, 5103.0, 5113.53978141969, 5079.92380114306, 5096.668716533562, 5151.0, 5172.646445867046, 5174.301469301596, 4982.975085124551, 5220.869478263074, 5330.813421530228, 5463.836084917459, 5479.013937404045, 5116.923246151385, 5408.648437851527, 5417.8103766367985, 5442.700651464207, 5492.050322135584, 4634.304854271886, 4956.355673762532, 5332.253484512249, 5410.458954104558, 5440.939016891826], "value": 3890.4681623674264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.3371043549056, 764.1562938419751, 780.4458834227698, 786.5320134519959, 788.2078511096347, 13602.863971360284, 14360.353784079716, 14591.635209119777, 14611.196384198865, 14644.926775366122, 12701.555445559385, 12886.613401597968, 13004.47982080718, 13049.804252936214, 13222.471101155943, 664.9900251496232, 825.108882406999, 828.5525816059321, 829.2039641943742, 831.4720152703047, 681.8125015620694, 686.7218776395542, 811.4482152136545, 829.0921441022072, 840.3613253926999, 856.6744637037917, 872.4198408058666, 884.1600479544427, 885.0485727842563, 893.5175005497024, 877.5305211711703, 878.0473186592554, 879.7976465412927, 880.5553195636163, 883.5935469683953, 508.28332051806774, 521.8199721096254, 522.305333905908, 522.8745101175729, 524.1587252459823, 12277.508899644003, 12633.810492842615, 12693.682657933625, 12874.098813083088, 12879.806802897963, 13434.664133396744, 13713.794293085612, 13788.310584470837, 13920.234387108705, 14008.439662413492, 9455.385399948982, 11021.669349919515, 11084.390358530358, 11283.548658053667, 11597.13021523389, 3580.820958952042, 3695.1685870679153, 3720.776753394805, 3807.124361396894, 3822.3502004659276, 91538.08461915363, 92075.0, 94978.99999999932, 95438.0, 95589.0, 10382.377057376581, 10416.479176041168, 10536.63121790749, 10550.47247637615, 10579.682609521727, 16580.0, 19274.42176734672, 20024.59950800976, 20184.49538761543, 20361.0, 7252.347288744038, 7256.8911466328045, 7451.478396512301, 7585.582792946441, 7603.505772124794, 3734.8982050295226, 4248.362745588125, 4254.723442976197, 4291.978540107342, 4324.524302326741, 3322.069820450278, 3364.2094107884627, 3386.508956201337, 3453.188500702333, 3456.3087382523595, 541.4342012596845, 580.6342004537115, 650.8080116365684, 687.3676217879563, 694.2259380790382, 550.3324925475815, 561.5732043646858, 642.399356601579, 661.3320546248324, 665.9134312539339, 7454.781652843031, 8516.233538981398, 9100.635974560944, 9320.72037838866, 9392.0, 516.9612279079009, 598.6497898729292, 665.0622622102812, 671.70445004198, 689.4105539763531, 4111.958880411157, 4466.486354069324, 4516.187086324493, 4562.292844609091, 4908.852734417973, 116320.0, 117825.8217417832, 121169.39415302867, 121949.0, 126579.03815290247, 68623.97064044043, 81491.0, 82007.7698834518, 85589.71615425774, 89550.10449895548, 56543.71728141333, 60467.69766151141, 84348.15651843403, 85016.99999999876, 86257.0, 9398.81202375935, 9630.0, 9697.806043879224, 9712.660056897974, 9731.41611503312, 54228.457715422344, 55680.443195567525, 55958.0, 57299.71350143389, 58379.540511487554, 75486.49027019538, 77868.0, 78348.60825696064, 78748.0, 78894.6055269743, 9966.152877005246, 12647.747045059232, 12698.365081745691, 12881.871181288067, 13358.198508089547, 13928.51250206238, 14244.801394595941, 14438.711225775636, 15042.275480221664, 15197.164155971528, 12727.681807954876, 13017.284049377375, 13178.670533236746, 13440.865591343962, 13459.932700336827, 12545.623631291075, 13232.735345292847, 13316.40076196554, 13451.46194152262, 13769.449222031006, 12321.01431885465, 12921.612351629467, 13008.349582520646, 13084.28036458004, 13427.328633568477, 10560.049595536439, 10730.356178629307, 10914.94211773147, 11197.944010280222, 11223.943880280547, 11376.601818936297, 11415.258008229439, 11669.474873630537, 11690.00634946005, 11697.356645384585], "value": 7206.299146520016, "unit": "score"}], "name": "dromaeo_css", "value": 7012.938001152098, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 114.0, 111.0, 111.0, 113.0, 112.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 138.0, 141.0, 143.0, 112.0, 110.0, 109.0, 109.0, 141.0, 112.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 182.0, 185.0, 185.0, 192.0, 264.0, 202.0, 192.0, 185.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 89.0, 60.0, 59.0, 58.0, 58.0, 82.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 95.0, 68.0, 68.0, 69.0, 89.0, 67.0, 70.0, 94.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 135.0, 125.0, 87.0, 84.0, 89.0, 86.0, 88.0, 86.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 100.0, 124.0, 122.0, 82.0, 82.0, 85.0, 91.0, 84.0, 65.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 58.0, 58.0, 57.0, 56.0, 57.0, 57.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 82.0, 79.0, 79.0, 102.0, 97.0, 72.0, 73.0, 73.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 85.0, 84.0, 82.0, 83.0, 96.0, 103.0, 101.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 144.0, 140.0, 139.0, 150.0, 157.0, 141.0, 141.0, 188.0, 140.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 165.0, 167.0, 174.0, 228.0, 195.0, 163.0, 165.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 67.0, 67.0, 66.0, 65.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1474.2000000000003, "alertThreshold": 2.0}]} 03:12:51 INFO - Return code: 0 03:12:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:12:51 INFO - # TBPL SUCCESS # 03:12:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:12:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:12:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:12:51 INFO - 2016-12-21 03:12:51.724 screenresolution[1655:9787] starting screenresolution argv=screenresolution get 03:12:51 INFO - 2016-12-21 03:12:51.727 screenresolution[1655:9787] Display 0: 1600x1200x32@60 03:12:51 INFO - 2016-12-21 03:12:51.733 screenresolution[1656:9790] starting screenresolution argv=screenresolution list 03:12:51 INFO - Available Modes on Display 0 03:12:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:12:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:12:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:12:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:12:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:12:51 INFO - Intel Iris: 03:12:51 INFO - Chipset Model: Intel Iris 03:12:51 INFO - Type: GPU 03:12:51 INFO - Bus: Built-In 03:12:51 INFO - VRAM (Dynamic, Max): 1536 MB 03:12:51 INFO - Vendor: Intel (0x8086) 03:12:51 INFO - Device ID: 0x0a2e 03:12:51 INFO - Revision ID: 0x0009 03:12:51 INFO - Displays: 03:12:51 INFO - Display: 03:12:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:12:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:12:51 INFO - Display Serial Number: 1600x1200 60 03:12:51 INFO - Main Display: Yes 03:12:51 INFO - Mirror: Off 03:12:51 INFO - Online: Yes 03:12:51 INFO - Rotation: Supported 03:12:51 INFO - Return code: 0 03:12:51 INFO - Running post-action listener: _resource_record_post_action 03:12:51 INFO - [mozharness: 2016-12-21 11:12:51.975441Z] Finished run-tests step (success) 03:12:51 INFO - Running post-run listener: _resource_record_post_run 03:12:52 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38736896; Write bytes: 659206656; Read time: 377; Write time: 1918 03:12:52 INFO - TinderboxPrint: CPU usage
53.5% 03:12:52 INFO - TinderboxPrint: I/O read bytes / time
38,736,896 / 377 03:12:52 INFO - TinderboxPrint: I/O write bytes / time
659,206,656 / 1,918 03:12:52 INFO - TinderboxPrint: CPU idle
2,258.0 (46.5%) 03:12:52 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 03:12:52 INFO - TinderboxPrint: CPU user
1,624.1 (33.5%) 03:12:52 INFO - TinderboxPrint: Swap in / out
387,211,264 / 0 03:12:52 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 344809984; Write bytes: 344880640; Read time: 26661; Write time: 648 03:12:52 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 36742144; Write bytes: 307391488; Read time: 356; Write time: 1242 03:12:52 INFO - Running post-run listener: _upload_blobber_files 03:12:52 INFO - Blob upload gear active. 03:12:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:12:52 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 03:12:52 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:12:52 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:12:52 INFO - (blobuploader) - INFO - Open directory for files ... 03:12:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:12:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:12:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:53 INFO - (blobuploader) - INFO - Done attempting. 03:12:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:12:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:12:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:54 INFO - (blobuploader) - INFO - Done attempting. 03:12:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:12:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:12:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:57 INFO - (blobuploader) - INFO - Done attempting. 03:12:57 INFO - (blobuploader) - INFO - Iteration through files over. 03:12:57 INFO - Return code: 0 03:12:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:12:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:12:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0e9c03727b07c2bfef408adcc04772a7ef0eac63bf1b7dd529227845a2bb452289f4c369e5a5ee7c3b47e6ef76bc82ccc7639e780fd7cd3d481de7d295e6f4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b8b1b62e6ddb405699ebc67f75d5f72c04b68cc804faf0b8371a3501482e37332fe34d7fd77fce3a51afb392b5dcd7cadba4fbe723a3e07ff90bf84ea937e66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/480af1c5c01492fd6431c6c10009b48852e050e6ac049db5bac70d724bbd365754ac7618414fc4bcd1be39735304c1c39bbea8e8b1419fb3789df159ba2aa11b"} 03:12:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:12:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:12:57 INFO - Contents: 03:12:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0e9c03727b07c2bfef408adcc04772a7ef0eac63bf1b7dd529227845a2bb452289f4c369e5a5ee7c3b47e6ef76bc82ccc7639e780fd7cd3d481de7d295e6f4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b8b1b62e6ddb405699ebc67f75d5f72c04b68cc804faf0b8371a3501482e37332fe34d7fd77fce3a51afb392b5dcd7cadba4fbe723a3e07ff90bf84ea937e66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/480af1c5c01492fd6431c6c10009b48852e050e6ac049db5bac70d724bbd365754ac7618414fc4bcd1be39735304c1c39bbea8e8b1419fb3789df159ba2aa11b"} 03:12:57 INFO - Running post-run listener: copy_logs_to_upload_dir 03:12:57 INFO - Copying logs to upload dir... 03:12:57 INFO - mkdir: /builds/slave/test/build/upload/logs 03:12:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.439570 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-21 03:12:57.328372) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 03:12:57.331453) ========= 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.hIXIezdXaR/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.QbtL6GBeWK/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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/Mozilla-Aurora/sha512/e0e9c03727b07c2bfef408adcc04772a7ef0eac63bf1b7dd529227845a2bb452289f4c369e5a5ee7c3b47e6ef76bc82ccc7639e780fd7cd3d481de7d295e6f4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b8b1b62e6ddb405699ebc67f75d5f72c04b68cc804faf0b8371a3501482e37332fe34d7fd77fce3a51afb392b5dcd7cadba4fbe723a3e07ff90bf84ea937e66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/480af1c5c01492fd6431c6c10009b48852e050e6ac049db5bac70d724bbd365754ac7618414fc4bcd1be39735304c1c39bbea8e8b1419fb3789df159ba2aa11b"} build_url:https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/aUtQCkyvQz2963Rk63R9dg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0e9c03727b07c2bfef408adcc04772a7ef0eac63bf1b7dd529227845a2bb452289f4c369e5a5ee7c3b47e6ef76bc82ccc7639e780fd7cd3d481de7d295e6f4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b8b1b62e6ddb405699ebc67f75d5f72c04b68cc804faf0b8371a3501482e37332fe34d7fd77fce3a51afb392b5dcd7cadba4fbe723a3e07ff90bf84ea937e66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/480af1c5c01492fd6431c6c10009b48852e050e6ac049db5bac70d724bbd365754ac7618414fc4bcd1be39735304c1c39bbea8e8b1419fb3789df159ba2aa11b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 03:12:57.369365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 03:12:57.369691) ========= 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.hIXIezdXaR/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.QbtL6GBeWK/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005311 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 03:12:57.418003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 03:12:57.421375) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 03:12:57.421708) ========= ========= Total master_lag: 8.81 =========