builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0254 starttime: 1468467073.51 results: success (0) buildid: 20160713192047 builduid: a435355ceca7427cb2b5bc1f9fbc8e2c revision: 7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.509710) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.510173) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.510457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.696547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.696943) ========= 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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-07-13 20:31:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2016-07-13 20:31:13 (19.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160367 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.925204) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.925523) ========= 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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.027218 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:13.989772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 20:31:13.990146) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8eJeDwgX9r/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.u4hSqjNOFw/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-07-13 20:31:14,071 truncating revision to first 12 chars 2016-07-13 20:31:14,071 Setting DEBUG logging. 2016-07-13 20:31:14,071 attempt 1/10 2016-07-13 20:31:14,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7c9ea2fa9a20?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 20:31:14,869 unpacking tar archive at: autoland-7c9ea2fa9a20/testing/mozharness/ program finished with exit code 0 elapsedTime=1.079374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 20:31:15.091139) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:15.091427) ========= script_repo_revision: 7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:15.091817) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:15.092105) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 20:31:15.110815) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 28 secs) (at 2016-07-13 20:31:15.111192) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8eJeDwgX9r/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.u4hSqjNOFw/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:31:15 INFO - MultiFileLogger online at 20160713 20:31:15 in /builds/slave/test 20:31:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:31:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:31:15 INFO - {'append_to_log': False, 20:31:15 INFO - 'base_work_dir': '/builds/slave/test', 20:31:15 INFO - 'blob_upload_branch': 'Autoland', 20:31:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:31:15 INFO - 'branch': 'Autoland', 20:31:15 INFO - 'buildbot_json_path': 'buildprops.json', 20:31:15 INFO - 'config_files': ('talos/mac_config.py',), 20:31:15 INFO - 'default_actions': ('clobber', 20:31:15 INFO - 'read-buildbot-config', 20:31:15 INFO - 'download-and-extract', 20:31:15 INFO - 'populate-webroot', 20:31:15 INFO - 'create-virtualenv', 20:31:15 INFO - 'install', 20:31:15 INFO - 'run-tests'), 20:31:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:31:15 INFO - 'download_minidump_stackwalk': True, 20:31:15 INFO - 'download_symbols': 'ondemand', 20:31:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:31:15 INFO - 'tooltool.py': '/tools/tooltool.py', 20:31:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:31:15 INFO - '/tools/misc-python/virtualenv.py')}, 20:31:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:31:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:31:15 INFO - 'installer_path': 'installer.exe', 20:31:15 INFO - 'log_level': 'info', 20:31:15 INFO - 'log_name': 'talos', 20:31:15 INFO - 'log_to_console': True, 20:31:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:31:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:31:15 INFO - 'opt_config_files': (), 20:31:15 INFO - 'pip_index': False, 20:31:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:31:15 INFO - 'cmd': ('bash', 20:31:15 INFO - '-c', 20:31:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:31:15 INFO - 'enabled': True, 20:31:15 INFO - 'halt_on_failure': False, 20:31:15 INFO - 'name': 'check_screen_resolution'},), 20:31:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:31:15 INFO - 'cmd': ('bash', 20:31:15 INFO - '-c', 20:31:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:31:15 INFO - 'enabled': True, 20:31:15 INFO - 'halt_on_failure': False, 20:31:15 INFO - 'name': 'check_screen_resolution'},), 20:31:15 INFO - 'run_cmd_checks_enabled': True, 20:31:15 INFO - 'sps_profile': False, 20:31:15 INFO - 'sps_profile_interval': 0, 20:31:15 INFO - 'suite': 'dromaeojs', 20:31:15 INFO - 'system_bits': '32', 20:31:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:31:15 INFO - 'title': 't-yosemite-r7-0254', 20:31:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:31:15 INFO - 'use_talos_json': True, 20:31:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:31:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:31:15 INFO - 'work_dir': 'build'} 20:31:15 INFO - ##### 20:31:15 INFO - ##### Running clobber step. 20:31:15 INFO - ##### 20:31:15 INFO - Running pre-action listener: _resource_record_pre_action 20:31:15 INFO - Running main action method: clobber 20:31:15 INFO - rmtree: /builds/slave/test/build 20:31:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:31:17 INFO - Running post-action listener: _resource_record_post_action 20:31:17 INFO - ##### 20:31:17 INFO - ##### Finished clobber step (success) 20:31:17 INFO - ##### 20:31:17 INFO - ##### 20:31:17 INFO - ##### Running read-buildbot-config step. 20:31:17 INFO - ##### 20:31:17 INFO - Running pre-action listener: _resource_record_pre_action 20:31:17 INFO - Running main action method: read_buildbot_config 20:31:17 INFO - Using buildbot properties: 20:31:17 INFO - { 20:31:17 INFO - "project": "", 20:31:17 INFO - "product": "firefox", 20:31:17 INFO - "script_repo_revision": "production", 20:31:17 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:31:17 INFO - "repository": "", 20:31:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:31:17 INFO - "buildid": "20160713192047", 20:31:17 INFO - "pgo_build": "False", 20:31:17 INFO - "basedir": "/builds/slave/test", 20:31:17 INFO - "buildnumber": 16, 20:31:17 INFO - "slavename": "t-yosemite-r7-0254", 20:31:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:31:17 INFO - "platform": "yosemite_r7", 20:31:17 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:31:17 INFO - "branch": "autoland", 20:31:17 INFO - "repo_path": "integration/autoland", 20:31:17 INFO - "revision": "7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea", 20:31:17 INFO - "stage_platform": "macosx64", 20:31:17 INFO - "builduid": "a435355ceca7427cb2b5bc1f9fbc8e2c", 20:31:17 INFO - "slavebuilddir": "test" 20:31:17 INFO - } 20:31:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 20:31:17 INFO - Running post-action listener: _resource_record_post_action 20:31:17 INFO - ##### 20:31:17 INFO - ##### Finished read-buildbot-config step (success) 20:31:17 INFO - ##### 20:31:17 INFO - ##### 20:31:17 INFO - ##### Running download-and-extract step. 20:31:17 INFO - ##### 20:31:17 INFO - Running pre-action listener: _resource_record_pre_action 20:31:17 INFO - Running main action method: download_and_extract 20:31:17 INFO - mkdir: /builds/slave/test/build/tests 20:31:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:31:17 INFO - https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 20:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 20:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 20:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 20:31:47 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 20:31:47 INFO - retry: attempt #1 caught exception: timed out 20:31:47 INFO - retry: Failed, sleeping 30 seconds before retrying 20:32:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #2 20:32:31 INFO - Downloaded 1199 bytes. 20:32:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 20:32:31 INFO - Using the following test package requirements: 20:32:31 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:32:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 20:32:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 20:32:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'jsshell-mac.zip'], 20:32:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 20:32:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:32:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 20:32:31 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 20:32:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 20:32:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:32:31 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 20:32:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:32:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:32:31 INFO - https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:32:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:32:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:32:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:32:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:32:32 INFO - Downloaded 18827601 bytes. 20:32:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:32:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:32:35 INFO - Return code: 0 20:32:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:32:35 INFO - https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:32:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:32:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:32:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:32:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:32:42 INFO - Downloaded 18827601 bytes. 20:32:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:32:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:32:45 INFO - Return code: 0 20:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:32:45 INFO - https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:32:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:32:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:32:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:32:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:32:46 INFO - Downloaded 14403317 bytes. 20:32:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:32:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 20:32:47 INFO - Return code: 0 20:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:32:47 INFO - https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:32:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:32:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:32:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 20:32:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 20:32:56 INFO - Downloaded 112779595 bytes. 20:32:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:32:56 INFO - mkdir: /builds/slave/test/properties 20:32:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:32:56 INFO - Writing to file /builds/slave/test/properties/build_url 20:32:56 INFO - Contents: 20:32:56 INFO - build_url:https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:32:57 INFO - Running post-action listener: _resource_record_post_action 20:32:57 INFO - Running post-action listener: set_extra_try_arguments 20:32:57 INFO - ##### 20:32:57 INFO - ##### Finished download-and-extract step (success) 20:32:57 INFO - ##### 20:32:57 INFO - ##### 20:32:57 INFO - ##### Running populate-webroot step. 20:32:57 INFO - ##### 20:32:57 INFO - Running pre-action listener: _resource_record_pre_action 20:32:57 INFO - Running main action method: populate_webroot 20:32:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:32:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:32:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:32:57 INFO - '--tppagecycles', 20:32:57 INFO - '7'], 20:32:57 INFO - 'tests': ['tsvgm']}}, 20:32:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:32:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:32:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:32:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:32:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:32:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:32:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['damp', 'tps']}, 20:32:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'tests': ['damp', 'tps']}, 20:32:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['dromaeo_dom']}, 20:32:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:32:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['basic_compositor_video']}, 20:32:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:32:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['a11yr', 20:32:57 INFO - 'ts_paint', 20:32:57 INFO - 'tpaint', 20:32:57 INFO - 'sessionrestore', 20:32:57 INFO - 'sessionrestore_no_auto_restore', 20:32:57 INFO - 'tabpaint']}, 20:32:57 INFO - 'other-e10s': {'tests': ['a11yr', 20:32:57 INFO - 'ts_paint', 20:32:57 INFO - 'tpaint', 20:32:57 INFO - 'sessionrestore', 20:32:57 INFO - 'sessionrestore_no_auto_restore', 20:32:57 INFO - 'tabpaint']}, 20:32:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:32:57 INFO - 'ts_paint', 20:32:57 INFO - 'tpaint', 20:32:57 INFO - 'sessionrestore', 20:32:57 INFO - 'sessionrestore_no_auto_restore', 20:32:57 INFO - 'tabpaint']}, 20:32:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:32:57 INFO - 'ts_paint', 20:32:57 INFO - 'tpaint', 20:32:57 INFO - 'sessionrestore', 20:32:57 INFO - 'sessionrestore_no_auto_restore', 20:32:57 INFO - 'tabpaint']}, 20:32:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['a11yr', 20:32:57 INFO - 'ts_paint', 20:32:57 INFO - 'tpaint', 20:32:57 INFO - 'sessionrestore', 20:32:57 INFO - 'sessionrestore_no_auto_restore', 20:32:57 INFO - 'tabpaint']}, 20:32:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['a11yr', 20:32:57 INFO - 'ts_paint', 20:32:57 INFO - 'tpaint', 20:32:57 INFO - 'sessionrestore', 20:32:57 INFO - 'sessionrestore_no_auto_restore', 20:32:57 INFO - 'tabpaint']}, 20:32:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['tsvgx', 20:32:57 INFO - 'tsvgr_opacity', 20:32:57 INFO - 'tart', 20:32:57 INFO - 'tscrollx', 20:32:57 INFO - 'cart']}, 20:32:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:32:57 INFO - 'tsvgr_opacity', 20:32:57 INFO - 'tart', 20:32:57 INFO - 'tscrollx', 20:32:57 INFO - 'cart']}, 20:32:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'talos_options': ['--disable-e10s'], 20:32:57 INFO - 'tests': ['tp5o']}, 20:32:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'tests': ['tp5o']}, 20:32:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'talos_options': ['--disable-e10s', 20:32:57 INFO - '--xperf_path', 20:32:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:32:57 INFO - 'tests': ['tp5n']}, 20:32:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:32:57 INFO - 'talos_options': ['--xperf_path', 20:32:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:32:57 INFO - 'tests': ['tp5n']}}, 20:32:57 INFO - 'talos.zip': {'path': '', 20:32:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:32:57 INFO - Running post-action listener: _resource_record_post_action 20:32:57 INFO - ##### 20:32:57 INFO - ##### Finished populate-webroot step (success) 20:32:57 INFO - ##### 20:32:57 INFO - ##### 20:32:57 INFO - ##### Running create-virtualenv step. 20:32:57 INFO - ##### 20:32:57 INFO - Running pre-action listener: _resource_record_pre_action 20:32:57 INFO - Running main action method: create_virtualenv 20:32:57 INFO - Creating virtualenv /builds/slave/test/build/venv 20:32:57 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 20:32:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:32:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:32:57 INFO - Using real prefix '/tools/python27' 20:32:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:32:58 INFO - Installing distribute.............................................................................................................................................................................................done. 20:33:01 INFO - Installing pip.................done. 20:33:01 INFO - Return code: 0 20:33:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:01 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:01 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 20:33:01 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 20:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:01 INFO - 'HOME': '/Users/cltbld', 20:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:01 INFO - 'LOGNAME': 'cltbld', 20:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:01 INFO - 'MOZ_NO_REMOTE': '1', 20:33:01 INFO - 'NO_EM_RESTART': '1', 20:33:01 INFO - 'PAGER': '/bin/cat', 20:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:01 INFO - 'PWD': '/builds/slave/test', 20:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:01 INFO - 'SHELL': '/bin/bash', 20:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:01 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:01 INFO - 'USER': 'cltbld', 20:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:01 INFO - 'XPC_FLAGS': '0x0', 20:33:01 INFO - 'XPC_SERVICE_NAME': '0', 20:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:01 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 20:33:01 INFO - Cleaning up... 20:33:01 INFO - Return code: 0 20:33:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:01 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:01 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 20:33:01 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 20:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:01 INFO - 'HOME': '/Users/cltbld', 20:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:01 INFO - 'LOGNAME': 'cltbld', 20:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:01 INFO - 'MOZ_NO_REMOTE': '1', 20:33:01 INFO - 'NO_EM_RESTART': '1', 20:33:01 INFO - 'PAGER': '/bin/cat', 20:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:01 INFO - 'PWD': '/builds/slave/test', 20:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:01 INFO - 'SHELL': '/bin/bash', 20:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:01 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:01 INFO - 'USER': 'cltbld', 20:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:01 INFO - 'XPC_FLAGS': '0x0', 20:33:01 INFO - 'XPC_SERVICE_NAME': '0', 20:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:01 INFO - Downloading/unpacking psutil>=3.1.1 20:33:01 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 20:33:01 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 20:33:01 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 20:33:01 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 20:33:01 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 20:33:01 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 20:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:33:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:33:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:33:05 INFO - Installing collected packages: psutil 20:33:05 INFO - Running setup.py install for psutil 20:33:05 INFO - building 'psutil._psutil_osx' extension 20:33:05 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 20:33:05 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 20:33:06 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 20:33:06 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 20:33:06 INFO - building 'psutil._psutil_posix' extension 20:33:06 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 20:33:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:33:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:33:06 INFO - ^ 20:33:06 INFO - 1 warning generated. 20:33:06 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 20:33:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:33:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:33:06 INFO - Successfully installed psutil 20:33:06 INFO - Cleaning up... 20:33:06 INFO - Return code: 0 20:33:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:06 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:06 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 20:33:06 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 20:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:06 INFO - 'HOME': '/Users/cltbld', 20:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:06 INFO - 'LOGNAME': 'cltbld', 20:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:06 INFO - 'MOZ_NO_REMOTE': '1', 20:33:06 INFO - 'NO_EM_RESTART': '1', 20:33:06 INFO - 'PAGER': '/bin/cat', 20:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:06 INFO - 'PWD': '/builds/slave/test', 20:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:06 INFO - 'SHELL': '/bin/bash', 20:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:06 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:06 INFO - 'USER': 'cltbld', 20:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:06 INFO - 'XPC_FLAGS': '0x0', 20:33:06 INFO - 'XPC_SERVICE_NAME': '0', 20:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:33:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:33:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:33:09 INFO - Installing collected packages: mozsystemmonitor 20:33:09 INFO - Running setup.py install for mozsystemmonitor 20:33:09 INFO - Successfully installed mozsystemmonitor 20:33:09 INFO - Cleaning up... 20:33:09 INFO - Return code: 0 20:33:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:09 INFO - 'HOME': '/Users/cltbld', 20:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:09 INFO - 'LOGNAME': 'cltbld', 20:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:09 INFO - 'MOZ_NO_REMOTE': '1', 20:33:09 INFO - 'NO_EM_RESTART': '1', 20:33:09 INFO - 'PAGER': '/bin/cat', 20:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:09 INFO - 'PWD': '/builds/slave/test', 20:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:09 INFO - 'SHELL': '/bin/bash', 20:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:09 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:09 INFO - 'USER': 'cltbld', 20:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:09 INFO - 'XPC_FLAGS': '0x0', 20:33:09 INFO - 'XPC_SERVICE_NAME': '0', 20:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:09 INFO - Downloading/unpacking jsonschema==2.5.1 20:33:09 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 20:33:09 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 20:33:09 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 20:33:09 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 20:33:09 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 20:33:09 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 20:33:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:33:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:33:12 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 20:33:12 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 20:33:12 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 20:33:12 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 20:33:12 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 20:33:12 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 20:33:12 INFO - Downloading functools32-3.2.3-2.tar.gz 20:33:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:33:12 INFO - warning: no files found matching '*.txt' 20:33:12 INFO - no previously-included directories found matching 'build' 20:33:12 INFO - no previously-included directories found matching 'dist' 20:33:12 INFO - no previously-included directories found matching '.git*' 20:33:12 INFO - Installing collected packages: jsonschema, functools32 20:33:12 INFO - Running setup.py install for functools32 20:33:12 INFO - warning: no files found matching '*.txt' 20:33:12 INFO - no previously-included directories found matching 'build' 20:33:12 INFO - no previously-included directories found matching 'dist' 20:33:12 INFO - no previously-included directories found matching '.git*' 20:33:12 INFO - Successfully installed jsonschema functools32 20:33:12 INFO - Cleaning up... 20:33:12 INFO - Return code: 0 20:33:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:12 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:12 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 20:33:12 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 20:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:12 INFO - 'HOME': '/Users/cltbld', 20:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:12 INFO - 'LOGNAME': 'cltbld', 20:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:12 INFO - 'MOZ_NO_REMOTE': '1', 20:33:12 INFO - 'NO_EM_RESTART': '1', 20:33:12 INFO - 'PAGER': '/bin/cat', 20:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:12 INFO - 'PWD': '/builds/slave/test', 20:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:12 INFO - 'SHELL': '/bin/bash', 20:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:12 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:12 INFO - 'USER': 'cltbld', 20:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:12 INFO - 'XPC_FLAGS': '0x0', 20:33:12 INFO - 'XPC_SERVICE_NAME': '0', 20:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:13 INFO - Downloading/unpacking blobuploader==1.2.4 20:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:15 INFO - Downloading blobuploader-1.2.4.tar.gz 20:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:33:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:33: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 20:33: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 20:33: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 20:33: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 20:33: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 20:33: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 20:33:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:33:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:16 INFO - Downloading docopt-0.6.1.tar.gz 20:33:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:33:16 INFO - Installing collected packages: blobuploader, requests, docopt 20:33:16 INFO - Running setup.py install for blobuploader 20:33:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:33:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:33:17 INFO - Running setup.py install for requests 20:33:17 INFO - Running setup.py install for docopt 20:33:17 INFO - Successfully installed blobuploader requests docopt 20:33:17 INFO - Cleaning up... 20:33:17 INFO - Return code: 0 20:33:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:33:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:17 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:17 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 20:33:17 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 20:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:17 INFO - 'HOME': '/Users/cltbld', 20:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:17 INFO - 'LOGNAME': 'cltbld', 20:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:17 INFO - 'MOZ_NO_REMOTE': '1', 20:33:17 INFO - 'NO_EM_RESTART': '1', 20:33:17 INFO - 'PAGER': '/bin/cat', 20:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:17 INFO - 'PWD': '/builds/slave/test', 20:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:17 INFO - 'SHELL': '/bin/bash', 20:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:17 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:17 INFO - 'USER': 'cltbld', 20:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:17 INFO - 'XPC_FLAGS': '0x0', 20:33:17 INFO - 'XPC_SERVICE_NAME': '0', 20:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-HOY93M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9kv2Ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-hCxUpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3hP2Ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-6jaZJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-RxV79_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-67BCe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-X7fmZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:33:18 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-risn9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:33:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-nGTDFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-P6njfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-V6H6Ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-uc0djl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-2VBi5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-lqXXYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Bx25L3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:33:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:33:19 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-sseB_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:33:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:33:19 INFO - Running setup.py install for manifestparser 20:33:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:33:20 INFO - Running setup.py install for mozcrash 20:33:20 INFO - Running setup.py install for mozdebug 20:33:20 INFO - Running setup.py install for mozdevice 20:33:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:33:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:33:20 INFO - Running setup.py install for mozfile 20:33:20 INFO - Running setup.py install for mozhttpd 20:33:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:33:20 INFO - Running setup.py install for mozinfo 20:33:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:33:20 INFO - Running setup.py install for mozInstall 20:33:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Running setup.py install for mozleak 20:33:21 INFO - Running setup.py install for mozlog 20:33:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Running setup.py install for moznetwork 20:33:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Running setup.py install for mozprocess 20:33:21 INFO - Running setup.py install for mozprofile 20:33:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:33:21 INFO - Running setup.py install for mozrunner 20:33:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:33:22 INFO - Running setup.py install for mozscreenshot 20:33:22 INFO - Running setup.py install for moztest 20:33:22 INFO - Running setup.py install for mozversion 20:33:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:33:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:33:22 INFO - Cleaning up... 20:33:22 INFO - Return code: 0 20:33:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:22 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:22 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 20:33:22 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 20:33:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:22 INFO - 'HOME': '/Users/cltbld', 20:33:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:22 INFO - 'LOGNAME': 'cltbld', 20:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:22 INFO - 'MOZ_NO_REMOTE': '1', 20:33:22 INFO - 'NO_EM_RESTART': '1', 20:33:22 INFO - 'PAGER': '/bin/cat', 20:33:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:22 INFO - 'PWD': '/builds/slave/test', 20:33:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:22 INFO - 'SHELL': '/bin/bash', 20:33:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:22 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:22 INFO - 'USER': 'cltbld', 20:33:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:22 INFO - 'XPC_FLAGS': '0x0', 20:33:22 INFO - 'XPC_SERVICE_NAME': '0', 20:33:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:33:22 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-NNjumO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:33:22 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)) 20:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:33:22 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-y9QR2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:33:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:33:22 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-or2oDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-0EXz5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-2_L47k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-5bf0eL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-JlfldA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-XTINHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-E36oNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-BgbkiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-qrNYN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:33:23 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)) 20:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:33:23 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-AdqpA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:33:24 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)) 20:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:33:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-9YPx1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:33:24 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)) 20:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:33:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-xpCS_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-sXPH8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:33:24 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)) 20:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:33:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-pw2due-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:33:24 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)) 20:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:33:24 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-fJOGZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:33:24 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)) 20:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:33:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:33:24 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)) 20:33:24 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)) 20:33:24 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)) 20:33:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:33:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:33:26 INFO - Downloading blessings-1.6.tar.gz 20:33:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:33:27 INFO - Installing collected packages: blessings 20:33:27 INFO - Running setup.py install for blessings 20:33:27 INFO - Successfully installed blessings 20:33:27 INFO - Cleaning up... 20:33:27 INFO - Return code: 0 20:33:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:33:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:33:27 INFO - Reading from file tmpfile_stdout 20:33:27 INFO - Current package versions: 20:33:27 INFO - blessings == 1.6 20:33:27 INFO - blobuploader == 1.2.4 20:33:27 INFO - docopt == 0.6.1 20:33:27 INFO - functools32 == 3.2.3-2 20:33:27 INFO - jsonschema == 2.5.1 20:33:27 INFO - manifestparser == 1.1 20:33:27 INFO - mozInstall == 1.12 20:33:27 INFO - mozcrash == 0.17 20:33:27 INFO - mozdebug == 0.1 20:33:27 INFO - mozdevice == 0.48 20:33:27 INFO - mozfile == 1.2 20:33:27 INFO - mozhttpd == 0.7 20:33:27 INFO - mozinfo == 0.9 20:33:27 INFO - mozleak == 0.1 20:33:27 INFO - mozlog == 3.2 20:33:27 INFO - moznetwork == 0.27 20:33:27 INFO - mozprocess == 0.23 20:33:27 INFO - mozprofile == 0.28 20:33:27 INFO - mozrunner == 6.12 20:33:27 INFO - mozscreenshot == 0.1 20:33:27 INFO - mozsystemmonitor == 0.3 20:33:27 INFO - moztest == 0.7 20:33:27 INFO - mozversion == 1.4 20:33:27 INFO - psutil == 3.1.1 20:33:27 INFO - requests == 1.2.3 20:33:27 INFO - wsgiref == 0.1.2 20:33:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:27 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:27 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 20:33:27 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 20:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:27 INFO - 'HOME': '/Users/cltbld', 20:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:27 INFO - 'LOGNAME': 'cltbld', 20:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:27 INFO - 'MOZ_NO_REMOTE': '1', 20:33:27 INFO - 'NO_EM_RESTART': '1', 20:33:27 INFO - 'PAGER': '/bin/cat', 20:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:27 INFO - 'PWD': '/builds/slave/test', 20:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:27 INFO - 'SHELL': '/bin/bash', 20:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:27 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:27 INFO - 'USER': 'cltbld', 20:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:27 INFO - 'XPC_FLAGS': '0x0', 20:33:27 INFO - 'XPC_SERVICE_NAME': '0', 20:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 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)) 20:33:27 INFO - Cleaning up... 20:33:27 INFO - Return code: 0 20:33:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:33:27 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1a8c6dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 20:33:27 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 20:33:27 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 20:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:27 INFO - 'HOME': '/Users/cltbld', 20:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:27 INFO - 'LOGNAME': 'cltbld', 20:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:27 INFO - 'MOZ_NO_REMOTE': '1', 20:33:27 INFO - 'NO_EM_RESTART': '1', 20:33:27 INFO - 'PAGER': '/bin/cat', 20:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:27 INFO - 'PWD': '/builds/slave/test', 20:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:27 INFO - 'SHELL': '/bin/bash', 20:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:33:27 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:27 INFO - 'USER': 'cltbld', 20:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:27 INFO - 'XPC_FLAGS': '0x0', 20:33:27 INFO - 'XPC_SERVICE_NAME': '0', 20:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:33:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:33:28 INFO - Cleaning up... 20:33:28 INFO - Return code: 0 20:33:28 INFO - Running post-action listener: _resource_record_post_action 20:33:28 INFO - Running post-action listener: _start_resource_monitoring 20:33:28 INFO - Starting resource monitoring. 20:33:28 INFO - ##### 20:33:28 INFO - ##### Finished create-virtualenv step (success) 20:33:28 INFO - ##### 20:33:28 INFO - ##### 20:33:28 INFO - ##### Running install step. 20:33:28 INFO - ##### 20:33:28 INFO - Running pre-action listener: _resource_record_pre_action 20:33:28 INFO - Running main action method: install 20:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:33:28 INFO - Reading from file tmpfile_stdout 20:33:28 INFO - Detecting whether we're running mozinstall >=1.0... 20:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:33:28 INFO - Reading from file tmpfile_stdout 20:33:28 INFO - Output received: 20:33:28 INFO - Usage: mozinstall [options] installer 20:33:28 INFO - Options: 20:33:28 INFO - -h, --help show this help message and exit 20:33:28 INFO - -d DEST, --destination=DEST 20:33:28 INFO - Directory to install application into. [default: 20:33:28 INFO - "/builds/slave/test"] 20:33:28 INFO - --app=APP Application being installed. [default: firefox] 20:33:28 INFO - mkdir: /builds/slave/test/build/application 20:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:34:02 INFO - Reading from file tmpfile_stdout 20:34:02 INFO - Output received: 20:34:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:34:02 INFO - Running post-action listener: _resource_record_post_action 20:34:02 INFO - ##### 20:34:02 INFO - ##### Finished install step (success) 20:34:02 INFO - ##### 20:34:02 INFO - ##### 20:34:02 INFO - ##### Running run-tests step. 20:34:02 INFO - ##### 20:34:02 INFO - Running pre-action listener: _resource_record_pre_action 20:34:02 INFO - Running main action method: run_tests 20:34:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:34:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:34:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:34:02 INFO - 2016-07-13 20:34:02.973 screenresolution[1643:7575] starting screenresolution argv=screenresolution get 20:34:02 INFO - 2016-07-13 20:34:02.976 screenresolution[1643:7575] Display 0: 1600x1200x32@60 20:34:02 INFO - 2016-07-13 20:34:02.982 screenresolution[1644:7577] starting screenresolution argv=screenresolution list 20:34:02 INFO - Available Modes on Display 0 20:34:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:34:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:34:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:34:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:34:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:34:03 INFO - Intel Iris: 20:34:03 INFO - Chipset Model: Intel Iris 20:34:03 INFO - Type: GPU 20:34:03 INFO - Bus: Built-In 20:34:03 INFO - VRAM (Dynamic, Max): 1536 MB 20:34:03 INFO - Vendor: Intel (0x8086) 20:34:03 INFO - Device ID: 0x0a2e 20:34:03 INFO - Revision ID: 0x0009 20:34:03 INFO - Displays: 20:34:03 INFO - Display: 20:34:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:34:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:34:03 INFO - Display Serial Number: 1600x1200 60 20:34:03 INFO - Main Display: Yes 20:34:03 INFO - Mirror: Off 20:34:03 INFO - Online: Yes 20:34:03 INFO - Rotation: Supported 20:34:03 INFO - Return code: 0 20:34:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:34:03 INFO - Python 2.7.3 20:34:03 INFO - Return code: 0 20:34:03 INFO - grabbing minidump binary from tooltool 20:34:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:34:03 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 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:34:03 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 20:34:03 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 20:34:03 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 20:34:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:34:03 INFO - Return code: 0 20:34:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:34:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:34:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:34:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:34:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:34:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 20:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --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 20:34:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8eJeDwgX9r/Render', 20:34:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:34:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:34:03 INFO - 'HOME': '/Users/cltbld', 20:34:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:34:03 INFO - 'LOGNAME': 'cltbld', 20:34:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:34:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:34:03 INFO - 'MOZ_NO_REMOTE': '1', 20:34:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:34:03 INFO - 'NO_EM_RESTART': '1', 20:34:03 INFO - 'PAGER': '/bin/cat', 20:34:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:34:03 INFO - 'PWD': '/builds/slave/test', 20:34:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:34:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:34:03 INFO - 'SHELL': '/bin/bash', 20:34:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u4hSqjNOFw/Listeners', 20:34:03 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 20:34:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:34:03 INFO - 'USER': 'cltbld', 20:34:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:34:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:34:03 INFO - 'XPC_FLAGS': '0x0', 20:34:03 INFO - 'XPC_SERVICE_NAME': '0', 20:34:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:34:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 20:34:03 INFO - mozversion application_buildid: 20160713192047 20:34:03 INFO - mozversion application_changeset: 7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea 20:34:03 INFO - mozversion application_display_name: Nightly 20:34:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:34:03 INFO - mozversion application_name: Firefox 20:34:03 INFO - mozversion application_remotingname: firefox 20:34:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:34:03 INFO - mozversion application_vendor: Mozilla 20:34:03 INFO - mozversion application_version: 50.0a1 20:34:03 INFO - mozversion platform_buildid: 20160713192047 20:34:03 INFO - mozversion platform_changeset: 7c9ea2fa9a20add444c0474508c5e6b0a4bac4ea 20:34:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:34:03 INFO - mozversion platform_version: 50.0a1 20:34:03 INFO - using testdate: 1468467243 20:34:03 INFO - actual date: 1468467243 20:34:03 INFO - starting webserver on 'localhost:49268' 20:34:03 INFO - SUITE-START | Running 2 tests 20:34:03 INFO - TEST-START | dromaeo_css 20:34:03 INFO - operating with platform_type : mac_ 20:34:03 INFO - Initialising browser for dromaeo_css test... 20:34:03 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmp9ngaGS/profile http://localhost:49268/getInfo.html) 20:34:05 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 20:34:05 INFO - PROCESS | 1654 | colorDepth:24 20:34:05 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 20:34:05 INFO - PROCESS | 1654 | __metrics 20:34:06 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:34:06 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:34:06 INFO - TEST-INFO | 1654: exit 0 20:34:06 INFO - Browser initialized. 20:34:06 INFO - Running cycle 1/1 for dromaeo_css test... 20:34:06 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmp9ngaGS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:34:18 INFO - PROCESS | 1656 | RSS: Main: 243671040 20:34:18 INFO - PROCESS | 1656 | 20:35:09 INFO - PROCESS | 1656 | 1468467309886 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] 20:37:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 20:37:21 INFO - PROCESS | 1656 | RSS: Main: 337616896 20:37:21 INFO - PROCESS | 1656 | 20:40:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 20:40:25 INFO - PROCESS | 1656 | RSS: Main: 381382656 20:40:25 INFO - PROCESS | 1656 | 20:43:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 20:43:28 INFO - PROCESS | 1656 | RSS: Main: 372121600 20:43:28 INFO - PROCESS | 1656 | 20:46:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 20:46:30 INFO - PROCESS | 1656 | RSS: Main: 378265600 20:46:30 INFO - PROCESS | 1656 | 20:49:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 20:49:34 INFO - PROCESS | 1656 | RSS: Main: 337563648 20:49:34 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 20:52:37 INFO - PROCESS | 1656 | RSS: Main: 404369408 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | __start_tp_report 20:52:37 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 20:52:37 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 20:52:37 INFO - PROCESS | 1656 | |i|pagename|runs| 20:52:37 INFO - PROCESS | 1656 | |0;dojo.html;3065.5708200851886;3329.9167520811984;3333.7333013358934;3361.983190084049;3398.524206611075;5522.972385138079;5591.049521581331;5616.719164041802;5628.718564071795;5676.659400435971;5611.382747897728;5640.661560306385;5718.771249150029;5724.055530837407;5736.684482353469;5061.519155680205;5074.137396642561;5141.614378921578;5152.845414637567;5222.92165617516;5198.948010519903;5226.320578324812;5244.947550524503;5255.185446255837;5292.523672869441;5061.089003979282;5077.593792496591;5106.744662766884;5125.2312153176945;5153.510416510426;4284.443022407098;5111.182210846247;5143.742812859342;5184.248283998799;5211.869703257411;4276.615104640598;5013.949860501385;5052.317937078484;5118.334616499831;5152.355955505562;5598.972005139989;5602.299712535933;5664.971675141639;5699.202111704323;5700.743466544015;5882.55880808941;5984.790532331347;6057.848553786191;6102.91701108444;6130.092410195535;5226.73866306687;6177.289611694668;6207.751689932443;6232.283287421959;6246.094316324153;5700.258966334433;5704.973104841086;5752.28096487939;5753.395893431213;5760.913586296209;6133.723982420802;6233.96883015582;6312.842178945563;6337.651429171347;6340.968295158587;6018.067199584071;6030.758769649253;6041.881904156405;6077;6083.787067452707;6133.693315334215;6222.253329600477;6229.221347331583;6230.906536401958;6236.968815155895;5964.761409543569;6011.579189456784;6050.939490605126;6110.938890611126;6125.509959203253;6125.356837532084;6132.973935358129;6175;6176.845578860564;6192.81421557354;4873.780679869415;4876.049170411736;4883.096627123993;4894.706317620954;4902.803887844518;3740.532433445819;4191.832326706897;4247.936280955788;4301.634361079281;4320.783960801948;5677.517411020027;5678;5683.9715801420725;5688.402717714663;5748.45389687979;6054.878902421927;6211.16149319836;6235.127082208494;6239.563230573816;6240.188775459252;5576.163575463632;5608.074667679869;5622.128570071645;5639.802606908738;5645.971770141123;5274.736263186902;5352.223927530486;5359.08895487768;5359.866003349947;5370.113931201387;6332.905006424908;6344.714487848058;6344.9048264277;6353.4599559038;6355.078513615592;5914.260717410324;6190.7833225838685;6191.102290167991;6197;6200.131981522501;6198.6280823150755;6266.874662506633;6319.24169099711;6320.525960553069;6329.651869147241;6018.488428483628;6055.969720151371;6074.271087469532;6092.515608078715;6123.938760612515;6032.487238584594;6126.7356173883045;6151.231096112986;6201.472874805692;6204.038374052029;5880.647161170344;5933.851653708692;5940.346561878104;5996.610220335664;6040.395960403925;5967.910481342697;5988.5508586855285;5995.7901473449965;5996.2504686914135;6008.579399441997;5685.090385538294;5841.94158058414;5843.824685259449;5845.444682755132;5858.882822343615;5779.682117483579;5888.941110588839;5904.61619994699;5936.465718085393;5952.5833191676165;5752.568557358131;5804.303483581997;5811.506021988178;5816.563757718103;5820.76716931318 20:52:37 INFO - PROCESS | 1656 | |1;ext.html;488.4626910398562;490.3331469201887;499.0069761175262;515.9664621799583;532.9920051199231;29614.703852961466;31131.377372452538;31149.68850311496;31174.844125779367;40178.7991060045;41102;43877;43941;43983.340249896195;44048.339274910824;9532.809343813136;9720.222382209442;9746.90253097467;9762.658306959258;9769.706908792748;8747.781305467382;8897.33270004749;8902.020777714446;9616.038396160398;9734.805303893918;8894.866577001318;8997.280217582611;11733.237339572943;11929.940350298237;11968.640940771747;11985;12025.699357516045;12026.4588093536;12045;12058.27650340978;8640.956795216047;11755.412229388581;11823.94088029563;11829.645110646694;11833.940830295793;39761.40357894634;40097.99755006119;40144;42613.57386426127;42691.78654106709;3517.243792584581;3654.506641603376;3840.6834929901142;3897.376419772851;4182.100848317596;31449.21376965571;32681;32893;32928.506072408934;33009.50485742716;3334.4664853623544;3351.463765797485;3413.3343997920415;3414.197663549064;3456.4124098902994;361538;361683;362265;362284;365703.17148414085;50623;60303.69848150819;60605;60654.39345606488;60757.78484430286;63412.682936585945;63730.044049339296;63969;63987.680061600324;63999.68000159969;16651.16744162787;16831.158442077845;16867.32530698783;16884.493465195817;16956.406525771537;13123.212607243597;13192.20846749198;13210.537631182862;13238.53665121716;13242.20546767197;14453.205073720836;14714.33785479656;15765.211739412984;16456.177191140396;16504.83495165033;7067.363937245673;7254.854902902018;7279.563226206445;7306.671199796022;7332.460183361447;950.3157726436955;1028.4035259549444;1123.9887601124049;1164.5691094294996;1209.3771707570609;26869.462610748065;29848.701512985026;31425.643076769335;31617.8419107903;31867.362652746815;1018.1803648063232;1057.9682609521726;1059.3855563772993;1136.8006752875795;1150.3443037468562;15353.309101090477;15434.536963891102;15487.915845890679;15522.146281954376;15871.841281587036;631373;634459;635601;640500;642536;58560.4143958555;58655.70672146612;58733.11900321498;58814.705926470095;58921;1435.8635929586674;1442.8557144285487;1468.4933697874003;1472.698096890135;1475;29135.980240691475;29816.552751709176;29909.70090299069;30142;30161.396772064876;51225;51938.74030629823;51990.22014669707;52126.21810672767;52167.47832521778;534156;553098;553140.702889449;555213;555349;23255.76744232536;23866.925988330913;25958.57227852486;26017.089401871603;26427;44784.43254486077;46979.76510117428;47271;48277;48344;35233.64766352406;37801.62198378091;38593.421098684106;38957.41563876601;39483.802580986914;37053.44419833759;39349;39786.60213397829;39893;40032.79901602957;33460;36891.44662830006;37643.81178094092;37742.62257377501;37893.81053094717;28019.57970630483;32568.185795355304;33041.50437743388;34519;34654.82672586621;21287.467813304793;23537.764622354243;24880.50238995246;25123.874380627978;25588.84850181709 20:52:37 INFO - PROCESS | 1656 | |2;jquery.html;361.7413549312241;381.8720728555934;390.8202226975592;395.2253582977364;416.1302876987098;15596.759425136348;17771.55571110723;21628.675569866424;21646.783532164693;21719.805410702404;20834.479138021557;21530.13879444824;21549.784502154973;21566.568668626616;21611.567768644618;5797.855053623672;5906.320773111104;6100.725467353957;6106.236720409949;6135.478484328837;5988.670623115727;5991.281046274453;6061.909071363911;6062.18160548327;6066.302375226839;5816.127580862883;6012.3687012863675;6152.938470615281;6165.599236049665;6186.907196392058;5814.738336774813;6108.114323423101;6152.723127459252;6171.87656246877;6181.629102253879;5982.581219314649;6000.879982400371;6029.095635654643;6029.577929544934;6035.879282414371;13563.389647465889;13601.795973060413;13637.386317615732;13858.237796921165;13954.581362559038;2011.2658879508874;2179.564087182554;2193.4187440328324;2587.728288529696;2760.5169095408223;10376.740581485448;10750.462476876124;10804.5678172873;12700.80948785769;15172.468963586221;1871.747314112591;1955.8533110016806;1988.0457380457317;2001.0395010394946;2080.147139672727;71375.28624713738;72589.99999999948;74190.25809741887;127910;134014.6598534012;10412.531436085394;11522.711932201762;11645.536791916466;11726.94136529312;11755.764884702257;14406.351714172888;14502.274886255645;14531.273436328142;14569.854301457062;14589.489367872286;8151.79620509492;8609.655613775503;8631.180037896514;8637.740867773977;8754.91245087541;8738.213560779433;8746.081661425458;8761.693340733043;8862.822743545223;8876.600552975131;3710.6289371063217;4483.663725220622;4554.83851617842;4625.583697467244;4704.623630109583;1893.176468236294;1927.180948097053;1931.111688623221;1931.4012656076661;1931.4688460673426;770.3221165374434;786.8244304498068;896.7758060484879;929.4702019848756;946.2997381937316;18779.154938027823;20229;22228.99969501344;23022.8848855758;23196.420089497897;868.5179725252545;891.0955380289012;923.6259314977444;985.1576901749045;1006.6929586476195;11143.944280278709;11432.371219582836;11477.311361318347;11493.138014648935;11495.482703278372;465317;471474;472622;568142.3185768172;648640;108994;144129.27935360465;147749;151210;152129.23935380252;975.4001289207229;991.5438898106952;1004.0110491166196;1004.5529739265957;1006.2603986070379;14496.905569974215;19126.617467650292;19458.902705486948;19866.90066549658;20503.616793855654;56895.146572800615;57860;58353;58544.70727646335;59542;67811.66094169498;75904;78328;78890;82186.58906705667;5176.948230517647;5694.743736531783;7441.660501109852;7893.526388416713;8390.28682561989;11847.289162650268;12486.875131248571;12546.881684059701;12556.686082848002;12663.303518306213;10957.178211634;11001.779964400506;11146.888531114586;11147.219694621299;11150.275232109887;10703.892961070602;10749.570017199538;10904.945475272572;10909.072728818139;11096.722581935515;11002.119830413707;11019.83470247931;11078.5568577256;11298;11361.034312083566;7937.484063535851;7940.6426710796995;8221.17788221173;8263.917360826315;8272.627731751965;10303.175745940456;10946.45267736594;10963.725906852393;11143.052840508648;11154.832677510007 20:52:37 INFO - PROCESS | 1656 | |3;mootools.html;1408.0495665425835;1565.4755656854957;1597.3450885137095;1735.4273089880342;1822.3439561757764;8029.8394032119395;8851;9088.409253398524;9098.408603440772;9207.539623018856;8752.91247087529;9002.594883230255;9009.324300677443;9009.864852027209;9010.86483702745;2387.1167667962836;2417.7340492545804;2421.4188594737225;2425.514897020594;2426.6602675625427;2381.249906279525;2409.2410890569413;2417.16607770319;2423.7333893271725;2426.599611064172;2375.9168429104984;2392.138830021191;2403;2412.0592968742203;2413.9517209655796;2357.2221167014845;2372.6678265042906;2376.584097782881;2377.1679912030822;2390.8685022323766;1925.1625542888748;2174.6846707227523;2223.899924503401;2266.88665566721;2267.580497607948;8037.115917249098;8251.00987881452;9031.322650801216;9144.314176436726;9149.771255718593;5195.922061169085;5362.195670649395;5376.4085950545405;5408.648437851527;5409.080456322435;3643.908902277438;3669.3395188865993;3716.3310604091253;3732.3654978653694;3740.4202348636004;1097.078454098553;1105.4141305108305;1107.7286064914067;1116.6984914073155;1125.6229163230328;10924.890751092467;11171.497282622302;12176.208546444454;12291.385430728516;12310.753784924342;4684.297355396718;4784.167897213032;4796.856094317176;4890.559849613481;4916.697075275055;10353.896461035443;10401.27191096631;10406.687799366031;10409.271351005356;10463.947680261703;5441.863953401117;5448.754806033738;5459.235707001023;5461.890762184734;5465.562754979592;4923.606111511142;4937.185364414831;4971.229459433794;4986.7257300848805;5005.724685142352;3817.10298079951;3827.349350610403;3868.845246190121;3913.3347330953807;3929.430232616312;1250.756102559992;1259.3010878962082;1260.4580030586935;1272.535524533557;1277.2719549856492;897.2059727141527;957.2055194188806;961.2886464016577;962.831504486712;981.2280308129563;5950.375210602824;6060.909086363708;6117.113018612276;6153.476954458921;6173;405.9391091336323;413.7517489506272;425.6424603333215;431.66977262394295;432.2069003378835;3718.1448266898224;3817.6945844332386;3818;3863.6522712955407;3866.149447121685;10725.892741072645;10984.231103822656;11017.669469915916;11146.888531114586;11148.05241554477;11079.667609971713;11092.611758588411;11097.667069987914;11109.722256943642;11113.944430277797;8095.676172953134;8308;8442.197991190827;8459.577021149042;8469.026062002826;6018.909716354346;6039;6058.878822423439;6091.817245482643;6151.108089327112;10083.848742269018;10482;10482.37105773656;10487.161027117965;10499.895001050198;10207.183425325808;10602.310849794738;10611.628592999517;10620.734481638021;10641.574337026432;7506.662200201114;7518.097828260644;7612.086549614081;7613.467057305936;7619.542827430372;8269.751907442545;8527.403081784463;8561.10108438605;8563.357748169037;8565.828683426173;7554.2823431773895;7615.352695020985;7693.230676932486;7819.335356494762;7849.843003140019;7485.88771168422;7494.288042635941;7579.0905091387185;7637;7739.032620922385;7158.355747982707;7264.091988501437;7268.200497945116;7268.563886166847;7269.3094156055095;4827.324174615486;4913.557779799835;4963.205887058053;4976.427710813303;5072.442031376472;5115.590752739847;5159.613029022913;5160.354955630546;5379.838604841861;5412.404635490172 20:52:37 INFO - PROCESS | 1656 | |4;prototype.html;290.5278921752153;306.1351681499764;310.3467201540757;321.15215830208257;325.71662653491467;4146.751194928306;5244.89510209796;5369.62412631116;5382.842688821903;5425.29471168748;5177.352830896138;5266.52601265886;5307.628466007372;5311.867203319919;5337.70642614656;4063.471748672683;4094.549599544048;4116.97941510292;4124.443200167969;4130.339145736682;3850.2107068051123;3875.689944804423;4014.7189696721243;4055.634992850643;4057.4928133983253;3539.557555305587;3945.7768091891608;4082.265192265391;4108.322126849066;4119.8970025749295;3132.9974408189387;4077.694172937012;4091.672666186713;4098.159877225162;4106.691998100155;3128.937421251585;3511.719062474996;3612.422012477991;3622.710183185365;3644.034330902313;5095.821646242401;5165.811863271469;5171.663841850268;5175.01674681813;5182.196759502283;4702.153612349775;4710.31700403443;4718.363020992157;4718.8584342469785;4718.961828397747;4809.495003024702;4998.500149985009;5535.892821435729;5591.27313449249;5654.095344744821;3914.941275880864;3928.9214215715524;3934.193490334503;3943.64507194351;3968.8412463501427;5173.793048278064;5927.903337882505;5931.169636250928;5943.465088142045;5950.107483877453;3571.535700358989;3661.5789184244154;4031.858884939013;4037.6164264394833;4053;3266.281418088017;3349.43059679855;3474.3398754236614;4009.6591789698205;4029.1337362466625;4139.77231252278;4443.800028998703;4562.840300589463;4601.493835678073;4690.226112691368;5198.792048318025;5435.456454354612;5698.914516282259;5699.349802341931;5725.713714314267;1255.3785875991443;1303.322272418356;1343.630501612045;1385.088577762701;1386.4731402067353;861.7673228228343;882.4308321132962;918.3571499950061;920.9033051529627;928.3826255540125;4595.517470665532;4964.212883361987;5256.842294731164;5270.262163297141;5286.101362768339;4887.3890763654545;5289.656172348785;5410.91883621746;5713.057345538023;5789.623674461231;5039.966806804597;5226.45122262157;5292.4707529247535;5418.566514678895;5473.507384335429;3028;3042.9653917668097;3043.46739320618;3048.4055609156003;3075.5232938894505;5234.534330387498;5763.682997435098;5781.248437703071;5792.13289421739;5852.326982397079;5224;5862.970685146547;5959.46364827159;5985.551083668743;5996.730147143389;5323.840284791462;5745.2818397700285;5976.760929562855;5981.342052374235;6028.4272994065495;5155.9742201288755;5287.206918962186;5414.8323230910355;5511;5841.283803031611;4700.694454860423;5627.859303517281;5662.518685911744;5715.742791574306;5716.742746576498;5057.924131137962;5476.561875050066;5976.057781221034;6045.879082418415;6057.061155520902;3622.9493446900105;3925.509311336083;3972.2323566012847;3979.900502487461;4004.2592120457807;5381.381141168659;5402.946425447;5638.661680299195;5797.449242321973;5866.829273168954;4705.247160454311;4737.265723812815;5440.945590544202;5693.572982026448;5701.918799638185;5656.60403771732;5659.6321239119325;5674.375818659862;5682.687452190169;5690.487856092972;4106.938395924004;5349.331333583302;5371.382291036581;5522.3649280333275;5641.210230567642;4505.414296141481;5659.151127330933;5664.745086471201;5703.62926409782;5718;5400.297961265011;5474.91787623178;5538.252335934597;5570.5543556516195;5581.7209139543675 20:52:37 INFO - PROCESS | 1656 | |5;yui.html;743.2567432567432;752.2928447259576;775.2402645407501;798.5807451088182;903.4172958441806;14554.781678274821;15740.212989350544;15744.606384840356;15772.763408548866;15774.4478943237;12828;13209;13254.403551840167;13281.402336894838;13308.134971226864;691.6023286610207;901.8827552418177;904.995475022624;909;910.4673765846987;881.8324518341494;897.0043251990286;897.6633762339122;899.3749344205779;903.7695387676166;947.2611363136764;948.1514044929784;948.9478078705669;949.2643201518844;951.219999600328;936.5036530638704;940.1679513630418;940.1867384712243;940.3041749105647;941.3457646935383;711.4415184080472;711.7153138744492;712.2343480758205;713.8429545499981;715.0632671200692;11134.832977505344;12999.610011699664;12999.805002924964;13021.088523803337;13035.957123430102;11938;12063.457144428521;12181.878181218251;12191.45138468771;12318;9068.682596109105;11050.171237157245;11066.612668556643;11069.114470842313;11446.885531144748;3697.8335974881193;3793.4499497572974;3804.5624753153465;3810.9618903810883;3814.4278358246206;112530.87469125286;114786;115230;115292;115678.42160789226;9286.95356523213;9303.53482325881;9344.719658410258;9477.90522094784;9697.612095516242;19635.410937671608;19780.70328945067;19783.703244451346;19831.900840495706;19835.30576429818;8175.7138500152205;8244.958775206205;8245.79385515367;8262.42163048581;8481.872771908304;4216.7259128156575;4311.223979683625;4374.081442896963;4605.148047611203;4716.1510928033285;3489.7382696297877;3611.638836116367;3618.6019537851344;3622.5109610202285;3633.3096711624707;662.9834254143685;667.5460686732971;674.8380388706676;685.1127789512593;685.3557655803529;612.3937302071007;633.9841503962438;636.9395155489683;685.2051620120736;697.4072038767007;9159.587818548182;9280.7679808004;9308.929473110544;9523.619055237712;9637.469939153274;514.6500379741811;548.9945100548944;562.0557463461424;612.8161551534522;653.7646447279071;4615.076984603093;4616.492185859553;4629.490756016836;4666.65000124992;4728.668993170514;114033;147097.26451367675;147390;149871;152954;131547.34226329;131589;132542.33728831293;132585;135265;73366.63316683382;108413;113808;114857.4257128709;115776.90348538673;8644.827103458021;8875.378723489552;8973.955130224307;8998.59506322204;9061.818763624822;59064.818703626544;62644.30167737347;65752;66031.67936641144;67927.98108028284;94915.52542237245;98800.50599746955;98831;99589;101074;11843.763124737285;12013;12036.518539258332;12174.14780965324;12592.622221333375;11544.711382215512;12447.377631118588;12585.685357865761;12974.740505189655;13100.868991309964;12054.698632534257;12063.21589096706;12086.39568021613;12101.273923564615;12130.818037729618;12035.75928481443;12079.214851034654;12172.513099476277;12787.61637150887;12860.614181574567;11850.52597896109;11980.76038479243;12020.278783273032;12071.456784444545;12192.451339689558;8515.829683406173;8879;10186.694399168036;10347.379157250589;10357.430341330997;9946.678526988027;10138.28086998794;10219;10230.590776368861;10282.588696452358 20:52:37 INFO - PROCESS | 1656 | __end_tp_report 20:52:37 INFO - PROCESS | 1656 | __start_cc_report 20:52:37 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1157 20:52:37 INFO - PROCESS | 1656 | __end_cc_report 20:52:37 INFO - PROCESS | 1656 | __startTimestamp1468468357598__endTimestamp 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | ------- Summary: start ------- 20:52:37 INFO - PROCESS | 1656 | Number of tests: 6 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5651.82 Median:5820.77 stddev:662.59 (11.4%) stddev-sans-first:632.80 20:52:37 INFO - PROCESS | 1656 | Values: 3065.6 3329.9 3333.7 3362.0 3398.5 5523.0 5591.0 5616.7 5628.7 5676.7 5611.4 5640.7 5718.8 5724.1 5736.7 5061.5 5074.1 5141.6 5152.8 5222.9 5198.9 5226.3 5244.9 5255.2 5292.5 5061.1 5077.6 5106.7 5125.2 5153.5 4284.4 5111.2 5143.7 5184.2 5211.9 4276.6 5013.9 5052.3 5118.3 5152.4 5599.0 5602.3 5665.0 5699.2 5700.7 5882.6 5984.8 6057.8 6102.9 6130.1 5226.7 6177.3 6207.8 6232.3 6246.1 5700.3 5705.0 5752.3 5753.4 5760.9 6133.7 6234.0 6312.8 6337.7 6341.0 6018.1 6030.8 6041.9 6077.0 6083.8 6133.7 6222.3 6229.2 6230.9 6237.0 5964.8 6011.6 6050.9 6110.9 6125.5 6125.4 6133.0 6175.0 6176.8 6192.8 4873.8 4876.0 4883.1 4894.7 4902.8 3740.5 4191.8 4247.9 4301.6 4320.8 5677.5 5678.0 5684.0 5688.4 5748.5 6054.9 6211.2 6235.1 6239.6 6240.2 5576.2 5608.1 5622.1 5639.8 5646.0 5274.7 5352.2 5359.1 5359.9 5370.1 6332.9 6344.7 6344.9 6353.5 6355.1 5914.3 6190.8 6191.1 6197.0 6200.1 6198.6 6266.9 6319.2 6320.5 6329.7 6018.5 6056.0 6074.3 6092.5 6123.9 6032.5 6126.7 6151.2 6201.5 6204.0 5880.6 5933.9 5940.3 5996.6 6040.4 5967.9 5988.6 5995.8 5996.3 6008.6 5685.1 5841.9 5843.8 5845.4 5858.9 5779.7 5888.9 5904.6 5936.5 5952.6 5752.6 5804.3 5811.5 5816.6 5820.8 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:65779.57 Median:362274.50 stddev:141404.82 (39.0%) stddev-sans-first:141716.52 20:52:37 INFO - PROCESS | 1656 | Values: 488.5 490.3 499.0 516.0 533.0 29614.7 31131.4 31149.7 31174.8 40178.8 41102.0 43877.0 43941.0 43983.3 44048.3 9532.8 9720.2 9746.9 9762.7 9769.7 8747.8 8897.3 8902.0 9616.0 9734.8 8894.9 8997.3 11733.2 11929.9 11968.6 11985.0 12025.7 12026.5 12045.0 12058.3 8641.0 11755.4 11823.9 11829.6 11833.9 39761.4 40098.0 40144.0 42613.6 42691.8 3517.2 3654.5 3840.7 3897.4 4182.1 31449.2 32681.0 32893.0 32928.5 33009.5 3334.5 3351.5 3413.3 3414.2 3456.4 361538.0 361683.0 362265.0 362284.0 365703.2 50623.0 60303.7 60605.0 60654.4 60757.8 63412.7 63730.0 63969.0 63987.7 63999.7 16651.2 16831.2 16867.3 16884.5 16956.4 13123.2 13192.2 13210.5 13238.5 13242.2 14453.2 14714.3 15765.2 16456.2 16504.8 7067.4 7254.9 7279.6 7306.7 7332.5 950.3 1028.4 1124.0 1164.6 1209.4 26869.5 29848.7 31425.6 31617.8 31867.4 1018.2 1058.0 1059.4 1136.8 1150.3 15353.3 15434.5 15487.9 15522.1 15871.8 631373.0 634459.0 635601.0 640500.0 642536.0 58560.4 58655.7 58733.1 58814.7 58921.0 1435.9 1442.9 1468.5 1472.7 1475.0 29136.0 29816.6 29909.7 30142.0 30161.4 51225.0 51938.7 51990.2 52126.2 52167.5 534156.0 553098.0 553140.7 555213.0 555349.0 23255.8 23866.9 25958.6 26017.1 26427.0 44784.4 46979.8 47271.0 48277.0 48344.0 35233.6 37801.6 38593.4 38957.4 39483.8 37053.4 39349.0 39786.6 39893.0 40032.8 33460.0 36891.4 37643.8 37742.6 37893.8 28019.6 32568.2 33041.5 34519.0 34654.8 21287.5 23537.8 24880.5 25123.9 25588.8 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32615.28 Median:12211.21 stddev:89359.60 (731.8%) stddev-sans-first:89577.46 20:52:37 INFO - PROCESS | 1656 | Values: 361.7 381.9 390.8 395.2 416.1 15596.8 17771.6 21628.7 21646.8 21719.8 20834.5 21530.1 21549.8 21566.6 21611.6 5797.9 5906.3 6100.7 6106.2 6135.5 5988.7 5991.3 6061.9 6062.2 6066.3 5816.1 6012.4 6152.9 6165.6 6186.9 5814.7 6108.1 6152.7 6171.9 6181.6 5982.6 6000.9 6029.1 6029.6 6035.9 13563.4 13601.8 13637.4 13858.2 13954.6 2011.3 2179.6 2193.4 2587.7 2760.5 10376.7 10750.5 10804.6 12700.8 15172.5 1871.7 1955.9 1988.0 2001.0 2080.1 71375.3 72590.0 74190.3 127910.0 134014.7 10412.5 11522.7 11645.5 11726.9 11755.8 14406.4 14502.3 14531.3 14569.9 14589.5 8151.8 8609.7 8631.2 8637.7 8754.9 8738.2 8746.1 8761.7 8862.8 8876.6 3710.6 4483.7 4554.8 4625.6 4704.6 1893.2 1927.2 1931.1 1931.4 1931.5 770.3 786.8 896.8 929.5 946.3 18779.2 20229.0 22229.0 23022.9 23196.4 868.5 891.1 923.6 985.2 1006.7 11143.9 11432.4 11477.3 11493.1 11495.5 465317.0 471474.0 472622.0 568142.3 648640.0 108994.0 144129.3 147749.0 151210.0 152129.2 975.4 991.5 1004.0 1004.6 1006.3 14496.9 19126.6 19458.9 19866.9 20503.6 56895.1 57860.0 58353.0 58544.7 59542.0 67811.7 75904.0 78328.0 78890.0 82186.6 5176.9 5694.7 7441.7 7893.5 8390.3 11847.3 12486.9 12546.9 12556.7 12663.3 10957.2 11001.8 11146.9 11147.2 11150.3 10703.9 10749.6 10904.9 10909.1 11096.7 11002.1 11019.8 11078.6 11298.0 11361.0 7937.5 7940.6 8221.2 8263.9 8272.6 10303.2 10946.5 10963.7 11143.1 11154.8 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5839.15 Median:4734.23 stddev:3332.05 (70.4%) stddev-sans-first:3324.75 20:52:37 INFO - PROCESS | 1656 | Values: 1408.0 1565.5 1597.3 1735.4 1822.3 8029.8 8851.0 9088.4 9098.4 9207.5 8752.9 9002.6 9009.3 9009.9 9010.9 2387.1 2417.7 2421.4 2425.5 2426.7 2381.2 2409.2 2417.2 2423.7 2426.6 2375.9 2392.1 2403.0 2412.1 2414.0 2357.2 2372.7 2376.6 2377.2 2390.9 1925.2 2174.7 2223.9 2266.9 2267.6 8037.1 8251.0 9031.3 9144.3 9149.8 5195.9 5362.2 5376.4 5408.6 5409.1 3643.9 3669.3 3716.3 3732.4 3740.4 1097.1 1105.4 1107.7 1116.7 1125.6 10924.9 11171.5 12176.2 12291.4 12310.8 4684.3 4784.2 4796.9 4890.6 4916.7 10353.9 10401.3 10406.7 10409.3 10463.9 5441.9 5448.8 5459.2 5461.9 5465.6 4923.6 4937.2 4971.2 4986.7 5005.7 3817.1 3827.3 3868.8 3913.3 3929.4 1250.8 1259.3 1260.5 1272.5 1277.3 897.2 957.2 961.3 962.8 981.2 5950.4 6060.9 6117.1 6153.5 6173.0 405.9 413.8 425.6 431.7 432.2 3718.1 3817.7 3818.0 3863.7 3866.1 10725.9 10984.2 11017.7 11146.9 11148.1 11079.7 11092.6 11097.7 11109.7 11113.9 8095.7 8308.0 8442.2 8459.6 8469.0 6018.9 6039.0 6058.9 6091.8 6151.1 10083.8 10482.0 10482.4 10487.2 10499.9 10207.2 10602.3 10611.6 10620.7 10641.6 7506.7 7518.1 7612.1 7613.5 7619.5 8269.8 8527.4 8561.1 8563.4 8565.8 7554.3 7615.4 7693.2 7819.3 7849.8 7485.9 7494.3 7579.1 7637.0 7739.0 7158.4 7264.1 7268.2 7268.6 7269.3 4827.3 4913.6 4963.2 4976.4 5072.4 5115.6 5159.6 5160.4 5379.8 5412.4 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4561.74 Median:5190.49 stddev:1392.07 (26.8%) stddev-sans-first:1358.56 20:52:37 INFO - PROCESS | 1656 | Values: 290.5 306.1 310.3 321.2 325.7 4146.8 5244.9 5369.6 5382.8 5425.3 5177.4 5266.5 5307.6 5311.9 5337.7 4063.5 4094.5 4117.0 4124.4 4130.3 3850.2 3875.7 4014.7 4055.6 4057.5 3539.6 3945.8 4082.3 4108.3 4119.9 3133.0 4077.7 4091.7 4098.2 4106.7 3128.9 3511.7 3612.4 3622.7 3644.0 5095.8 5165.8 5171.7 5175.0 5182.2 4702.2 4710.3 4718.4 4718.9 4719.0 4809.5 4998.5 5535.9 5591.3 5654.1 3914.9 3928.9 3934.2 3943.6 3968.8 5173.8 5927.9 5931.2 5943.5 5950.1 3571.5 3661.6 4031.9 4037.6 4053.0 3266.3 3349.4 3474.3 4009.7 4029.1 4139.8 4443.8 4562.8 4601.5 4690.2 5198.8 5435.5 5698.9 5699.3 5725.7 1255.4 1303.3 1343.6 1385.1 1386.5 861.8 882.4 918.4 920.9 928.4 4595.5 4964.2 5256.8 5270.3 5286.1 4887.4 5289.7 5410.9 5713.1 5789.6 5040.0 5226.5 5292.5 5418.6 5473.5 3028.0 3043.0 3043.5 3048.4 3075.5 5234.5 5763.7 5781.2 5792.1 5852.3 5224.0 5863.0 5959.5 5985.6 5996.7 5323.8 5745.3 5976.8 5981.3 6028.4 5156.0 5287.2 5414.8 5511.0 5841.3 4700.7 5627.9 5662.5 5715.7 5716.7 5057.9 5476.6 5976.1 6045.9 6057.1 3622.9 3925.5 3972.2 3979.9 4004.3 5381.4 5402.9 5638.7 5797.4 5866.8 4705.2 4737.3 5440.9 5693.6 5701.9 5656.6 5659.6 5674.4 5682.7 5690.5 4106.9 5349.3 5371.4 5522.4 5641.2 4505.4 5659.2 5664.7 5703.6 5718.0 5400.3 5474.9 5538.3 5570.6 5581.7 20:52:37 INFO - PROCESS | 1656 | 20:52:37 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24509.69 Median:4015.58 stddev:40033.89 (997.0%) stddev-sans-first:40106.42 20:52:37 INFO - PROCESS | 1656 | Values: 743.3 752.3 775.2 798.6 903.4 14554.8 15740.2 15744.6 15772.8 15774.4 12828.0 13209.0 13254.4 13281.4 13308.1 691.6 901.9 905.0 909.0 910.5 881.8 897.0 897.7 899.4 903.8 947.3 948.2 948.9 949.3 951.2 936.5 940.2 940.2 940.3 941.3 711.4 711.7 712.2 713.8 715.1 11134.8 12999.6 12999.8 13021.1 13036.0 11938.0 12063.5 12181.9 12191.5 12318.0 9068.7 11050.2 11066.6 11069.1 11446.9 3697.8 3793.4 3804.6 3811.0 3814.4 112530.9 114786.0 115230.0 115292.0 115678.4 9287.0 9303.5 9344.7 9477.9 9697.6 19635.4 19780.7 19783.7 19831.9 19835.3 8175.7 8245.0 8245.8 8262.4 8481.9 4216.7 4311.2 4374.1 4605.1 4716.2 3489.7 3611.6 3618.6 3622.5 3633.3 663.0 667.5 674.8 685.1 685.4 612.4 634.0 636.9 685.2 697.4 9159.6 9280.8 9308.9 9523.6 9637.5 514.7 549.0 562.1 612.8 653.8 4615.1 4616.5 4629.5 4666.7 4728.7 114033.0 147097.3 147390.0 149871.0 152954.0 131547.3 131589.0 132542.3 132585.0 135265.0 73366.6 108413.0 113808.0 114857.4 115776.9 8644.8 8875.4 8974.0 8998.6 9061.8 59064.8 62644.3 65752.0 66031.7 67928.0 94915.5 98800.5 98831.0 99589.0 101074.0 11843.8 12013.0 12036.5 12174.1 12592.6 11544.7 12447.4 12585.7 12974.7 13100.9 12054.7 12063.2 12086.4 12101.3 12130.8 12035.8 12079.2 12172.5 12787.6 12860.6 11850.5 11980.8 12020.3 12071.5 12192.5 8515.8 8879.0 10186.7 10347.4 10357.4 9946.7 10138.3 10219.0 10230.6 10282.6 20:52:37 INFO - PROCESS | 1656 | -------- Summary: end -------- 20:52:37 INFO - PROCESS | 1656 | 20:52:38 INFO - TEST-INFO | 1656: exit 0 20:52:38 INFO - TEST-OK | dromaeo_css | took 1114630ms 20:52:38 INFO - TEST-START | kraken 20:52:38 INFO - operating with platform_type : mac_ 20:52:38 INFO - Initialising browser for kraken test... 20:52:38 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmp2KJVFW/profile http://localhost:49268/getInfo.html) 20:52:40 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 20:52:40 INFO - PROCESS | 1662 | colorDepth:24 20:52:40 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 20:52:40 INFO - PROCESS | 1662 | __metrics 20:52:40 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:52:40 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:52:41 INFO - TEST-INFO | 1662: exit 0 20:52:41 INFO - Browser initialized. 20:52:41 INFO - Running cycle 1/1 for kraken test... 20:52:41 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmp2KJVFW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:52:53 INFO - PROCESS | 1663 | RSS: Main: 243822592 20:52:53 INFO - PROCESS | 1663 | 20:52:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 20:52:56 INFO - PROCESS | 1663 | RSS: Main: 294756352 20:52:56 INFO - PROCESS | 1663 | 20:52:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 20:52:59 INFO - PROCESS | 1663 | RSS: Main: 305143808 20:52:59 INFO - PROCESS | 1663 | 20:53:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 20:53:04 INFO - PROCESS | 1663 | RSS: Main: 389435392 20:53:04 INFO - PROCESS | 1663 | 20:53:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 20:53:06 INFO - PROCESS | 1663 | RSS: Main: 290361344 20:53:06 INFO - PROCESS | 1663 | 20:53:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:53:09 INFO - PROCESS | 1663 | RSS: Main: 294223872 20:53:09 INFO - PROCESS | 1663 | 20:53:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 20:53:12 INFO - PROCESS | 1663 | RSS: Main: 331874304 20:53:12 INFO - PROCESS | 1663 | 20:53:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 20:53:16 INFO - PROCESS | 1663 | RSS: Main: 333598720 20:53:16 INFO - PROCESS | 1663 | 20:53:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 20:53:19 INFO - PROCESS | 1663 | RSS: Main: 332361728 20:53:19 INFO - PROCESS | 1663 | 20:53:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:53:21 INFO - PROCESS | 1663 | RSS: Main: 314224640 20:53:21 INFO - PROCESS | 1663 | 20:53:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:53:24 INFO - PROCESS | 1663 | RSS: Main: 321245184 20:53:24 INFO - PROCESS | 1663 | 20:53:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:53:27 INFO - PROCESS | 1663 | RSS: Main: 317927424 20:53:27 INFO - PROCESS | 1663 | 20:53:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:53:30 INFO - PROCESS | 1663 | RSS: Main: 327831552 20:53:30 INFO - PROCESS | 1663 | 20:53:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:53:34 INFO - PROCESS | 1663 | RSS: Main: 329015296 20:53:34 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 20:53:36 INFO - PROCESS | 1663 | RSS: Main: 372535296 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | __start_tp_report 20:53:36 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 20:53:36 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 20:53:36 INFO - PROCESS | 1663 | |i|pagename|runs| 20:53:36 INFO - PROCESS | 1663 | |0;ai-astar;102;114;114;113;113;113;114;113;112;111 20:53:36 INFO - PROCESS | 1663 | |1;audio-beat-detection;168;96;128;195;133;131;105;126;141;106 20:53:36 INFO - PROCESS | 1663 | |2;audio-dft;184;181;184;184;184;182;182;183;184;182 20:53:36 INFO - PROCESS | 1663 | |3;audio-fft;79;74;58;108;58;56;60;69;82;56 20:53:36 INFO - PROCESS | 1663 | |4;audio-oscillator;85;89;93;71;76;68;95;73;67;101 20:53:36 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;117;131;99;85;87;85;90;84;87;83 20:53:36 INFO - PROCESS | 1663 | |6;imaging-darkroom;93;93;95;93;92;93;94;93;93;92 20:53:36 INFO - PROCESS | 1663 | |7;imaging-desaturate;90;90;89;88;89;88;89;91;89;86 20:53:36 INFO - PROCESS | 1663 | |8;json-parse-financial;55;54;54;54;54;54;55;54;54;54 20:53:36 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;69;73;74;73;71;70;69;70;72 20:53:36 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;85;84;83;83;81;94;101;98;87;83 20:53:36 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;138;134;139;143;128;134;136;136;177;137 20:53:36 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;165;172;213;165;163;163;163;215;221;176 20:53:36 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;66;67;67;68;69;83;82;82;84;85 20:53:36 INFO - PROCESS | 1663 | __end_tp_report 20:53:36 INFO - PROCESS | 1663 | __start_cc_report 20:53:36 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1989 20:53:36 INFO - PROCESS | 1663 | __end_cc_report 20:53:36 INFO - PROCESS | 1663 | __startTimestamp1468468416426__endTimestamp 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | ------- Summary: start ------- 20:53:36 INFO - PROCESS | 1663 | Number of tests: 14 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:3.60 (3.2%) stddev-sans-first:1.00 20:53:36 INFO - PROCESS | 1663 | Values: 102.0 114.0 114.0 113.0 113.0 113.0 114.0 113.0 112.0 111.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:137.00 stddev:29.99 (21.9%) stddev-sans-first:28.99 20:53:36 INFO - PROCESS | 1663 | Values: 168.0 96.0 128.0 195.0 133.0 131.0 105.0 126.0 141.0 106.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.15 (0.6%) stddev-sans-first:1.17 20:53:36 INFO - PROCESS | 1663 | Values: 184.0 181.0 184.0 184.0 184.0 182.0 182.0 183.0 184.0 182.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:70.00 Median:64.50 stddev:16.55 (25.7%) stddev-sans-first:17.23 20:53:36 INFO - PROCESS | 1663 | Values: 79.0 74.0 58.0 108.0 58.0 56.0 60.0 69.0 82.0 56.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:80.50 stddev:12.33 (15.3%) stddev-sans-first:13.02 20:53:36 INFO - PROCESS | 1663 | Values: 85.0 89.0 93.0 71.0 76.0 68.0 95.0 73.0 67.0 101.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:86.00 stddev:16.38 (19.0%) stddev-sans-first:15.27 20:53:36 INFO - PROCESS | 1663 | Values: 117.0 131.0 99.0 85.0 87.0 85.0 90.0 84.0 87.0 83.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 20:53:36 INFO - PROCESS | 1663 | Values: 93.0 93.0 95.0 93.0 92.0 93.0 94.0 93.0 93.0 92.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:88.90 Median:89.00 stddev:1.37 (1.5%) stddev-sans-first:1.39 20:53:36 INFO - PROCESS | 1663 | Values: 90.0 90.0 89.0 88.0 89.0 88.0 89.0 91.0 89.0 86.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.33 20:53:36 INFO - PROCESS | 1663 | Values: 55.0 54.0 54.0 54.0 54.0 54.0 55.0 54.0 54.0 54.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:2.41 (3.4%) stddev-sans-first:1.86 20:53:36 INFO - PROCESS | 1663 | Values: 66.0 69.0 73.0 74.0 73.0 71.0 70.0 69.0 70.0 72.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:84.50 stddev:7.11 (8.4%) stddev-sans-first:7.46 20:53:36 INFO - PROCESS | 1663 | Values: 85.0 84.0 83.0 83.0 81.0 94.0 101.0 98.0 87.0 83.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:137.50 stddev:13.50 (9.8%) stddev-sans-first:14.29 20:53:36 INFO - PROCESS | 1663 | Values: 138.0 134.0 139.0 143.0 128.0 134.0 136.0 136.0 177.0 137.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:174.00 stddev:24.42 (14.0%) stddev-sans-first:25.15 20:53:36 INFO - PROCESS | 1663 | Values: 165.0 172.0 213.0 165.0 163.0 163.0 163.0 215.0 221.0 176.0 20:53:36 INFO - PROCESS | 1663 | 20:53:36 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:82.00 stddev:8.41 (10.3%) stddev-sans-first:8.22 20:53:36 INFO - PROCESS | 1663 | Values: 66.0 67.0 67.0 68.0 69.0 83.0 82.0 82.0 84.0 85.0 20:53:36 INFO - PROCESS | 1663 | -------- Summary: end -------- 20:53:36 INFO - PROCESS | 1663 | 20:53:37 INFO - TEST-INFO | 1663: exit 0 20:53:37 INFO - TEST-OK | kraken | took 59068ms 20:53:37 INFO - SUITE-END | took 1173s 20:53:37 INFO - Completed test suite (00:19:34) 20:53:37 INFO - javascript benchmark 20:53:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3065.5708200851886, 3329.9167520811984, 3333.7333013358934, 3361.983190084049, 3398.524206611075, 5522.972385138079, 5591.049521581331, 5616.719164041802, 5628.718564071795, 5676.659400435971, 5611.382747897728, 5640.661560306385, 5718.771249150029, 5724.055530837407, 5736.684482353469, 5061.519155680205, 5074.137396642561, 5141.614378921578, 5152.845414637567, 5222.92165617516, 5198.948010519903, 5226.320578324812, 5244.947550524503, 5255.185446255837, 5292.523672869441, 5061.089003979282, 5077.593792496591, 5106.744662766884, 5125.2312153176945, 5153.510416510426, 4284.443022407098, 5111.182210846247, 5143.742812859342, 5184.248283998799, 5211.869703257411, 4276.615104640598, 5013.949860501385, 5052.317937078484, 5118.334616499831, 5152.355955505562, 5598.972005139989, 5602.299712535933, 5664.971675141639, 5699.202111704323, 5700.743466544015, 5882.55880808941, 5984.790532331347, 6057.848553786191, 6102.91701108444, 6130.092410195535, 5226.73866306687, 6177.289611694668, 6207.751689932443, 6232.283287421959, 6246.094316324153, 5700.258966334433, 5704.973104841086, 5752.28096487939, 5753.395893431213, 5760.913586296209, 6133.723982420802, 6233.96883015582, 6312.842178945563, 6337.651429171347, 6340.968295158587, 6018.067199584071, 6030.758769649253, 6041.881904156405, 6077.0, 6083.787067452707, 6133.693315334215, 6222.253329600477, 6229.221347331583, 6230.906536401958, 6236.968815155895, 5964.761409543569, 6011.579189456784, 6050.939490605126, 6110.938890611126, 6125.509959203253, 6125.356837532084, 6132.973935358129, 6175.0, 6176.845578860564, 6192.81421557354, 4873.780679869415, 4876.049170411736, 4883.096627123993, 4894.706317620954, 4902.803887844518, 3740.532433445819, 4191.832326706897, 4247.936280955788, 4301.634361079281, 4320.783960801948, 5677.517411020027, 5678.0, 5683.9715801420725, 5688.402717714663, 5748.45389687979, 6054.878902421927, 6211.16149319836, 6235.127082208494, 6239.563230573816, 6240.188775459252, 5576.163575463632, 5608.074667679869, 5622.128570071645, 5639.802606908738, 5645.971770141123, 5274.736263186902, 5352.223927530486, 5359.08895487768, 5359.866003349947, 5370.113931201387, 6332.905006424908, 6344.714487848058, 6344.9048264277, 6353.4599559038, 6355.078513615592, 5914.260717410324, 6190.7833225838685, 6191.102290167991, 6197.0, 6200.131981522501, 6198.6280823150755, 6266.874662506633, 6319.24169099711, 6320.525960553069, 6329.651869147241, 6018.488428483628, 6055.969720151371, 6074.271087469532, 6092.515608078715, 6123.938760612515, 6032.487238584594, 6126.7356173883045, 6151.231096112986, 6201.472874805692, 6204.038374052029, 5880.647161170344, 5933.851653708692, 5940.346561878104, 5996.610220335664, 6040.395960403925, 5967.910481342697, 5988.5508586855285, 5995.7901473449965, 5996.2504686914135, 6008.579399441997, 5685.090385538294, 5841.94158058414, 5843.824685259449, 5845.444682755132, 5858.882822343615, 5779.682117483579, 5888.941110588839, 5904.61619994699, 5936.465718085393, 5952.5833191676165, 5752.568557358131, 5804.303483581997, 5811.506021988178, 5816.563757718103, 5820.76716931318], "value": 5607.44569402664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.4626910398562, 490.3331469201887, 499.0069761175262, 515.9664621799583, 532.9920051199231, 29614.703852961466, 31131.377372452538, 31149.68850311496, 31174.844125779367, 40178.7991060045, 41102.0, 43877.0, 43941.0, 43983.340249896195, 44048.339274910824, 9532.809343813136, 9720.222382209442, 9746.90253097467, 9762.658306959258, 9769.706908792748, 8747.781305467382, 8897.33270004749, 8902.020777714446, 9616.038396160398, 9734.805303893918, 8894.866577001318, 8997.280217582611, 11733.237339572943, 11929.940350298237, 11968.640940771747, 11985.0, 12025.699357516045, 12026.4588093536, 12045.0, 12058.27650340978, 8640.956795216047, 11755.412229388581, 11823.94088029563, 11829.645110646694, 11833.940830295793, 39761.40357894634, 40097.99755006119, 40144.0, 42613.57386426127, 42691.78654106709, 3517.243792584581, 3654.506641603376, 3840.6834929901142, 3897.376419772851, 4182.100848317596, 31449.21376965571, 32681.0, 32893.0, 32928.506072408934, 33009.50485742716, 3334.4664853623544, 3351.463765797485, 3413.3343997920415, 3414.197663549064, 3456.4124098902994, 361538.0, 361683.0, 362265.0, 362284.0, 365703.17148414085, 50623.0, 60303.69848150819, 60605.0, 60654.39345606488, 60757.78484430286, 63412.682936585945, 63730.044049339296, 63969.0, 63987.680061600324, 63999.68000159969, 16651.16744162787, 16831.158442077845, 16867.32530698783, 16884.493465195817, 16956.406525771537, 13123.212607243597, 13192.20846749198, 13210.537631182862, 13238.53665121716, 13242.20546767197, 14453.205073720836, 14714.33785479656, 15765.211739412984, 16456.177191140396, 16504.83495165033, 7067.363937245673, 7254.854902902018, 7279.563226206445, 7306.671199796022, 7332.460183361447, 950.3157726436955, 1028.4035259549444, 1123.9887601124049, 1164.5691094294996, 1209.3771707570609, 26869.462610748065, 29848.701512985026, 31425.643076769335, 31617.8419107903, 31867.362652746815, 1018.1803648063232, 1057.9682609521726, 1059.3855563772993, 1136.8006752875795, 1150.3443037468562, 15353.309101090477, 15434.536963891102, 15487.915845890679, 15522.146281954376, 15871.841281587036, 631373.0, 634459.0, 635601.0, 640500.0, 642536.0, 58560.4143958555, 58655.70672146612, 58733.11900321498, 58814.705926470095, 58921.0, 1435.8635929586674, 1442.8557144285487, 1468.4933697874003, 1472.698096890135, 1475.0, 29135.980240691475, 29816.552751709176, 29909.70090299069, 30142.0, 30161.396772064876, 51225.0, 51938.74030629823, 51990.22014669707, 52126.21810672767, 52167.47832521778, 534156.0, 553098.0, 553140.702889449, 555213.0, 555349.0, 23255.76744232536, 23866.925988330913, 25958.57227852486, 26017.089401871603, 26427.0, 44784.43254486077, 46979.76510117428, 47271.0, 48277.0, 48344.0, 35233.64766352406, 37801.62198378091, 38593.421098684106, 38957.41563876601, 39483.802580986914, 37053.44419833759, 39349.0, 39786.60213397829, 39893.0, 40032.79901602957, 33460.0, 36891.44662830006, 37643.81178094092, 37742.62257377501, 37893.81053094717, 28019.57970630483, 32568.185795355304, 33041.50437743388, 34519.0, 34654.82672586621, 21287.467813304793, 23537.764622354243, 24880.50238995246, 25123.874380627978, 25588.84850181709], "value": 20025.64865991511, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.7413549312241, 381.8720728555934, 390.8202226975592, 395.2253582977364, 416.1302876987098, 15596.759425136348, 17771.55571110723, 21628.675569866424, 21646.783532164693, 21719.805410702404, 20834.479138021557, 21530.13879444824, 21549.784502154973, 21566.568668626616, 21611.567768644618, 5797.855053623672, 5906.320773111104, 6100.725467353957, 6106.236720409949, 6135.478484328837, 5988.670623115727, 5991.281046274453, 6061.909071363911, 6062.18160548327, 6066.302375226839, 5816.127580862883, 6012.3687012863675, 6152.938470615281, 6165.599236049665, 6186.907196392058, 5814.738336774813, 6108.114323423101, 6152.723127459252, 6171.87656246877, 6181.629102253879, 5982.581219314649, 6000.879982400371, 6029.095635654643, 6029.577929544934, 6035.879282414371, 13563.389647465889, 13601.795973060413, 13637.386317615732, 13858.237796921165, 13954.581362559038, 2011.2658879508874, 2179.564087182554, 2193.4187440328324, 2587.728288529696, 2760.5169095408223, 10376.740581485448, 10750.462476876124, 10804.5678172873, 12700.80948785769, 15172.468963586221, 1871.747314112591, 1955.8533110016806, 1988.0457380457317, 2001.0395010394946, 2080.147139672727, 71375.28624713738, 72589.99999999948, 74190.25809741887, 127910.0, 134014.6598534012, 10412.531436085394, 11522.711932201762, 11645.536791916466, 11726.94136529312, 11755.764884702257, 14406.351714172888, 14502.274886255645, 14531.273436328142, 14569.854301457062, 14589.489367872286, 8151.79620509492, 8609.655613775503, 8631.180037896514, 8637.740867773977, 8754.91245087541, 8738.213560779433, 8746.081661425458, 8761.693340733043, 8862.822743545223, 8876.600552975131, 3710.6289371063217, 4483.663725220622, 4554.83851617842, 4625.583697467244, 4704.623630109583, 1893.176468236294, 1927.180948097053, 1931.111688623221, 1931.4012656076661, 1931.4688460673426, 770.3221165374434, 786.8244304498068, 896.7758060484879, 929.4702019848756, 946.2997381937316, 18779.154938027823, 20229.0, 22228.99969501344, 23022.8848855758, 23196.420089497897, 868.5179725252545, 891.0955380289012, 923.6259314977444, 985.1576901749045, 1006.6929586476195, 11143.944280278709, 11432.371219582836, 11477.311361318347, 11493.138014648935, 11495.482703278372, 465317.0, 471474.0, 472622.0, 568142.3185768172, 648640.0, 108994.0, 144129.27935360465, 147749.0, 151210.0, 152129.23935380252, 975.4001289207229, 991.5438898106952, 1004.0110491166196, 1004.5529739265957, 1006.2603986070379, 14496.905569974215, 19126.617467650292, 19458.902705486948, 19866.90066549658, 20503.616793855654, 56895.146572800615, 57860.0, 58353.0, 58544.70727646335, 59542.0, 67811.66094169498, 75904.0, 78328.0, 78890.0, 82186.58906705667, 5176.948230517647, 5694.743736531783, 7441.660501109852, 7893.526388416713, 8390.28682561989, 11847.289162650268, 12486.875131248571, 12546.881684059701, 12556.686082848002, 12663.303518306213, 10957.178211634, 11001.779964400506, 11146.888531114586, 11147.219694621299, 11150.275232109887, 10703.892961070602, 10749.570017199538, 10904.945475272572, 10909.072728818139, 11096.722581935515, 11002.119830413707, 11019.83470247931, 11078.5568577256, 11298.0, 11361.034312083566, 7937.484063535851, 7940.6426710796995, 8221.17788221173, 8263.917360826315, 8272.627731751965, 10303.175745940456, 10946.45267736594, 10963.725906852393, 11143.052840508648, 11154.832677510007], "value": 9446.23470223338, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.0495665425835, 1565.4755656854957, 1597.3450885137095, 1735.4273089880342, 1822.3439561757764, 8029.8394032119395, 8851.0, 9088.409253398524, 9098.408603440772, 9207.539623018856, 8752.91247087529, 9002.594883230255, 9009.324300677443, 9009.864852027209, 9010.86483702745, 2387.1167667962836, 2417.7340492545804, 2421.4188594737225, 2425.514897020594, 2426.6602675625427, 2381.249906279525, 2409.2410890569413, 2417.16607770319, 2423.7333893271725, 2426.599611064172, 2375.9168429104984, 2392.138830021191, 2403.0, 2412.0592968742203, 2413.9517209655796, 2357.2221167014845, 2372.6678265042906, 2376.584097782881, 2377.1679912030822, 2390.8685022323766, 1925.1625542888748, 2174.6846707227523, 2223.899924503401, 2266.88665566721, 2267.580497607948, 8037.115917249098, 8251.00987881452, 9031.322650801216, 9144.314176436726, 9149.771255718593, 5195.922061169085, 5362.195670649395, 5376.4085950545405, 5408.648437851527, 5409.080456322435, 3643.908902277438, 3669.3395188865993, 3716.3310604091253, 3732.3654978653694, 3740.4202348636004, 1097.078454098553, 1105.4141305108305, 1107.7286064914067, 1116.6984914073155, 1125.6229163230328, 10924.890751092467, 11171.497282622302, 12176.208546444454, 12291.385430728516, 12310.753784924342, 4684.297355396718, 4784.167897213032, 4796.856094317176, 4890.559849613481, 4916.697075275055, 10353.896461035443, 10401.27191096631, 10406.687799366031, 10409.271351005356, 10463.947680261703, 5441.863953401117, 5448.754806033738, 5459.235707001023, 5461.890762184734, 5465.562754979592, 4923.606111511142, 4937.185364414831, 4971.229459433794, 4986.7257300848805, 5005.724685142352, 3817.10298079951, 3827.349350610403, 3868.845246190121, 3913.3347330953807, 3929.430232616312, 1250.756102559992, 1259.3010878962082, 1260.4580030586935, 1272.535524533557, 1277.2719549856492, 897.2059727141527, 957.2055194188806, 961.2886464016577, 962.831504486712, 981.2280308129563, 5950.375210602824, 6060.909086363708, 6117.113018612276, 6153.476954458921, 6173.0, 405.9391091336323, 413.7517489506272, 425.6424603333215, 431.66977262394295, 432.2069003378835, 3718.1448266898224, 3817.6945844332386, 3818.0, 3863.6522712955407, 3866.149447121685, 10725.892741072645, 10984.231103822656, 11017.669469915916, 11146.888531114586, 11148.05241554477, 11079.667609971713, 11092.611758588411, 11097.667069987914, 11109.722256943642, 11113.944430277797, 8095.676172953134, 8308.0, 8442.197991190827, 8459.577021149042, 8469.026062002826, 6018.909716354346, 6039.0, 6058.878822423439, 6091.817245482643, 6151.108089327112, 10083.848742269018, 10482.0, 10482.37105773656, 10487.161027117965, 10499.895001050198, 10207.183425325808, 10602.310849794738, 10611.628592999517, 10620.734481638021, 10641.574337026432, 7506.662200201114, 7518.097828260644, 7612.086549614081, 7613.467057305936, 7619.542827430372, 8269.751907442545, 8527.403081784463, 8561.10108438605, 8563.357748169037, 8565.828683426173, 7554.2823431773895, 7615.352695020985, 7693.230676932486, 7819.335356494762, 7849.843003140019, 7485.88771168422, 7494.288042635941, 7579.0905091387185, 7637.0, 7739.032620922385, 7158.355747982707, 7264.091988501437, 7268.200497945116, 7268.563886166847, 7269.3094156055095, 4827.324174615486, 4913.557779799835, 4963.205887058053, 4976.427710813303, 5072.442031376472, 5115.590752739847, 5159.613029022913, 5160.354955630546, 5379.838604841861, 5412.404635490172], "value": 4582.116288278099, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.5278921752153, 306.1351681499764, 310.3467201540757, 321.15215830208257, 325.71662653491467, 4146.751194928306, 5244.89510209796, 5369.62412631116, 5382.842688821903, 5425.29471168748, 5177.352830896138, 5266.52601265886, 5307.628466007372, 5311.867203319919, 5337.70642614656, 4063.471748672683, 4094.549599544048, 4116.97941510292, 4124.443200167969, 4130.339145736682, 3850.2107068051123, 3875.689944804423, 4014.7189696721243, 4055.634992850643, 4057.4928133983253, 3539.557555305587, 3945.7768091891608, 4082.265192265391, 4108.322126849066, 4119.8970025749295, 3132.9974408189387, 4077.694172937012, 4091.672666186713, 4098.159877225162, 4106.691998100155, 3128.937421251585, 3511.719062474996, 3612.422012477991, 3622.710183185365, 3644.034330902313, 5095.821646242401, 5165.811863271469, 5171.663841850268, 5175.01674681813, 5182.196759502283, 4702.153612349775, 4710.31700403443, 4718.363020992157, 4718.8584342469785, 4718.961828397747, 4809.495003024702, 4998.500149985009, 5535.892821435729, 5591.27313449249, 5654.095344744821, 3914.941275880864, 3928.9214215715524, 3934.193490334503, 3943.64507194351, 3968.8412463501427, 5173.793048278064, 5927.903337882505, 5931.169636250928, 5943.465088142045, 5950.107483877453, 3571.535700358989, 3661.5789184244154, 4031.858884939013, 4037.6164264394833, 4053.0, 3266.281418088017, 3349.43059679855, 3474.3398754236614, 4009.6591789698205, 4029.1337362466625, 4139.77231252278, 4443.800028998703, 4562.840300589463, 4601.493835678073, 4690.226112691368, 5198.792048318025, 5435.456454354612, 5698.914516282259, 5699.349802341931, 5725.713714314267, 1255.3785875991443, 1303.322272418356, 1343.630501612045, 1385.088577762701, 1386.4731402067353, 861.7673228228343, 882.4308321132962, 918.3571499950061, 920.9033051529627, 928.3826255540125, 4595.517470665532, 4964.212883361987, 5256.842294731164, 5270.262163297141, 5286.101362768339, 4887.3890763654545, 5289.656172348785, 5410.91883621746, 5713.057345538023, 5789.623674461231, 5039.966806804597, 5226.45122262157, 5292.4707529247535, 5418.566514678895, 5473.507384335429, 3028.0, 3042.9653917668097, 3043.46739320618, 3048.4055609156003, 3075.5232938894505, 5234.534330387498, 5763.682997435098, 5781.248437703071, 5792.13289421739, 5852.326982397079, 5224.0, 5862.970685146547, 5959.46364827159, 5985.551083668743, 5996.730147143389, 5323.840284791462, 5745.2818397700285, 5976.760929562855, 5981.342052374235, 6028.4272994065495, 5155.9742201288755, 5287.206918962186, 5414.8323230910355, 5511.0, 5841.283803031611, 4700.694454860423, 5627.859303517281, 5662.518685911744, 5715.742791574306, 5716.742746576498, 5057.924131137962, 5476.561875050066, 5976.057781221034, 6045.879082418415, 6057.061155520902, 3622.9493446900105, 3925.509311336083, 3972.2323566012847, 3979.900502487461, 4004.2592120457807, 5381.381141168659, 5402.946425447, 5638.661680299195, 5797.449242321973, 5866.829273168954, 4705.247160454311, 4737.265723812815, 5440.945590544202, 5693.572982026448, 5701.918799638185, 5656.60403771732, 5659.6321239119325, 5674.375818659862, 5682.687452190169, 5690.487856092972, 4106.938395924004, 5349.331333583302, 5371.382291036581, 5522.3649280333275, 5641.210230567642, 4505.414296141481, 5659.151127330933, 5664.745086471201, 5703.62926409782, 5718.0, 5400.297961265011, 5474.91787623178, 5538.252335934597, 5570.5543556516195, 5581.7209139543675], "value": 4119.782370860842, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.2567432567432, 752.2928447259576, 775.2402645407501, 798.5807451088182, 903.4172958441806, 14554.781678274821, 15740.212989350544, 15744.606384840356, 15772.763408548866, 15774.4478943237, 12828.0, 13209.0, 13254.403551840167, 13281.402336894838, 13308.134971226864, 691.6023286610207, 901.8827552418177, 904.995475022624, 909.0, 910.4673765846987, 881.8324518341494, 897.0043251990286, 897.6633762339122, 899.3749344205779, 903.7695387676166, 947.2611363136764, 948.1514044929784, 948.9478078705669, 949.2643201518844, 951.219999600328, 936.5036530638704, 940.1679513630418, 940.1867384712243, 940.3041749105647, 941.3457646935383, 711.4415184080472, 711.7153138744492, 712.2343480758205, 713.8429545499981, 715.0632671200692, 11134.832977505344, 12999.610011699664, 12999.805002924964, 13021.088523803337, 13035.957123430102, 11938.0, 12063.457144428521, 12181.878181218251, 12191.45138468771, 12318.0, 9068.682596109105, 11050.171237157245, 11066.612668556643, 11069.114470842313, 11446.885531144748, 3697.8335974881193, 3793.4499497572974, 3804.5624753153465, 3810.9618903810883, 3814.4278358246206, 112530.87469125286, 114786.0, 115230.0, 115292.0, 115678.42160789226, 9286.95356523213, 9303.53482325881, 9344.719658410258, 9477.90522094784, 9697.612095516242, 19635.410937671608, 19780.70328945067, 19783.703244451346, 19831.900840495706, 19835.30576429818, 8175.7138500152205, 8244.958775206205, 8245.79385515367, 8262.42163048581, 8481.872771908304, 4216.7259128156575, 4311.223979683625, 4374.081442896963, 4605.148047611203, 4716.1510928033285, 3489.7382696297877, 3611.638836116367, 3618.6019537851344, 3622.5109610202285, 3633.3096711624707, 662.9834254143685, 667.5460686732971, 674.8380388706676, 685.1127789512593, 685.3557655803529, 612.3937302071007, 633.9841503962438, 636.9395155489683, 685.2051620120736, 697.4072038767007, 9159.587818548182, 9280.7679808004, 9308.929473110544, 9523.619055237712, 9637.469939153274, 514.6500379741811, 548.9945100548944, 562.0557463461424, 612.8161551534522, 653.7646447279071, 4615.076984603093, 4616.492185859553, 4629.490756016836, 4666.65000124992, 4728.668993170514, 114033.0, 147097.26451367675, 147390.0, 149871.0, 152954.0, 131547.34226329, 131589.0, 132542.33728831293, 132585.0, 135265.0, 73366.63316683382, 108413.0, 113808.0, 114857.4257128709, 115776.90348538673, 8644.827103458021, 8875.378723489552, 8973.955130224307, 8998.59506322204, 9061.818763624822, 59064.818703626544, 62644.30167737347, 65752.0, 66031.67936641144, 67927.98108028284, 94915.52542237245, 98800.50599746955, 98831.0, 99589.0, 101074.0, 11843.763124737285, 12013.0, 12036.518539258332, 12174.14780965324, 12592.622221333375, 11544.711382215512, 12447.377631118588, 12585.685357865761, 12974.740505189655, 13100.868991309964, 12054.698632534257, 12063.21589096706, 12086.39568021613, 12101.273923564615, 12130.818037729618, 12035.75928481443, 12079.214851034654, 12172.513099476277, 12787.61637150887, 12860.614181574567, 11850.52597896109, 11980.76038479243, 12020.278783273032, 12071.456784444545, 12192.451339689558, 8515.829683406173, 8879.0, 10186.694399168036, 10347.379157250589, 10357.430341330997, 9946.678526988027, 10138.28086998794, 10219.0, 10230.590776368861, 10282.588696452358], "value": 7585.326888732221, "unit": "score"}], "name": "dromaeo_css", "value": 7304.578332804914, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 96.0, 128.0, 195.0, 133.0, 131.0, 105.0, 126.0, 141.0, 106.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 184.0, 184.0, 184.0, 182.0, 182.0, 183.0, 184.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 108.0, 58.0, 56.0, 60.0, 69.0, 82.0, 56.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 93.0, 71.0, 76.0, 68.0, 95.0, 73.0, 67.0, 101.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 131.0, 99.0, 85.0, 87.0, 85.0, 90.0, 84.0, 87.0, 83.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 93.0, 92.0, 93.0, 94.0, 93.0, 93.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 88.0, 89.0, 88.0, 89.0, 91.0, 89.0, 86.0], "value": 88.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 73.0, 74.0, 73.0, 71.0, 70.0, 69.0, 70.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 83.0, 81.0, 94.0, 101.0, 98.0, 87.0, 83.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 139.0, 143.0, 128.0, 134.0, 136.0, 136.0, 177.0, 137.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 172.0, 213.0, 165.0, 163.0, 163.0, 163.0, 215.0, 221.0, 176.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 69.0, 83.0, 82.0, 82.0, 84.0, 85.0], "value": 75.3, "unit": "score"}], "name": "kraken", "value": 1466.3, "alertThreshold": 2.0}]} 20:53:37 INFO - Return code: 0 20:53:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:53:37 INFO - # TBPL SUCCESS # 20:53:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:53:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:53:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:53:37 INFO - 2016-07-13 20:53:37.707 screenresolution[1667:9596] starting screenresolution argv=screenresolution get 20:53:37 INFO - 2016-07-13 20:53:37.709 screenresolution[1667:9596] Display 0: 1600x1200x32@60 20:53:37 INFO - 2016-07-13 20:53:37.715 screenresolution[1668:9599] starting screenresolution argv=screenresolution list 20:53:37 INFO - Available Modes on Display 0 20:53:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:53:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:53:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:53:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:53:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:53:37 INFO - Intel Iris: 20:53:37 INFO - Chipset Model: Intel Iris 20:53:37 INFO - Type: GPU 20:53:37 INFO - Bus: Built-In 20:53:37 INFO - VRAM (Dynamic, Max): 1536 MB 20:53:37 INFO - Vendor: Intel (0x8086) 20:53:37 INFO - Device ID: 0x0a2e 20:53:37 INFO - Revision ID: 0x0009 20:53:37 INFO - Displays: 20:53:37 INFO - Display: 20:53:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:53:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:53:37 INFO - Display Serial Number: 1600x1200 60 20:53:37 INFO - Main Display: Yes 20:53:37 INFO - Mirror: Off 20:53:37 INFO - Online: Yes 20:53:37 INFO - Rotation: Supported 20:53:37 INFO - Return code: 0 20:53:37 INFO - Running post-action listener: _resource_record_post_action 20:53:37 INFO - ##### 20:53:37 INFO - ##### Finished run-tests step (success) 20:53:37 INFO - ##### 20:53:37 INFO - Running post-run listener: _resource_record_post_run 20:53:38 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 31645184; Write bytes: 704051712; Read time: 194; Write time: 1966 20:53:38 INFO - TinderboxPrint: CPU usage
53.5% 20:53:38 INFO - TinderboxPrint: I/O read bytes / time
31,645,184 / 194 20:53:38 INFO - TinderboxPrint: I/O write bytes / time
704,051,712 / 1,966 20:53:38 INFO - TinderboxPrint: CPU idle
2,248.1 (46.5%) 20:53:38 INFO - TinderboxPrint: CPU system
967.8 (20.0%) 20:53:38 INFO - TinderboxPrint: CPU user
1,621.8 (33.5%) 20:53:38 INFO - TinderboxPrint: Swap in / out
434,712,576 / 0 20:53:38 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392575488; Write bytes: 386144256; Read time: 29569; Write time: 846 20:53:38 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30719488; Write bytes: 294838784; Read time: 182; Write time: 1064 20:53:38 INFO - Running post-run listener: _upload_blobber_files 20:53:38 INFO - Blob upload gear active. 20:53:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:53:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:53:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:53:38 INFO - (blobuploader) - INFO - Open directory for files ... 20:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:53:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:53:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:53:39 INFO - (blobuploader) - INFO - Done attempting. 20:53:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:53:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:53:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:53:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:53:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:53:40 INFO - (blobuploader) - INFO - Done attempting. 20:53:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:53:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:53:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:53:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:53:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:53:43 INFO - (blobuploader) - INFO - Done attempting. 20:53:43 INFO - (blobuploader) - INFO - Iteration through files over. 20:53:43 INFO - Return code: 0 20:53:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:53:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:53:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/65cce9dc1d7ef59a27e0990a97152473ae16abea79106a2eb8a1e5eb5df670e06caa34ea09b8509f4ef9973efeac08bf63fb1e6da3f5df49fd264e2fb06063d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ec8f07ccaaa1906af8cc027c312581a06f6a269b8875f2e0a49149bca75c2181eff67998b54da260b21f5919b4a1d00c0d69faebdfeb9deaa4d19c83d1e7bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24366e310022dfd036a82d14e0f5de2dc4d999ebb3cbcd04a50830c720d8603621f1445f48333d8bef54bb5ab5e91c6197a321d2dd845720043f624f5945fe38"} 20:53:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:53:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:53:43 INFO - Contents: 20:53:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/65cce9dc1d7ef59a27e0990a97152473ae16abea79106a2eb8a1e5eb5df670e06caa34ea09b8509f4ef9973efeac08bf63fb1e6da3f5df49fd264e2fb06063d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ec8f07ccaaa1906af8cc027c312581a06f6a269b8875f2e0a49149bca75c2181eff67998b54da260b21f5919b4a1d00c0d69faebdfeb9deaa4d19c83d1e7bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24366e310022dfd036a82d14e0f5de2dc4d999ebb3cbcd04a50830c720d8603621f1445f48333d8bef54bb5ab5e91c6197a321d2dd845720043f624f5945fe38"} 20:53:43 INFO - Running post-run listener: copy_logs_to_upload_dir 20:53:43 INFO - Copying logs to upload dir... 20:53:43 INFO - mkdir: /builds/slave/test/build/upload/logs 20:53:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1347.964328 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 28 secs) (at 2016-07-13 20:53:43.113219) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 20:53:43.116472) ========= 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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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/Autoland/sha512/65cce9dc1d7ef59a27e0990a97152473ae16abea79106a2eb8a1e5eb5df670e06caa34ea09b8509f4ef9973efeac08bf63fb1e6da3f5df49fd264e2fb06063d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ec8f07ccaaa1906af8cc027c312581a06f6a269b8875f2e0a49149bca75c2181eff67998b54da260b21f5919b4a1d00c0d69faebdfeb9deaa4d19c83d1e7bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24366e310022dfd036a82d14e0f5de2dc4d999ebb3cbcd04a50830c720d8603621f1445f48333d8bef54bb5ab5e91c6197a321d2dd845720043f624f5945fe38"} build_url:https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010802 build_url: 'https://queue.taskcluster.net/v1/task/X0bI-M-ZTCujqfm986TUEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/65cce9dc1d7ef59a27e0990a97152473ae16abea79106a2eb8a1e5eb5df670e06caa34ea09b8509f4ef9973efeac08bf63fb1e6da3f5df49fd264e2fb06063d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ec8f07ccaaa1906af8cc027c312581a06f6a269b8875f2e0a49149bca75c2181eff67998b54da260b21f5919b4a1d00c0d69faebdfeb9deaa4d19c83d1e7bf0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/24366e310022dfd036a82d14e0f5de2dc4d999ebb3cbcd04a50830c720d8603621f1445f48333d8bef54bb5ab5e91c6197a321d2dd845720043f624f5945fe38"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 20:53:43.148309) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 20:53:43.148619) ========= 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.8eJeDwgX9r/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.u4hSqjNOFw/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 20:53:43.197060) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 20:53:43.197392) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 20:53:43.197727) ========= ========= Total master_lag: 0.23 =========