builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0137 starttime: 1471985021.01 results: success (0) buildid: 20160823103647 builduid: 50d7bc2eabcd4906a2e5a33581301de0 revision: 7214320961eda063ecef1be314c1c4da9b949dbe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.006191) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.006712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.007031) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.030917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.031244) ========= 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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-23 13:43:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.09M=0.001s 2016-08-23 13:43:41 (8.09 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105590 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.157216) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.157576) ========= 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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.020915 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:41.206678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 13:43:41.207080) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 7214320961eda063ecef1be314c1c4da9b949dbe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 7214320961eda063ecef1be314c1c4da9b949dbe --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-23 13:43:41,274 truncating revision to first 12 chars 2016-08-23 13:43:41,274 Setting DEBUG logging. 2016-08-23 13:43:41,274 attempt 1/10 2016-08-23 13:43:41,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/7214320961ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 13:43:42,475 unpacking tar archive at: larch-7214320961ed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 13:43:42.688793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:42.689167) ========= script_repo_revision: 7214320961eda063ecef1be314c1c4da9b949dbe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:42.689612) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:42.689921) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 13:43:42.708544) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-08-23 13:43:42.708996) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:43:42 INFO - MultiFileLogger online at 20160823 13:43:42 in /builds/slave/test 13:43:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 13:43:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:43:42 INFO - {'append_to_log': False, 13:43:42 INFO - 'base_work_dir': '/builds/slave/test', 13:43:42 INFO - 'blob_upload_branch': 'Larch', 13:43:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:43:42 INFO - 'branch': 'Larch', 13:43:42 INFO - 'buildbot_json_path': 'buildprops.json', 13:43:42 INFO - 'config_files': ('talos/mac_config.py',), 13:43:42 INFO - 'default_actions': ('clobber', 13:43:42 INFO - 'read-buildbot-config', 13:43:42 INFO - 'download-and-extract', 13:43:42 INFO - 'populate-webroot', 13:43:42 INFO - 'create-virtualenv', 13:43:42 INFO - 'install', 13:43:42 INFO - 'run-tests'), 13:43:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:43:42 INFO - 'download_minidump_stackwalk': True, 13:43:42 INFO - 'download_symbols': 'ondemand', 13:43:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:43:42 INFO - 'tooltool.py': '/tools/tooltool.py', 13:43:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:43:42 INFO - '/tools/misc-python/virtualenv.py')}, 13:43:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:43:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:43:42 INFO - 'installer_path': 'installer.exe', 13:43:42 INFO - 'log_level': 'info', 13:43:42 INFO - 'log_name': 'talos', 13:43:42 INFO - 'log_to_console': True, 13:43:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:43:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:43:42 INFO - 'opt_config_files': (), 13:43:42 INFO - 'pip_index': False, 13:43:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:43:42 INFO - 'cmd': ('bash', 13:43:42 INFO - '-c', 13:43:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:43:42 INFO - 'enabled': True, 13:43:42 INFO - 'halt_on_failure': False, 13:43:42 INFO - 'name': 'check_screen_resolution'},), 13:43:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:43:42 INFO - 'cmd': ('bash', 13:43:42 INFO - '-c', 13:43:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:43:42 INFO - 'enabled': True, 13:43:42 INFO - 'halt_on_failure': False, 13:43:42 INFO - 'name': 'check_screen_resolution'},), 13:43:42 INFO - 'run_cmd_checks_enabled': True, 13:43:42 INFO - 'sps_profile': False, 13:43:42 INFO - 'sps_profile_interval': 0, 13:43:42 INFO - 'suite': 'dromaeojs', 13:43:42 INFO - 'system_bits': '32', 13:43:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:43:42 INFO - 'title': 't-yosemite-r7-0137', 13:43:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:43:42 INFO - 'use_talos_json': True, 13:43:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:43:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:43:42 INFO - 'work_dir': 'build'} 13:43:42 INFO - [mozharness: 2016-08-23 20:43:42.896611Z] Running clobber step. 13:43:42 INFO - Running pre-action listener: _resource_record_pre_action 13:43:42 INFO - Running main action method: clobber 13:43:42 INFO - rmtree: /builds/slave/test/build 13:43:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:43:44 INFO - Running post-action listener: _resource_record_post_action 13:43:44 INFO - [mozharness: 2016-08-23 20:43:44.353079Z] Finished clobber step (success) 13:43:44 INFO - [mozharness: 2016-08-23 20:43:44.353250Z] Running read-buildbot-config step. 13:43:44 INFO - Running pre-action listener: _resource_record_pre_action 13:43:44 INFO - Running main action method: read_buildbot_config 13:43:44 INFO - Using buildbot properties: 13:43:44 INFO - { 13:43:44 INFO - "project": "", 13:43:44 INFO - "product": "firefox", 13:43:44 INFO - "script_repo_revision": "production", 13:43:44 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 13:43:44 INFO - "repository": "", 13:43:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 13:43:44 INFO - "buildid": "20160823103647", 13:43:44 INFO - "pgo_build": "False", 13:43:44 INFO - "basedir": "/builds/slave/test", 13:43:44 INFO - "buildnumber": 0, 13:43:44 INFO - "slavename": "t-yosemite-r7-0137", 13:43:44 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:43:44 INFO - "platform": "yosemite_r7", 13:43:44 INFO - "scheduler": "tests-larch-macosx64-talos", 13:43:44 INFO - "branch": "larch", 13:43:44 INFO - "repo_path": "projects/larch", 13:43:44 INFO - "revision": "7214320961eda063ecef1be314c1c4da9b949dbe", 13:43:44 INFO - "stage_platform": "macosx64", 13:43:44 INFO - "builduid": "50d7bc2eabcd4906a2e5a33581301de0", 13:43:44 INFO - "slavebuilddir": "test" 13:43:44 INFO - } 13:43:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 13:43:44 INFO - Running post-action listener: _resource_record_post_action 13:43:44 INFO - [mozharness: 2016-08-23 20:43:44.356740Z] Finished read-buildbot-config step (success) 13:43:44 INFO - [mozharness: 2016-08-23 20:43:44.356850Z] Running download-and-extract step. 13:43:44 INFO - Running pre-action listener: _resource_record_pre_action 13:43:44 INFO - Running main action method: download_and_extract 13:43:44 INFO - mkdir: /builds/slave/test/build/tests 13:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:44 INFO - trying https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 13:43:44 INFO - Downloading https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:43:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 13:43:45 INFO - Downloaded 1199 bytes. 13:43:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:43:45 INFO - Using the following test package requirements: 13:43:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:43:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 13:43:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 13:43:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'jsshell-mac.zip'], 13:43:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 13:43:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:43:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 13:43:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 13:43:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 13:43:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:43:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 13:43:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:43: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:45 INFO - trying https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:43:45 INFO - Downloading https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:43:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:43:47 INFO - Downloaded 16352370 bytes. 13:43:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:43:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:43:54 INFO - trying https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:43:54 INFO - Downloading https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:43:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:43:55 INFO - Downloaded 16352370 bytes. 13:43:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:02 INFO - trying https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:44:02 INFO - Downloading https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:44:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:44:03 INFO - Downloaded 14413981 bytes. 13:44:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 13:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:04 INFO - trying https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:44:04 INFO - Downloading https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 13:44:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 13:44:08 INFO - Downloaded 111542362 bytes. 13:44:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:44:08 INFO - mkdir: /builds/slave/test/properties 13:44:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:44:08 INFO - Writing to file /builds/slave/test/properties/build_url 13:44:08 INFO - Contents: 13:44:08 INFO - build_url:https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:44:09 INFO - Running post-action listener: _resource_record_post_action 13:44:09 INFO - Running post-action listener: set_extra_try_arguments 13:44:09 INFO - [mozharness: 2016-08-23 20:44:09.357030Z] Finished download-and-extract step (success) 13:44:09 INFO - [mozharness: 2016-08-23 20:44:09.357177Z] Running populate-webroot step. 13:44:09 INFO - Running pre-action listener: _resource_record_pre_action 13:44:09 INFO - Running main action method: populate_webroot 13:44:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:44:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:44:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:44:09 INFO - '--tppagecycles', 13:44:09 INFO - '7'], 13:44:09 INFO - 'tests': ['tsvgm']}}, 13:44:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:44:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:44:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:44:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:44:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:44:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:44:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['damp', 'tps']}, 13:44:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'tests': ['damp', 'tps']}, 13:44:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['dromaeo_dom']}, 13:44:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:44:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['basic_compositor_video']}, 13:44:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:44:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['a11yr', 13:44:09 INFO - 'ts_paint', 13:44:09 INFO - 'tpaint', 13:44:09 INFO - 'sessionrestore', 13:44:09 INFO - 'sessionrestore_no_auto_restore', 13:44:09 INFO - 'tabpaint']}, 13:44:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:44:09 INFO - 'ts_paint', 13:44:09 INFO - 'tpaint', 13:44:09 INFO - 'sessionrestore', 13:44:09 INFO - 'sessionrestore_no_auto_restore', 13:44:09 INFO - 'tabpaint']}, 13:44:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:44:09 INFO - 'ts_paint', 13:44:09 INFO - 'tpaint', 13:44:09 INFO - 'sessionrestore', 13:44:09 INFO - 'sessionrestore_no_auto_restore', 13:44:09 INFO - 'tabpaint']}, 13:44:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:44:09 INFO - 'ts_paint', 13:44:09 INFO - 'tpaint', 13:44:09 INFO - 'sessionrestore', 13:44:09 INFO - 'sessionrestore_no_auto_restore', 13:44:09 INFO - 'tabpaint']}, 13:44:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['a11yr', 13:44:09 INFO - 'ts_paint', 13:44:09 INFO - 'tpaint', 13:44:09 INFO - 'sessionrestore', 13:44:09 INFO - 'sessionrestore_no_auto_restore', 13:44:09 INFO - 'tabpaint']}, 13:44:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['a11yr', 13:44:09 INFO - 'ts_paint', 13:44:09 INFO - 'tpaint', 13:44:09 INFO - 'sessionrestore', 13:44:09 INFO - 'sessionrestore_no_auto_restore', 13:44:09 INFO - 'tabpaint']}, 13:44:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['tsvgx', 13:44:09 INFO - 'tsvgr_opacity', 13:44:09 INFO - 'tart', 13:44:09 INFO - 'tscrollx', 13:44:09 INFO - 'cart']}, 13:44:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:44:09 INFO - 'tsvgr_opacity', 13:44:09 INFO - 'tart', 13:44:09 INFO - 'tscrollx', 13:44:09 INFO - 'cart']}, 13:44:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'talos_options': ['--disable-e10s'], 13:44:09 INFO - 'tests': ['tp5o']}, 13:44:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'tests': ['tp5o']}, 13:44:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'talos_options': ['--disable-e10s', 13:44:09 INFO - '--xperf_path', 13:44:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:44:09 INFO - 'tests': ['tp5n']}, 13:44:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:44:09 INFO - 'talos_options': ['--xperf_path', 13:44:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:44:09 INFO - 'tests': ['tp5n']}}, 13:44:09 INFO - 'talos.zip': {'path': '', 13:44:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:44:09 INFO - Running post-action listener: _resource_record_post_action 13:44:09 INFO - [mozharness: 2016-08-23 20:44:09.376044Z] Finished populate-webroot step (success) 13:44:09 INFO - [mozharness: 2016-08-23 20:44:09.376177Z] Running create-virtualenv step. 13:44:09 INFO - Running pre-action listener: _resource_record_pre_action 13:44:09 INFO - Running main action method: create_virtualenv 13:44:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:44:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:44:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:44:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:44:09 INFO - Using real prefix '/tools/python27' 13:44:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:44:10 INFO - Installing distribute.............................................................................................................................................................................................done. 13:44:13 INFO - Installing pip.................done. 13:44:13 INFO - Return code: 0 13:44:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:44:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:44:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:13 INFO - 'HOME': '/Users/cltbld', 13:44:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:13 INFO - 'LOGNAME': 'cltbld', 13:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:13 INFO - 'MOZ_NO_REMOTE': '1', 13:44:13 INFO - 'NO_EM_RESTART': '1', 13:44:13 INFO - 'PAGER': '/bin/cat', 13:44:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:13 INFO - 'PWD': '/builds/slave/test', 13:44:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:13 INFO - 'SHELL': '/bin/bash', 13:44:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:13 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:13 INFO - 'USER': 'cltbld', 13:44:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:13 INFO - 'XPC_FLAGS': '0x0', 13:44:13 INFO - 'XPC_SERVICE_NAME': '0', 13:44:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:44:13 INFO - Cleaning up... 13:44:13 INFO - Return code: 0 13:44:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:44:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:44:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:13 INFO - 'HOME': '/Users/cltbld', 13:44:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:13 INFO - 'LOGNAME': 'cltbld', 13:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:13 INFO - 'MOZ_NO_REMOTE': '1', 13:44:13 INFO - 'NO_EM_RESTART': '1', 13:44:13 INFO - 'PAGER': '/bin/cat', 13:44:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:13 INFO - 'PWD': '/builds/slave/test', 13:44:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:13 INFO - 'SHELL': '/bin/bash', 13:44:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:13 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:13 INFO - 'USER': 'cltbld', 13:44:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:13 INFO - 'XPC_FLAGS': '0x0', 13:44:13 INFO - 'XPC_SERVICE_NAME': '0', 13:44:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:13 INFO - Downloading/unpacking psutil>=3.1.1 13:44: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 13:44: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 13:44: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 13:44: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 13:44: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 13:44: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 13:44:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:44:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:44:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:44:17 INFO - Installing collected packages: psutil 13:44:17 INFO - Running setup.py install for psutil 13:44:17 INFO - building 'psutil._psutil_osx' extension 13:44:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:44:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:44:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:44:17 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 13:44:17 INFO - building 'psutil._psutil_posix' extension 13:44:17 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 13:44:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:44:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:44:17 INFO - ^ 13:44:17 INFO - 1 warning generated. 13:44:17 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 13:44:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:44:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:44:17 INFO - Successfully installed psutil 13:44:17 INFO - Cleaning up... 13:44:17 INFO - Return code: 0 13:44:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:44: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:17 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:17 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 13:44:17 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 13:44:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:17 INFO - 'HOME': '/Users/cltbld', 13:44:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:17 INFO - 'LOGNAME': 'cltbld', 13:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:17 INFO - 'MOZ_NO_REMOTE': '1', 13:44:17 INFO - 'NO_EM_RESTART': '1', 13:44:17 INFO - 'PAGER': '/bin/cat', 13:44:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:17 INFO - 'PWD': '/builds/slave/test', 13:44:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:17 INFO - 'SHELL': '/bin/bash', 13:44:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:17 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:17 INFO - 'USER': 'cltbld', 13:44:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:17 INFO - 'XPC_FLAGS': '0x0', 13:44:17 INFO - 'XPC_SERVICE_NAME': '0', 13:44:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:44:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:44:20 INFO - Installing collected packages: mozsystemmonitor 13:44:20 INFO - Running setup.py install for mozsystemmonitor 13:44:20 INFO - Successfully installed mozsystemmonitor 13:44:20 INFO - Cleaning up... 13:44:20 INFO - Return code: 0 13:44:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:20 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:20 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 13:44:20 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 13:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:20 INFO - 'HOME': '/Users/cltbld', 13:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:20 INFO - 'LOGNAME': 'cltbld', 13:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:20 INFO - 'MOZ_NO_REMOTE': '1', 13:44:20 INFO - 'NO_EM_RESTART': '1', 13:44:20 INFO - 'PAGER': '/bin/cat', 13:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:20 INFO - 'PWD': '/builds/slave/test', 13:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:20 INFO - 'SHELL': '/bin/bash', 13:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:20 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:20 INFO - 'USER': 'cltbld', 13:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:20 INFO - 'XPC_FLAGS': '0x0', 13:44:20 INFO - 'XPC_SERVICE_NAME': '0', 13:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:21 INFO - Downloading/unpacking jsonschema==2.5.1 13:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:44:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:24 INFO - Downloading functools32-3.2.3-2.tar.gz 13:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:44:24 INFO - warning: no files found matching '*.txt' 13:44:24 INFO - no previously-included directories found matching 'build' 13:44:24 INFO - no previously-included directories found matching 'dist' 13:44:24 INFO - no previously-included directories found matching '.git*' 13:44:24 INFO - Installing collected packages: jsonschema, functools32 13:44:24 INFO - Running setup.py install for functools32 13:44:24 INFO - warning: no files found matching '*.txt' 13:44:24 INFO - no previously-included directories found matching 'build' 13:44:24 INFO - no previously-included directories found matching 'dist' 13:44:24 INFO - no previously-included directories found matching '.git*' 13:44:24 INFO - Successfully installed jsonschema functools32 13:44:24 INFO - Cleaning up... 13:44:24 INFO - Return code: 0 13:44:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:24 INFO - 'HOME': '/Users/cltbld', 13:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:24 INFO - 'LOGNAME': 'cltbld', 13:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:24 INFO - 'MOZ_NO_REMOTE': '1', 13:44:24 INFO - 'NO_EM_RESTART': '1', 13:44:24 INFO - 'PAGER': '/bin/cat', 13:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:24 INFO - 'PWD': '/builds/slave/test', 13:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:24 INFO - 'SHELL': '/bin/bash', 13:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:24 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:24 INFO - 'USER': 'cltbld', 13:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:24 INFO - 'XPC_FLAGS': '0x0', 13:44:24 INFO - 'XPC_SERVICE_NAME': '0', 13:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:44:24 INFO - Cleaning up... 13:44:24 INFO - Return code: 0 13:44:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:24 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:24 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 13:44:24 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 13:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:24 INFO - 'HOME': '/Users/cltbld', 13:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:24 INFO - 'LOGNAME': 'cltbld', 13:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:24 INFO - 'MOZ_NO_REMOTE': '1', 13:44:24 INFO - 'NO_EM_RESTART': '1', 13:44:24 INFO - 'PAGER': '/bin/cat', 13:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:24 INFO - 'PWD': '/builds/slave/test', 13:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:24 INFO - 'SHELL': '/bin/bash', 13:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:24 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:24 INFO - 'USER': 'cltbld', 13:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:24 INFO - 'XPC_FLAGS': '0x0', 13:44:24 INFO - 'XPC_SERVICE_NAME': '0', 13:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:24 INFO - Downloading/unpacking blobuploader==1.2.4 13:44: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 13:44: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 13:44: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 13:44: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 13:44: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 13:44: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 13:44:27 INFO - Downloading blobuploader-1.2.4.tar.gz 13:44:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:44:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:44:27 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 13:44:27 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 13:44:27 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 13:44:27 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 13:44:27 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 13:44:27 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 13:44:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:44:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:28 INFO - Downloading docopt-0.6.1.tar.gz 13:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:44:28 INFO - Installing collected packages: blobuploader, requests, docopt 13:44:28 INFO - Running setup.py install for blobuploader 13:44:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:44:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:44:28 INFO - Running setup.py install for requests 13:44:29 INFO - Running setup.py install for docopt 13:44:29 INFO - Successfully installed blobuploader requests docopt 13:44:29 INFO - Cleaning up... 13:44:29 INFO - Return code: 0 13:44:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:29 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:29 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 13:44:29 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 13:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:29 INFO - 'HOME': '/Users/cltbld', 13:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:29 INFO - 'LOGNAME': 'cltbld', 13:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:29 INFO - 'MOZ_NO_REMOTE': '1', 13:44:29 INFO - 'NO_EM_RESTART': '1', 13:44:29 INFO - 'PAGER': '/bin/cat', 13:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:29 INFO - 'PWD': '/builds/slave/test', 13:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:29 INFO - 'SHELL': '/bin/bash', 13:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:29 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:29 INFO - 'USER': 'cltbld', 13:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:29 INFO - 'XPC_FLAGS': '0x0', 13:44:29 INFO - 'XPC_SERVICE_NAME': '0', 13:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:44:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-JnoHGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:44:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-GkQYeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:44:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-_klTpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-hP2yjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-DyKFsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-78AEsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-GIXdXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-qaTfEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-eUeW8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-mwBdl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-C2ENE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kWhDf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:44:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-PIDS5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:44:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-9VYfCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ClPPkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:44:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Jxb1a1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:44:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-8EjwVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:44:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:44:31 INFO - Running setup.py install for manifestparser 13:44:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:44:31 INFO - Running setup.py install for mozcrash 13:44:31 INFO - Running setup.py install for mozdebug 13:44:31 INFO - Running setup.py install for mozdevice 13:44:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Running setup.py install for mozfile 13:44:32 INFO - Running setup.py install for mozhttpd 13:44:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Running setup.py install for mozinfo 13:44:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Running setup.py install for mozInstall 13:44:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Running setup.py install for mozleak 13:44:32 INFO - Running setup.py install for mozlog 13:44:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:44:32 INFO - Running setup.py install for moznetwork 13:44:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:44:33 INFO - Running setup.py install for mozprocess 13:44:33 INFO - Running setup.py install for mozprofile 13:44:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:44:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:44:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:44:33 INFO - Running setup.py install for mozrunner 13:44:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:44:33 INFO - Running setup.py install for mozscreenshot 13:44:33 INFO - Running setup.py install for moztest 13:44:33 INFO - Running setup.py install for mozversion 13:44:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:44:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:44:34 INFO - Cleaning up... 13:44:34 INFO - Return code: 0 13:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:34 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:34 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 13:44:34 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 13:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:34 INFO - 'HOME': '/Users/cltbld', 13:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:34 INFO - 'LOGNAME': 'cltbld', 13:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:34 INFO - 'MOZ_NO_REMOTE': '1', 13:44:34 INFO - 'NO_EM_RESTART': '1', 13:44:34 INFO - 'PAGER': '/bin/cat', 13:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:34 INFO - 'PWD': '/builds/slave/test', 13:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:34 INFO - 'SHELL': '/bin/bash', 13:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:34 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:34 INFO - 'USER': 'cltbld', 13:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:34 INFO - 'XPC_FLAGS': '0x0', 13:44:34 INFO - 'XPC_SERVICE_NAME': '0', 13:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:44:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Ekpsyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:44:34 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)) 13:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:44:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-J2rvTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:44:34 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)) 13:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:44:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-RRAKGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:44:34 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)) 13:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:44:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KA_Vw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:44:34 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)) 13:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:44:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-K50NBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:44:34 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)) 13:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:44:34 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-9aQ6N1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ddEte6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Gpk57_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-u_KREf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-WJ38oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-cV4kBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-cg4LzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-lhKAhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-K7ujNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-WriQ0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:44:35 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)) 13:44:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:44:35 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rSVP0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:44:36 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)) 13:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:44:36 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-EXMpLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:44:36 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)) 13:44:36 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)) 13:44:36 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)) 13:44:36 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)) 13:44:36 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)) 13:44:36 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)) 13:44:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:44:38 INFO - Downloading blessings-1.6.tar.gz 13:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:44:38 INFO - Installing collected packages: blessings 13:44:38 INFO - Running setup.py install for blessings 13:44:38 INFO - Successfully installed blessings 13:44:38 INFO - Cleaning up... 13:44:39 INFO - Return code: 0 13:44:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:44:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:44:39 INFO - Reading from file tmpfile_stdout 13:44:39 INFO - Current package versions: 13:44:39 INFO - blessings == 1.6 13:44:39 INFO - blobuploader == 1.2.4 13:44:39 INFO - docopt == 0.6.1 13:44:39 INFO - functools32 == 3.2.3-2 13:44:39 INFO - jsonschema == 2.5.1 13:44:39 INFO - manifestparser == 1.1 13:44:39 INFO - mozInstall == 1.12 13:44:39 INFO - mozcrash == 0.17 13:44:39 INFO - mozdebug == 0.1 13:44:39 INFO - mozdevice == 0.48 13:44:39 INFO - mozfile == 1.2 13:44:39 INFO - mozhttpd == 0.7 13:44:39 INFO - mozinfo == 0.9 13:44:39 INFO - mozleak == 0.1 13:44:39 INFO - mozlog == 3.3 13:44:39 INFO - moznetwork == 0.27 13:44:39 INFO - mozprocess == 0.23 13:44:39 INFO - mozprofile == 0.28 13:44:39 INFO - mozrunner == 6.12 13:44:39 INFO - mozscreenshot == 0.1 13:44:39 INFO - mozsystemmonitor == 0.3 13:44:39 INFO - moztest == 0.7 13:44:39 INFO - mozversion == 1.4 13:44:39 INFO - psutil == 3.1.1 13:44:39 INFO - requests == 1.2.3 13:44:39 INFO - wsgiref == 0.1.2 13:44:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:39 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:39 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 13:44:39 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 13:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:39 INFO - 'HOME': '/Users/cltbld', 13:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:39 INFO - 'LOGNAME': 'cltbld', 13:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:39 INFO - 'MOZ_NO_REMOTE': '1', 13:44:39 INFO - 'NO_EM_RESTART': '1', 13:44:39 INFO - 'PAGER': '/bin/cat', 13:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:39 INFO - 'PWD': '/builds/slave/test', 13:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:39 INFO - 'SHELL': '/bin/bash', 13:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:39 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:39 INFO - 'USER': 'cltbld', 13:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:39 INFO - 'XPC_FLAGS': '0x0', 13:44:39 INFO - 'XPC_SERVICE_NAME': '0', 13:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 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)) 13:44:39 INFO - Cleaning up... 13:44:39 INFO - Return code: 0 13:44:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:44:39 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 0x10e8c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9501580ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, '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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 13:44:39 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 13:44:39 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 13:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:44:39 INFO - 'HOME': '/Users/cltbld', 13:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:44:39 INFO - 'LOGNAME': 'cltbld', 13:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:44:39 INFO - 'MOZ_NO_REMOTE': '1', 13:44:39 INFO - 'NO_EM_RESTART': '1', 13:44:39 INFO - 'PAGER': '/bin/cat', 13:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:44:39 INFO - 'PWD': '/builds/slave/test', 13:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:44:39 INFO - 'SHELL': '/bin/bash', 13:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:44:39 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:44:39 INFO - 'USER': 'cltbld', 13:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:44:39 INFO - 'XPC_FLAGS': '0x0', 13:44:39 INFO - 'XPC_SERVICE_NAME': '0', 13:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:44:40 INFO - Cleaning up... 13:44:40 INFO - Return code: 0 13:44:40 INFO - Running post-action listener: _resource_record_post_action 13:44:40 INFO - Running post-action listener: _start_resource_monitoring 13:44:40 INFO - Starting resource monitoring. 13:44:40 INFO - [mozharness: 2016-08-23 20:44:40.207014Z] Finished create-virtualenv step (success) 13:44:40 INFO - [mozharness: 2016-08-23 20:44:40.207999Z] Running install step. 13:44:40 INFO - Running pre-action listener: _resource_record_pre_action 13:44:40 INFO - Running main action method: install 13:44:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:44:40 INFO - Reading from file tmpfile_stdout 13:44:40 INFO - Detecting whether we're running mozinstall >=1.0... 13:44:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:44:40 INFO - Reading from file tmpfile_stdout 13:44:40 INFO - Output received: 13:44:40 INFO - Usage: mozinstall [options] installer 13:44:40 INFO - Options: 13:44:40 INFO - -h, --help show this help message and exit 13:44:40 INFO - -d DEST, --destination=DEST 13:44:40 INFO - Directory to install application into. [default: 13:44:40 INFO - "/builds/slave/test"] 13:44:40 INFO - --app=APP Application being installed. [default: firefox] 13:44:40 INFO - mkdir: /builds/slave/test/build/application 13:44:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:45:14 INFO - Reading from file tmpfile_stdout 13:45:14 INFO - Output received: 13:45:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:45:14 INFO - Running post-action listener: _resource_record_post_action 13:45:14 INFO - [mozharness: 2016-08-23 20:45:14.876563Z] Finished install step (success) 13:45:14 INFO - [mozharness: 2016-08-23 20:45:14.876663Z] Running run-tests step. 13:45:14 INFO - Running pre-action listener: _resource_record_pre_action 13:45:14 INFO - Running main action method: run_tests 13:45:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:45:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:45:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:45:14 INFO - 2016-08-23 13:45:14.889 screenresolution[2454:14409] starting screenresolution argv=screenresolution get 13:45:14 INFO - 2016-08-23 13:45:14.891 screenresolution[2454:14409] Display 0: 1600x1200x32@60 13:45:14 INFO - 2016-08-23 13:45:14.897 screenresolution[2455:14412] starting screenresolution argv=screenresolution list 13:45:14 INFO - Available Modes on Display 0 13:45:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:45:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:45:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:45:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:45:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:45:15 INFO - Intel Iris: 13:45:15 INFO - Chipset Model: Intel Iris 13:45:15 INFO - Type: GPU 13:45:15 INFO - Bus: Built-In 13:45:15 INFO - VRAM (Dynamic, Max): 1536 MB 13:45:15 INFO - Vendor: Intel (0x8086) 13:45:15 INFO - Device ID: 0x0a2e 13:45:15 INFO - Revision ID: 0x0009 13:45:15 INFO - Displays: 13:45:15 INFO - Display: 13:45:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:45:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:45:15 INFO - Display Serial Number: 1600x1200 60 13:45:15 INFO - Main Display: Yes 13:45:15 INFO - Mirror: Off 13:45:15 INFO - Online: Yes 13:45:15 INFO - Rotation: Supported 13:45:15 INFO - Return code: 0 13:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:45:15 INFO - Python 2.7.3 13:45:15 INFO - Return code: 0 13:45:15 INFO - grabbing minidump binary from tooltool 13:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:15 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 0x10e82cd30>, '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 0x10e591db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e824b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:45:15 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 13:45:15 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 13:45:15 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 13:45:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:45:15 INFO - Return code: 0 13:45:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:45:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:45:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:45:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:45:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 13:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --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 13:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RKXtgP6BOQ/Render', 13:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:15 INFO - 'HOME': '/Users/cltbld', 13:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:15 INFO - 'LOGNAME': 'cltbld', 13:45:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:45:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:15 INFO - 'MOZ_NO_REMOTE': '1', 13:45:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:45:15 INFO - 'NO_EM_RESTART': '1', 13:45:15 INFO - 'PAGER': '/bin/cat', 13:45:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:15 INFO - 'PWD': '/builds/slave/test', 13:45:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:15 INFO - 'SHELL': '/bin/bash', 13:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ol2lJBAnXm/Listeners', 13:45:15 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 13:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:15 INFO - 'USER': 'cltbld', 13:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:15 INFO - 'XPC_FLAGS': '0x0', 13:45:15 INFO - 'XPC_SERVICE_NAME': '0', 13:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 13:45:15 INFO - mozversion application_buildid: 20160823103647 13:45:15 INFO - mozversion application_changeset: 7214320961eda063ecef1be314c1c4da9b949dbe 13:45:15 INFO - mozversion application_display_name: Nightly 13:45:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:45:15 INFO - mozversion application_name: Firefox 13:45:15 INFO - mozversion application_remotingname: firefox 13:45:15 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:45:15 INFO - mozversion application_vendor: Mozilla 13:45:15 INFO - mozversion application_version: 51.0a1 13:45:15 INFO - mozversion platform_buildid: 20160823103647 13:45:15 INFO - mozversion platform_changeset: 7214320961eda063ecef1be314c1c4da9b949dbe 13:45:15 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:45:15 INFO - mozversion platform_version: 51.0a1 13:45:15 INFO - using testdate: 1471985115 13:45:15 INFO - actual date: 1471985115 13:45:15 INFO - starting webserver on 'localhost:51711' 13:45:15 INFO - SUITE-START | Running 2 tests 13:45:15 INFO - TEST-START | dromaeo_css 13:45:15 INFO - operating with platform_type : mac_ 13:45:15 INFO - Initialising browser for dromaeo_css test... 13:45:15 INFO - TEST-INFO | started process 2465 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpo8S58a/profile http://localhost:51711/getInfo.html) 13:45:17 INFO - PROCESS | 2465 | __metrics Screen width/height:1600/1200 13:45:17 INFO - PROCESS | 2465 | colorDepth:24 13:45:17 INFO - PROCESS | 2465 | Browser inner width/height: 1024/689 13:45:17 INFO - PROCESS | 2465 | __metrics 13:45:18 INFO - PROCESS | 2465 | 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" 13:45:18 INFO - PROCESS | 2465 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:45:18 INFO - TEST-INFO | 2465: exit 0 13:45:18 INFO - Browser initialized. 13:45:18 INFO - Running cycle 1/1 for dromaeo_css test... 13:45:18 INFO - TEST-INFO | started process 2467 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpo8S58a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:45:30 INFO - PROCESS | 2467 | RSS: Main: 231612416 13:45:30 INFO - PROCESS | 2467 | 13:46:21 INFO - PROCESS | 2467 | 1471985181982 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] 13:48:33 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:51711/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51711/tests/dromaeo/cssquery-ext.html) 13:48:35 INFO - PROCESS | 2467 | RSS: Main: 378908672 13:48:35 INFO - PROCESS | 2467 | 13:51:38 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:51711/tests/dromaeo/cssquery-ext.html (next: http://localhost:51711/tests/dromaeo/cssquery-jquery.html) 13:51:39 INFO - PROCESS | 2467 | RSS: Main: 377171968 13:51:39 INFO - PROCESS | 2467 | 13:54:41 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:51711/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51711/tests/dromaeo/cssquery-mootools.html) 13:54:42 INFO - PROCESS | 2467 | RSS: Main: 335159296 13:54:42 INFO - PROCESS | 2467 | 13:57:44 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:51711/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51711/tests/dromaeo/cssquery-prototype.html) 13:57:45 INFO - PROCESS | 2467 | RSS: Main: 343568384 13:57:45 INFO - PROCESS | 2467 | 14:00:48 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:51711/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51711/tests/dromaeo/cssquery-yui.html) 14:00:49 INFO - PROCESS | 2467 | RSS: Main: 337817600 14:00:49 INFO - PROCESS | 2467 | 14:03:52 INFO - PROCESS | 2467 | Cycle 1(1): loaded http://localhost:51711/tests/dromaeo/cssquery-yui.html (next: http://localhost:51711/tests/dromaeo/cssquery-dojo.html) 14:03:53 INFO - PROCESS | 2467 | RSS: Main: 434995200 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | __start_tp_report 14:03:53 INFO - PROCESS | 2467 | _x_x_mozilla_page_load 14:03:53 INFO - PROCESS | 2467 | _x_x_mozilla_page_load_details 14:03:53 INFO - PROCESS | 2467 | |i|pagename|runs| 14:03:53 INFO - PROCESS | 2467 | |0;dojo.html;3330.866765329387;3351.7653764236507;3354.312365964976;3356.5972083350002;3358.865645374187;5615.157726341051;5705.315362156537;5712.086066229404;5774.826755197346;5786.768529258825;5684.971575142118;5753.513567487953;5802.738876750545;5810.331811841639;5810.796622118227;5049.621278404116;5172.482751724835;5192.376914770214;5268.025415298163;5272.446393128705;4653.767311634405;5380.408155102936;5381.408045115034;5389.622726409153;5409.540189083933;5150.201718733602;5215.947840521584;5216.7913283468615;5268.157094864822;5299.4435584263865;3959.6238357356006;5179.663321884103;5190;5242.344706911636;5272.762725677316;5243.2659427680155;5255.185446255837;5263.44733802949;5263.4473380295285;5281.63028587999;5818.44724751148;5819.970900145472;5823.88352232953;5829.329627092896;5833.2416785817995;6130.111133885562;6228;6272.529560282951;6273.6235825850135;6300.779472718433;6288.842778930518;6313.273973493061;6321.146645202884;6322.841928951813;6343.111964324999;5708.343540492897;5883.911741323884;5896.4398382153195;5903.409659034105;5917.644941303535;6272.184615999982;6310.5898116622275;6317.1156038154695;6324.9051264231075;6374.84062898422;6171.537134714914;6238.688065596702;6240.344763799826;6248.750049997949;6262.467690246289;6319.652419116993;6327.525435592349;6328.082428047999;6340.4321249893865;6341.619502829845;6087.634741915499;6156.199694039746;6170.197874276405;6208.068789681584;6232.8130156095385;6157.291911430153;6195.287541932646;6258.74965001404;6268.686565671698;6312.24253089623;4999.775010124553;5000.399952005782;5002.1496345621335;5003.574696150795;5007.574356179765;4458.175237581057;4466.196084704719;4468.084042771289;4484.394606728115;4486.7083639563325;5648.548116150698;5726.226959360433;5756.394884092709;5783.91470448697;5821.592488525763;6310.274318453345;6326.905096423558;6328.398802113792;6332.651704156224;6334.5249106317215;5847.649141051551;5853.795117170878;5866.2960444746905;5906.911396329058;5917.230760001173;5370.597205209625;5405.648632838932;5430.728463576806;5459.989901868167;5494.230807686927;6430.196225471816;6435.935640643534;6438.034294855809;6444.709988050643;6453.096566480696;5582.581306401955;6315.705280417412;6329.7468101275435;6334.904976425265;6346.650934198478;6264.718087686157;6324.3991820776955;6343.238811342669;6383.234011918599;6439.839004024937;6197.659128747782;6242.937570624235;6244.562880598314;6257.68711564429;6268.937310626835;6283.654399008098;6332.208473940757;6332.556721029484;6334.90497642545;6350.333215012357;4898;5111.923321150261;5701.458361455655;6005.519558435228;6229.719662615103;6122.724477398617;6138.109974053827;6149.292831324455;6159.476444502088;6165.198524191826;5930.703464826655;5952.196453478725;5959.344472108151;5961.225040744675;5963.28440587115;5997.220361352996;6073.817785466443;6092.543059270483;6105.9694701526205;6125.601835880653;5986.281646202484;6021.729022194099;6029.668368239789;6038.335783063947;6041.24484439445 14:03:53 INFO - PROCESS | 2467 | |1;ext.html;513.8021861583289;516.5222169493219;524.3890867139783;532.7176596403905;551.618196417973;31540.684593154092;31746.841265793668;33375.33249335015;33393.99818005458;33431.498527522104;42958;44028.3395749064;44193.77903110488;46392.768036159774;46503.534964650346;9874.851877221847;10027.59889604415;10041.04610062043;10053.296269261155;10059.49702514875;8928.37501374904;10031.799364012715;10101.444420556867;10115.54480048396;10117.696469105938;8986.415882967618;9165.175134237918;9202.907970920272;9205.355625106245;9218.815623687522;12003.579874704386;12313.322767247797;12331.445084971198;12591.24452532851;12594.559190428292;10242.846357304647;12103.576374826927;12268.509259629604;12301.384930753517;12336.198147120409;41376.793116034525;41703.1659366811;41712.37431438501;41736.1652766946;41765.58234417678;3393.5927688677516;3418.1967237699123;3441.5353927219753;3458.775179613317;3533.116720819795;31703.682963170304;32797;33083.3383332332;33185.83407082949;33455.6654433455;3251.235959549504;3329.0845017620104;3379.273456206927;3440.122768693992;3468.8959331220103;488934;489994.99999999645;501363;519190;625564;58821;60019.699901500215;60068.79862402727;60115;60272.39727602756;52090.73954630203;63326.0501092484;63515.68242158852;63570;63727.36272637307;15958.1223032732;16147;16175.757363639555;16213.351465941467;16360.509184724477;12722.236665800081;12743.681407964876;12753.744925101631;12795.872041279654;12796.93601532005;17863.374781882572;17868.374606888698;17886.642267154843;17933.820661793216;17951.910240448713;5550.611457198038;6356.555041147075;6413.9416996195105;6787.7600391870565;6959.652017399109;749.5465243527588;1004.7488127968008;1031.664708969588;1045.2213101239624;1090.1278976818514;28687.13938581846;30321.393572128432;30517.38965220683;31196.702884975646;31344.05967820969;916.3127654259306;1043.8799538053222;1100.526773487408;1109.1958330210532;1152.9711757206137;15879.755940274861;15911.68176636484;15915.761263581055;16148.031118132949;16359.83640163607;660113;666214;667565.6621716861;669909;671050;51929.701757456365;52836.679083023235;60095.69952150299;60512.697436512535;60524;1371.8833899118686;1383.6817531967906;1422.1396055386342;1422.3386125451507;1431.5848403962734;23525.836169142;30669.079927602208;31842.522362164123;32200.35599288048;32378;52906.735466322425;53603.19595206153;53702.46297537131;53770.46229537654;53770.73114634402;603798;607098;653574;656147;666034;26220.606690900033;26556.8672156638;26569.468610627293;26888.32779180536;26932.922683092456;46914.29628555506;47156.29265561088;47594.334198302895;47774;48322.758386207846;32319.384030798836;38358.23283534258;38832.22335553329;39198;39676.206475870895;39065.80467097646;39971.9173853296;40187;40651.1869762609;40962;37569.248615027;37647.81176094102;38508.80745596254;38598.42102368523;38668.6133138665;32750.181245469055;34139.93677972945;34490.86109666856;34589.65410345864;35434.822825885705;19724.30964916221;20903.12528091566;25079.87460062688;25885.09402170915;26507.072252471073 14:03:53 INFO - PROCESS | 2467 | |2;jquery.html;351.7379552233583;366.1322665283279;370.85536640710126;380.72397511803945;394.5994815262509;15779.28993195306;16566.088865112415;20614.1754329827;21131.260405885798;21235.25676601317;20954.895225523887;21111.472213194676;21382.572348553018;21455.356339309845;21758.347249582497;5986.73059712314;6030.155778191057;6169.722362493699;6194.442500174983;6252.093446450262;6114.419130182626;6213.974694175456;6225.221847269091;6245.406686364788;6255.562110652256;4183.707140500166;5690.60165788395;6714.328567143295;6789.932100679004;6820.35206655367;6503.447206987412;6724.462043036546;6796;6798.592084474898;6805.013273075426;5856.736446859902;5860.267466566679;5861;5862.589618726691;5867.677277749722;24381.63427548588;25450;26661;26795.73204267952;27082.72917270822;2436.1838784007377;2492.700875894886;2718.4563087382403;3003.7446817020577;3074.3851229753914;10261.230407719451;10287.022732840369;10287.639932602398;10342.7414314642;14086.647833804134;1623.902565846053;1645.12808211648;1758.9296428142986;1838.0901453780464;1940.7865134835156;113812;120224;121051;125492;127245.45509089858;9361.90638093624;9647.807043859084;10034.09693127622;10050.899491005142;11479;12378.381080945917;12461.940735037624;12462.507498500336;12485.063620228519;12528;7964.561949092856;8139.918600814034;8147.185281471805;8242.546659933761;8255.174482551696;7054.294570542905;8513.872291915626;8514.318854491627;8543.658253669908;8678.435901666371;3154.6845315468727;3927.096767743435;3929.685625149981;3974.18529201513;4006.7395619284653;1868.9065546722882;1915.4445210888964;1916.377177417345;1916.48254971157;1920.1647283431473;852.5609311204735;856.246503077288;883.4054531570577;915.2037727177266;969.8399764038995;20922.267720629705;21556.245531406326;21921.01355439009;21963.450913727283;21995.23016694408;894.3605322194662;905.2034209895251;950.5532399772096;983.3460748602242;994.5574219472265;9726.610935562498;9927.602895884229;10942.781144377068;11434;11437.485313160762;582471;583686;590949;598656;600096;95798;129185;132918.00622990663;132949.33525332506;134977;942.241495596038;958.4249450329886;979.6277414582413;981.3964411886639;984.4536282363214;18108.818911810715;18554.701170917906;19106.235750570377;19355.806441935965;19414.902925485283;50512;53712;56337.316449677106;56762;56904.29287121393;77831;78730;80058.19941800507;81997.59001204956;83046.58476707578;7914.129445761077;8062.153473885158;8123.512589244664;8238.176182381714;8375.288100511525;12484;13204.735905282032;13257;13570.253636049718;13752.381142848795;9104.453732776055;11181.776364472827;11431.542738290374;11561.537538498365;11643.88356116462;10877.51051202682;11223.326600404002;11458.369789661649;11647.660519040419;11805.107748601647;11033.558657653604;11650.825237621611;12023.338716370334;12063.336516491678;12105.031597472358;7666.4633475656165;7814.765557033071;7836.2760192757505;8230.176982301722;8268.917310827055;8897.555122243732;9647.118811831626;10679.626213082505;11261;11925.582604608797 14:03:53 INFO - PROCESS | 2467 | |3;mootools.html;1304.6607881950692;1331.9533816316432;1467.5817392043266;1589.984100158999;1811.719183526553;7783;8607.870881936777;8852.380333376666;8862.734117976463;8927.28581713463;8664.263537599296;8746.562671866413;8753.824923501526;8759.386842920998;8777.64889404423;2357.116081469453;2385.654080158376;2387.0929046962215;2390.8924098415614;2393.6050551658955;1956.4228552576953;1969.9212031518723;2323.361075704178;2369.609014512603;2376.5959786836193;2349.0955981946904;2355.5995480768224;2366.100881664965;2386.3079706884982;2386.78518933296;1958.8237058664647;2330.4057465346227;2342.4261056041296;2355.823313251513;2356.5640356534095;2304.5275718477837;2352.9764702352927;2357.1278626907983;2357.4695693469002;2358.740538540759;8757.912420875773;8828.779280517974;8835.469871807712;8841.425307355;8846.955765221133;5220.112580861262;5400.324959380077;5413.837584872421;5422.728863556807;5427.864303392407;3568.57177138745;3582.140286331288;3632.3098611263777;3650.0874781304674;3650.653187947141;1036.7045392063226;1056.4717641179411;1065.4406436620836;1074.505727365413;1074.8925107489267;9941.35381200227;11594.768104637944;11697.94151029248;11731.941340293244;11801.586944456989;3695.9630403696156;4463.263561512379;4611.538846115362;4628.675992680547;4631.768411579407;9127.224185944136;9128.634854605742;9142.725718228463;9151.039140890243;9161.862572061424;5191.273221748958;5203.531682148625;5203.687778733288;5209.661372010883;5210.21846722987;4393.143337049245;4574.359589657451;4758.666893317434;4857.6599638025;4896.681715688469;3758.379867321861;3763.5295588051495;3880.5925377835483;3911.2568611963634;3917.7061720370516;1180.0677464802882;1194.5759255464359;1195.910306726999;1198.5804968261214;1209.9334536600572;843.7468759372164;905.8958219804675;919.9632014719336;924.6624981881565;960.5485421851719;5089.643724939293;5746.13807928814;6016.879662406727;6130.448259656652;6148.631082135086;444.8665400379873;458.0655462855737;461.3610149942354;465.234688936704;471.0602348315133;3611.5485564304463;3868.535775706877;3980.5223373195404;4109.4246805447265;4129.15352352767;10325.9483702581;10695.465226738632;10754.139668826476;10758.784824303626;10784.838227426595;9792.608295668237;10671.626493072705;10683.09193718527;10713.196510261761;10730;7641.541507509567;7724.7682569523;8214.63034163464;8214.8357032859;8219.342452603778;5578.7447824239225;5727.1695604138;6143.877122457615;6186.443220110212;6194.893736129738;9512.191463725661;9839.114479696862;10005.299629025898;10022;10134.543945522322;8605.784855378415;9699.078587534173;9878.950605246928;10281.537330819981;10303.639372621923;5503.587230957614;6511.544191906711;6705.497087718538;6965.442764578923;7593.088829340514;8256.422050456411;8318.667253310043;8326.167383261625;8331.50010999342;8354.832903342021;7244.601546914971;7285.963570182115;7418.925810742039;7511;7527.247275272429;7199.2440793715905;7246.855062898818;7270.6728197230195;7287.744928927462;7321.890171647536;6895.862082758216;6934.375906168469;7019.894701579378;7030.683619237045;7037.648117594203;5016.372953380827;5065.392152941523;5152.690838549699;5163.199704045879;5243.764030618555;4991.201407774739;5075.5864292751985;5129.9743501282255;5175.663581867167;5296.099663057213 14:03:53 INFO - PROCESS | 2467 | |4;prototype.html;256.9254916074338;294.95870578119053;310.7467414057543;312.0404755377216;313.96389415217243;4559.8404055858055;5032.5219104185135;5207.505286997739;5242.580593552516;5311.141100614787;4959.752012399384;5124.92312615311;5170.224466330053;5170.974145129269;5211.817586384468;3996.42051902474;4060.21350605734;4061.390791381294;4061.57353477885;4063.2889244382263;3831.9042023949487;4012.217617564576;4038;4046.9392959105635;4047.494063242095;3723.7021038317007;3801.8669346572874;3907.8241479133508;3989.940150897739;4006.559278479365;3655.0131464504702;3663.303972245265;3998.820053097618;3999.2401443725744;4026.7583944963103;2904.8547572621496;3510.7542472026967;3539.504469374264;3550.396432606463;3574.4459608760685;4685.2972054191805;5093.210552364352;5120.564751996085;5145.768440420153;5170.844874653766;4247.426597409341;4442.755648439334;4494.033782736695;4495.561420345491;4498.527654596253;5241.528262456397;5459.181122831607;5481.945180548183;5491.670499770026;5508.6970216638065;3833.271678381099;3893.649571538575;3900.258950799339;3910.8658489038144;3919.490466239399;5020.560915772671;5053.646244762905;5106.387233531963;5538.501534861842;5796.333421656522;3928.548216955087;3952.9802350988634;3953.940690889639;3979.840806367713;3997.3004724173734;3508.4737289406794;3867.0912335601674;3959.208757825726;4009.1580768038457;4011.4183443400543;4519.84180553679;4564.1556312082375;4610.515895830957;4622.907541849144;4637.420322459692;5551.3893471718075;5558.333000040021;5580.330360356783;5597.328320601554;5650.74571644277;1131.1968502363413;1295.863934286905;1310.5937159480402;1312.4094157629106;1335.6660834791303;845.9154084591614;949.9597940255409;956.0296299256261;958.4824194934797;958.9212136346611;5178.223266510054;5180.870478238075;5196.818111366084;5212.713300768418;5214.556762675215;4622;4961.900761984813;5009.5741861941415;5655.519280861173;5686.914696279559;5084;5086.694798312039;5099.311592934981;5116.360454943132;5133.794648214104;2175.053327272114;2317.8145748340094;2328.2316835444262;2392.377981724729;2504.5742223821994;4900.705957642553;4909.509049095133;5442.673439593637;5954.761809527657;6024.487918526975;5148.279240906276;5204.297419848348;5471.398146203915;5926.229590153339;5929.495992840571;4493.0115374617535;5438.2325744134105;5954.464098231093;5993.910091348546;6027;5415.756290966995;5802.883942321046;5808.448197421239;5823.631446610128;5826.388229235869;5759.596828221985;5762.423757624204;5764.884702306014;5765.4522820332;5791.82624521265;5558.277423934863;5689.34572524165;5986.401359864153;6029.57792954489;6030.48740857032;3463.1169051891607;3605.7836529808296;3853.3064048470424;3876.573576906482;3879.398693202558;5836.562257830765;5863.589548731547;5865.736041878211;5892.410758924073;5893.734781934738;5527.977324195037;5630.97284977416;5662.405447428126;5680.602357834912;5723.7424315905055;4570.840020599263;5319.813806516753;5605.766731319103;5619.550435965195;5648.661080335193;4310.241445474961;4610.608098311547;5350.9933774834435;5498.175273708815;5502.174673798802;5158.303629010035;5161.483851614958;5647.915281270866;5701.800436984686;5731.713414329351;4751.028323245952;4772.976135119302;5205.245239440335;5372.435894231206;5446.210299506629 14:03:53 INFO - PROCESS | 2467 | |5;yui.html;694.9235584085751;717.0785540580354;725.1298441869758;747.8055705516567;764.1288930619091;15519.379224831007;15899.52301430956;16319.347226110956;16557.337706491755;16669.41657042004;14790.630234244149;14851.405943762236;14986;14990.625234369147;15015.849841501582;867.7787164273117;881.951492667903;886.5301390263138;887.0641473245723;888.8177923525661;693.5491930245356;718.7879575525233;863.434450434963;890.1721399098835;902.9097090290984;939.3143005605912;941.7268991992314;942.7784470649392;942.9905700943006;943.0522325063301;931.483026920059;934.0472717827846;936.4334577580602;936.5644975086617;936.8641546975718;542.5632365945412;559.035663480497;559.9384067752585;562.9859253518695;662.6057495789997;12814.42335094923;14982.10107393549;15051.172185529791;15116.697666046617;15166.848331516654;14040.789388159186;14155.929220353832;14316.713665726731;14513.27433628325;14562.344694488773;12982.35088245584;13221.86778132216;13236.867631323756;13304.800427993589;13599.728005439934;4464.15181115587;4543.363929049936;4589.173948689235;4618.145643056046;4686.2267725825195;114829.4258528702;114904;115283;115792;116566.99999999916;10444.582216711246;10590.417527035934;10944.945275273572;11052.668419947415;11540.076793856471;21196.15215391367;21534.78465215359;21549.24577639775;21558.353249402542;21603.567928641653;7743.5353878767455;7820.843583128306;7877.7636670899965;8007.03827562251;8022.237887400687;4464.687471877002;4474.451281795734;4484.641228701761;4591;4675.649326300541;3581.964180358163;3708.517892673935;3709.295233905549;3709.499217605589;3710.9443358349645;567.9488846003796;670.8591195848829;677.5866721300004;677.9084823548758;679.2358596578849;566.9886602268014;570.403927895342;578.673049726903;603.408659513679;628.1551313483449;10148.594056237667;10325.432101234504;10380.429076400871;10406.546021128737;10484.318519296221;574.8304250246188;591.5504216795266;595.4075694683818;625.1185827982523;689.5931400473745;4508.684392092588;4679.859604211879;4695.835645752382;4716.1510928033285;4847.903041939212;135569;143439.28280358532;149517;153418.23290883473;157268;126621;126864;128905.35547322391;129886;131912.68087319197;105030.94969050212;108449;108655;109452;113198;8544.871826922725;9052.683156089506;9165;9188.356815022884;9286.72139835806;53824.19263711126;56002;58126;61930.38069619426;62632;88488.67266991129;91205;94682.52658736662;96069;99244.50377748066;8630.741077767678;12956;13490.932545337211;14004.369803359077;14598.489052883522;13813.102148360324;14423.92788036095;14894.404223830925;15838.445654402041;15858.841411586198;14101.21257238193;14461.421543138156;14489.855101448851;14490.927545362627;14768.704625907636;13936.651583710489;14216.857831421554;14235;14498.927505362406;14704.852951470777;13466.865331346562;13804.516841910485;14039.719205616035;14158.362873670916;14436.494722684656;10680.83978740335;11470.19708620422;11687.532498700297;11694.532218711156;11946.522139114339;10722.89277107219;11330.634143918065;11547.191696581494;11664.6500604982;11736.70658233551 14:03:53 INFO - PROCESS | 2467 | __end_tp_report 14:03:53 INFO - PROCESS | 2467 | __start_cc_report 14:03:53 INFO - PROCESS | 2467 | _x_x_mozilla_cycle_collect,1478 14:03:53 INFO - PROCESS | 2467 | __end_cc_report 14:03:53 INFO - PROCESS | 2467 | __startTimestamp1471986233135__endTimestamp 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | ------- Summary: start ------- 14:03:53 INFO - PROCESS | 2467 | Number of tests: 6 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | [#0] dojo.html Cycles:165 Average:5771.81 Median:5961.23 stddev:669.73 (11.2%) stddev-sans-first:643.83 14:03:53 INFO - PROCESS | 2467 | Values: 3330.9 3351.8 3354.3 3356.6 3358.9 5615.2 5705.3 5712.1 5774.8 5786.8 5685.0 5753.5 5802.7 5810.3 5810.8 5049.6 5172.5 5192.4 5268.0 5272.4 4653.8 5380.4 5381.4 5389.6 5409.5 5150.2 5215.9 5216.8 5268.2 5299.4 3959.6 5179.7 5190.0 5242.3 5272.8 5243.3 5255.2 5263.4 5263.4 5281.6 5818.4 5820.0 5823.9 5829.3 5833.2 6130.1 6228.0 6272.5 6273.6 6300.8 6288.8 6313.3 6321.1 6322.8 6343.1 5708.3 5883.9 5896.4 5903.4 5917.6 6272.2 6310.6 6317.1 6324.9 6374.8 6171.5 6238.7 6240.3 6248.8 6262.5 6319.7 6327.5 6328.1 6340.4 6341.6 6087.6 6156.2 6170.2 6208.1 6232.8 6157.3 6195.3 6258.7 6268.7 6312.2 4999.8 5000.4 5002.1 5003.6 5007.6 4458.2 4466.2 4468.1 4484.4 4486.7 5648.5 5726.2 5756.4 5783.9 5821.6 6310.3 6326.9 6328.4 6332.7 6334.5 5847.6 5853.8 5866.3 5906.9 5917.2 5370.6 5405.6 5430.7 5460.0 5494.2 6430.2 6435.9 6438.0 6444.7 6453.1 5582.6 6315.7 6329.7 6334.9 6346.7 6264.7 6324.4 6343.2 6383.2 6439.8 6197.7 6242.9 6244.6 6257.7 6268.9 6283.7 6332.2 6332.6 6334.9 6350.3 4898.0 5111.9 5701.5 6005.5 6229.7 6122.7 6138.1 6149.3 6159.5 6165.2 5930.7 5952.2 5959.3 5961.2 5963.3 5997.2 6073.8 6092.5 6106.0 6125.6 5986.3 6021.7 6029.7 6038.3 6041.2 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | [#1] ext.html Cycles:180 Average:73754.69 Median:18966.20 stddev:164291.84 (866.2%) stddev-sans-first:164660.69 14:03:53 INFO - PROCESS | 2467 | Values: 513.8 516.5 524.4 532.7 551.6 31540.7 31746.8 33375.3 33394.0 33431.5 42958.0 44028.3 44193.8 46392.8 46503.5 9874.9 10027.6 10041.0 10053.3 10059.5 8928.4 10031.8 10101.4 10115.5 10117.7 8986.4 9165.2 9202.9 9205.4 9218.8 12003.6 12313.3 12331.4 12591.2 12594.6 10242.8 12103.6 12268.5 12301.4 12336.2 41376.8 41703.2 41712.4 41736.2 41765.6 3393.6 3418.2 3441.5 3458.8 3533.1 31703.7 32797.0 33083.3 33185.8 33455.7 3251.2 3329.1 3379.3 3440.1 3468.9 488934.0 489995.0 501363.0 519190.0 625564.0 58821.0 60019.7 60068.8 60115.0 60272.4 52090.7 63326.1 63515.7 63570.0 63727.4 15958.1 16147.0 16175.8 16213.4 16360.5 12722.2 12743.7 12753.7 12795.9 12796.9 17863.4 17868.4 17886.6 17933.8 17951.9 5550.6 6356.6 6413.9 6787.8 6959.7 749.5 1004.7 1031.7 1045.2 1090.1 28687.1 30321.4 30517.4 31196.7 31344.1 916.3 1043.9 1100.5 1109.2 1153.0 15879.8 15911.7 15915.8 16148.0 16359.8 660113.0 666214.0 667565.7 669909.0 671050.0 51929.7 52836.7 60095.7 60512.7 60524.0 1371.9 1383.7 1422.1 1422.3 1431.6 23525.8 30669.1 31842.5 32200.4 32378.0 52906.7 53603.2 53702.5 53770.5 53770.7 603798.0 607098.0 653574.0 656147.0 666034.0 26220.6 26556.9 26569.5 26888.3 26932.9 46914.3 47156.3 47594.3 47774.0 48322.8 32319.4 38358.2 38832.2 39198.0 39676.2 39065.8 39971.9 40187.0 40651.2 40962.0 37569.2 37647.8 38508.8 38598.4 38668.6 32750.2 34139.9 34490.9 34589.7 35434.8 19724.3 20903.1 25079.9 25885.1 26507.1 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | [#2] jquery.html Cycles:180 Average:34978.65 Median:375.79 stddev:98826.27 (26298.3%) stddev-sans-first:99069.30 14:03:53 INFO - PROCESS | 2467 | Values: 351.7 366.1 370.9 380.7 394.6 15779.3 16566.1 20614.2 21131.3 21235.3 20954.9 21111.5 21382.6 21455.4 21758.3 5986.7 6030.2 6169.7 6194.4 6252.1 6114.4 6214.0 6225.2 6245.4 6255.6 4183.7 5690.6 6714.3 6789.9 6820.4 6503.4 6724.5 6796.0 6798.6 6805.0 5856.7 5860.3 5861.0 5862.6 5867.7 24381.6 25450.0 26661.0 26795.7 27082.7 2436.2 2492.7 2718.5 3003.7 3074.4 10261.2 10287.0 10287.6 10342.7 14086.6 1623.9 1645.1 1758.9 1838.1 1940.8 113812.0 120224.0 121051.0 125492.0 127245.5 9361.9 9647.8 10034.1 10050.9 11479.0 12378.4 12461.9 12462.5 12485.1 12528.0 7964.6 8139.9 8147.2 8242.5 8255.2 7054.3 8513.9 8514.3 8543.7 8678.4 3154.7 3927.1 3929.7 3974.2 4006.7 1868.9 1915.4 1916.4 1916.5 1920.2 852.6 856.2 883.4 915.2 969.8 20922.3 21556.2 21921.0 21963.5 21995.2 894.4 905.2 950.6 983.3 994.6 9726.6 9927.6 10942.8 11434.0 11437.5 582471.0 583686.0 590949.0 598656.0 600096.0 95798.0 129185.0 132918.0 132949.3 134977.0 942.2 958.4 979.6 981.4 984.5 18108.8 18554.7 19106.2 19355.8 19414.9 50512.0 53712.0 56337.3 56762.0 56904.3 77831.0 78730.0 80058.2 81997.6 83046.6 7914.1 8062.2 8123.5 8238.2 8375.3 12484.0 13204.7 13257.0 13570.3 13752.4 9104.5 11181.8 11431.5 11561.5 11643.9 10877.5 11223.3 11458.4 11647.7 11805.1 11033.6 11650.8 12023.3 12063.3 12105.0 7666.5 7814.8 7836.3 8230.2 8268.9 8897.6 9647.1 10679.6 11261.0 11925.6 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | [#3] mootools.html Cycles:180 Average:5610.06 Median:5070.49 stddev:3158.68 (62.3%) stddev-sans-first:3150.97 14:03:53 INFO - PROCESS | 2467 | Values: 1304.7 1332.0 1467.6 1590.0 1811.7 7783.0 8607.9 8852.4 8862.7 8927.3 8664.3 8746.6 8753.8 8759.4 8777.6 2357.1 2385.7 2387.1 2390.9 2393.6 1956.4 1969.9 2323.4 2369.6 2376.6 2349.1 2355.6 2366.1 2386.3 2386.8 1958.8 2330.4 2342.4 2355.8 2356.6 2304.5 2353.0 2357.1 2357.5 2358.7 8757.9 8828.8 8835.5 8841.4 8847.0 5220.1 5400.3 5413.8 5422.7 5427.9 3568.6 3582.1 3632.3 3650.1 3650.7 1036.7 1056.5 1065.4 1074.5 1074.9 9941.4 11594.8 11697.9 11731.9 11801.6 3696.0 4463.3 4611.5 4628.7 4631.8 9127.2 9128.6 9142.7 9151.0 9161.9 5191.3 5203.5 5203.7 5209.7 5210.2 4393.1 4574.4 4758.7 4857.7 4896.7 3758.4 3763.5 3880.6 3911.3 3917.7 1180.1 1194.6 1195.9 1198.6 1209.9 843.7 905.9 920.0 924.7 960.5 5089.6 5746.1 6016.9 6130.4 6148.6 444.9 458.1 461.4 465.2 471.1 3611.5 3868.5 3980.5 4109.4 4129.2 10325.9 10695.5 10754.1 10758.8 10784.8 9792.6 10671.6 10683.1 10713.2 10730.0 7641.5 7724.8 8214.6 8214.8 8219.3 5578.7 5727.2 6143.9 6186.4 6194.9 9512.2 9839.1 10005.3 10022.0 10134.5 8605.8 9699.1 9879.0 10281.5 10303.6 5503.6 6511.5 6705.5 6965.4 7593.1 8256.4 8318.7 8326.2 8331.5 8354.8 7244.6 7286.0 7418.9 7511.0 7527.2 7199.2 7246.9 7270.7 7287.7 7321.9 6895.9 6934.4 7019.9 7030.7 7037.6 5016.4 5065.4 5152.7 5163.2 5243.8 4991.2 5075.6 5130.0 5175.7 5296.1 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | [#4] prototype.html Cycles:180 Average:4501.40 Median:5102.85 stddev:1399.64 (27.4%) stddev-sans-first:1366.83 14:03:53 INFO - PROCESS | 2467 | Values: 256.9 295.0 310.7 312.0 314.0 4559.8 5032.5 5207.5 5242.6 5311.1 4959.8 5124.9 5170.2 5171.0 5211.8 3996.4 4060.2 4061.4 4061.6 4063.3 3831.9 4012.2 4038.0 4046.9 4047.5 3723.7 3801.9 3907.8 3989.9 4006.6 3655.0 3663.3 3998.8 3999.2 4026.8 2904.9 3510.8 3539.5 3550.4 3574.4 4685.3 5093.2 5120.6 5145.8 5170.8 4247.4 4442.8 4494.0 4495.6 4498.5 5241.5 5459.2 5481.9 5491.7 5508.7 3833.3 3893.6 3900.3 3910.9 3919.5 5020.6 5053.6 5106.4 5538.5 5796.3 3928.5 3953.0 3953.9 3979.8 3997.3 3508.5 3867.1 3959.2 4009.2 4011.4 4519.8 4564.2 4610.5 4622.9 4637.4 5551.4 5558.3 5580.3 5597.3 5650.7 1131.2 1295.9 1310.6 1312.4 1335.7 845.9 950.0 956.0 958.5 958.9 5178.2 5180.9 5196.8 5212.7 5214.6 4622.0 4961.9 5009.6 5655.5 5686.9 5084.0 5086.7 5099.3 5116.4 5133.8 2175.1 2317.8 2328.2 2392.4 2504.6 4900.7 4909.5 5442.7 5954.8 6024.5 5148.3 5204.3 5471.4 5926.2 5929.5 4493.0 5438.2 5954.5 5993.9 6027.0 5415.8 5802.9 5808.4 5823.6 5826.4 5759.6 5762.4 5764.9 5765.5 5791.8 5558.3 5689.3 5986.4 6029.6 6030.5 3463.1 3605.8 3853.3 3876.6 3879.4 5836.6 5863.6 5865.7 5892.4 5893.7 5528.0 5631.0 5662.4 5680.6 5723.7 4570.8 5319.8 5605.8 5619.6 5648.7 4310.2 4610.6 5351.0 5498.2 5502.2 5158.3 5161.5 5647.9 5701.8 5731.7 4751.0 4773.0 5205.2 5372.4 5446.2 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - PROCESS | 2467 | [#5] yui.html Cycles:180 Average:25115.90 Median:21542.02 stddev:39746.13 (184.5%) stddev-sans-first:39815.33 14:03:53 INFO - PROCESS | 2467 | Values: 694.9 717.1 725.1 747.8 764.1 15519.4 15899.5 16319.3 16557.3 16669.4 14790.6 14851.4 14986.0 14990.6 15015.8 867.8 882.0 886.5 887.1 888.8 693.5 718.8 863.4 890.2 902.9 939.3 941.7 942.8 943.0 943.1 931.5 934.0 936.4 936.6 936.9 542.6 559.0 559.9 563.0 662.6 12814.4 14982.1 15051.2 15116.7 15166.8 14040.8 14155.9 14316.7 14513.3 14562.3 12982.4 13221.9 13236.9 13304.8 13599.7 4464.2 4543.4 4589.2 4618.1 4686.2 114829.4 114904.0 115283.0 115792.0 116567.0 10444.6 10590.4 10944.9 11052.7 11540.1 21196.2 21534.8 21549.2 21558.4 21603.6 7743.5 7820.8 7877.8 8007.0 8022.2 4464.7 4474.5 4484.6 4591.0 4675.6 3582.0 3708.5 3709.3 3709.5 3710.9 567.9 670.9 677.6 677.9 679.2 567.0 570.4 578.7 603.4 628.2 10148.6 10325.4 10380.4 10406.5 10484.3 574.8 591.6 595.4 625.1 689.6 4508.7 4679.9 4695.8 4716.2 4847.9 135569.0 143439.3 149517.0 153418.2 157268.0 126621.0 126864.0 128905.4 129886.0 131912.7 105030.9 108449.0 108655.0 109452.0 113198.0 8544.9 9052.7 9165.0 9188.4 9286.7 53824.2 56002.0 58126.0 61930.4 62632.0 88488.7 91205.0 94682.5 96069.0 99244.5 8630.7 12956.0 13490.9 14004.4 14598.5 13813.1 14423.9 14894.4 15838.4 15858.8 14101.2 14461.4 14489.9 14490.9 14768.7 13936.7 14216.9 14235.0 14498.9 14704.9 13466.9 13804.5 14039.7 14158.4 14436.5 10680.8 11470.2 11687.5 11694.5 11946.5 10722.9 11330.6 11547.2 11664.7 11736.7 14:03:53 INFO - PROCESS | 2467 | -------- Summary: end -------- 14:03:53 INFO - PROCESS | 2467 | 14:03:53 INFO - TEST-INFO | 2467: exit 0 14:03:53 INFO - TEST-OK | dromaeo_css | took 1118301ms 14:03:53 INFO - TEST-START | kraken 14:03:53 INFO - operating with platform_type : mac_ 14:03:53 INFO - Initialising browser for kraken test... 14:03:53 INFO - TEST-INFO | started process 2471 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpQR9LJL/profile http://localhost:51711/getInfo.html) 14:03:56 INFO - PROCESS | 2471 | __metrics Screen width/height:1600/1200 14:03:56 INFO - PROCESS | 2471 | colorDepth:24 14:03:56 INFO - PROCESS | 2471 | Browser inner width/height: 1024/689 14:03:56 INFO - PROCESS | 2471 | __metrics 14:03:56 INFO - PROCESS | 2471 | 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" 14:03:56 INFO - PROCESS | 2471 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:03:56 INFO - TEST-INFO | 2471: exit 0 14:03:56 INFO - Browser initialized. 14:03:56 INFO - Running cycle 1/1 for kraken test... 14:03:56 INFO - TEST-INFO | started process 2472 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpQR9LJL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:04:08 INFO - PROCESS | 2472 | RSS: Main: 231538688 14:04:08 INFO - PROCESS | 2472 | 14:04:11 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51711/tests/kraken/driver.html?testName=audio-beat-detection) 14:04:12 INFO - PROCESS | 2472 | RSS: Main: 297156608 14:04:12 INFO - PROCESS | 2472 | 14:04:15 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51711/tests/kraken/driver.html?testName=audio-dft) 14:04:15 INFO - PROCESS | 2472 | RSS: Main: 297816064 14:04:15 INFO - PROCESS | 2472 | 14:04:19 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51711/tests/kraken/driver.html?testName=audio-fft) 14:04:19 INFO - PROCESS | 2472 | RSS: Main: 382758912 14:04:19 INFO - PROCESS | 2472 | 14:04:22 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51711/tests/kraken/driver.html?testName=audio-oscillator) 14:04:22 INFO - PROCESS | 2472 | RSS: Main: 284340224 14:04:22 INFO - PROCESS | 2472 | 14:04:24 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51711/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:04:25 INFO - PROCESS | 2472 | RSS: Main: 288464896 14:04:25 INFO - PROCESS | 2472 | 14:04:28 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51711/tests/kraken/driver.html?testName=imaging-darkroom) 14:04:29 INFO - PROCESS | 2472 | RSS: Main: 327434240 14:04:29 INFO - PROCESS | 2472 | 14:04:31 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51711/tests/kraken/driver.html?testName=imaging-desaturate) 14:04:32 INFO - PROCESS | 2472 | RSS: Main: 328093696 14:04:32 INFO - PROCESS | 2472 | 14:04:35 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51711/tests/kraken/driver.html?testName=json-parse-financial) 14:04:36 INFO - PROCESS | 2472 | RSS: Main: 325599232 14:04:36 INFO - PROCESS | 2472 | 14:04:37 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51711/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:04:38 INFO - PROCESS | 2472 | RSS: Main: 302833664 14:04:38 INFO - PROCESS | 2472 | 14:04:40 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:04:40 INFO - PROCESS | 2472 | RSS: Main: 303169536 14:04:40 INFO - PROCESS | 2472 | 14:04:42 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:04:43 INFO - PROCESS | 2472 | RSS: Main: 303038464 14:04:43 INFO - PROCESS | 2472 | 14:04:46 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:04:47 INFO - PROCESS | 2472 | RSS: Main: 311930880 14:04:47 INFO - PROCESS | 2472 | 14:04:50 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:04:50 INFO - PROCESS | 2472 | RSS: Main: 313348096 14:04:50 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | Cycle 1(1): loaded http://localhost:51711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51711/tests/kraken/driver.html?testName=ai-astar) 14:04:52 INFO - PROCESS | 2472 | RSS: Main: 356397056 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | __start_tp_report 14:04:52 INFO - PROCESS | 2472 | _x_x_mozilla_page_load 14:04:52 INFO - PROCESS | 2472 | _x_x_mozilla_page_load_details 14:04:52 INFO - PROCESS | 2472 | |i|pagename|runs| 14:04:52 INFO - PROCESS | 2472 | |0;ai-astar;97;114;108;114;114;114;116;113;114;112 14:04:52 INFO - PROCESS | 2472 | |1;audio-beat-detection;163;137;164;167;107;129;130;107;191;110 14:04:52 INFO - PROCESS | 2472 | |2;audio-dft;186;182;183;184;184;183;180;181;183;184 14:04:52 INFO - PROCESS | 2472 | |3;audio-fft;78;73;111;99;73;85;86;115;87;57 14:04:52 INFO - PROCESS | 2472 | |4;audio-oscillator;85;87;141;71;71;74;120;56;66;143 14:04:52 INFO - PROCESS | 2472 | |5;imaging-gaussian-blur;89;87;90;88;89;86;91;90;135;130 14:04:52 INFO - PROCESS | 2472 | |6;imaging-darkroom;95;94;95;96;95;94;94;94;95;93 14:04:52 INFO - PROCESS | 2472 | |7;imaging-desaturate;84;87;86;84;84;84;85;97;124;86 14:04:52 INFO - PROCESS | 2472 | |8;json-parse-financial;54;53;55;58;57;55;56;55;54;53 14:04:52 INFO - PROCESS | 2472 | |9;json-stringify-tinderbox;67;70;71;83;74;73;71;71;71;72 14:04:52 INFO - PROCESS | 2472 | |10;stanford-crypto-aes;87;85;90;97;106;109;83;85;85;86 14:04:52 INFO - PROCESS | 2472 | |11;stanford-crypto-ccm;137;139;138;140;139;139;137;138;157;137 14:04:52 INFO - PROCESS | 2472 | |12;stanford-crypto-pbkdf2;159;164;149;222;238;168;168;168;167;167 14:04:52 INFO - PROCESS | 2472 | |13;stanford-crypto-sha256-iterative;68;67;80;87;71;68;69;66;67;67 14:04:52 INFO - PROCESS | 2472 | __end_tp_report 14:04:52 INFO - PROCESS | 2472 | __start_cc_report 14:04:52 INFO - PROCESS | 2472 | _x_x_mozilla_cycle_collect,2190 14:04:52 INFO - PROCESS | 2472 | __end_cc_report 14:04:52 INFO - PROCESS | 2472 | __startTimestamp1471986292789__endTimestamp 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | ------- Summary: start ------- 14:04:52 INFO - PROCESS | 2472 | Number of tests: 14 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#0] ai-astar Cycles:10 Average:111.60 Median:114.00 stddev:5.54 (4.9%) stddev-sans-first:2.22 14:04:52 INFO - PROCESS | 2472 | Values: 97.0 114.0 108.0 114.0 114.0 114.0 116.0 113.0 114.0 112.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:150.00 stddev:29.33 (19.6%) stddev-sans-first:29.95 14:04:52 INFO - PROCESS | 2472 | Values: 163.0 137.0 164.0 167.0 107.0 129.0 130.0 107.0 191.0 110.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.70 (0.9%) stddev-sans-first:1.41 14:04:52 INFO - PROCESS | 2472 | Values: 186.0 182.0 183.0 184.0 184.0 183.0 180.0 181.0 183.0 184.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#3] audio-fft Cycles:10 Average:86.40 Median:81.50 stddev:17.88 (21.9%) stddev-sans-first:18.71 14:04:52 INFO - PROCESS | 2472 | Values: 78.0 73.0 111.0 99.0 73.0 85.0 86.0 115.0 87.0 57.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:71.00 stddev:31.68 (44.6%) stddev-sans-first:33.52 14:04:52 INFO - PROCESS | 2472 | Values: 85.0 87.0 141.0 71.0 71.0 74.0 120.0 56.0 66.0 143.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:89.00 stddev:18.54 (20.8%) stddev-sans-first:19.41 14:04:52 INFO - PROCESS | 2472 | Values: 89.0 87.0 90.0 88.0 89.0 86.0 91.0 90.0 135.0 130.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 14:04:52 INFO - PROCESS | 2472 | Values: 95.0 94.0 95.0 96.0 95.0 94.0 94.0 94.0 95.0 93.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#7] imaging-desaturate Cycles:10 Average:90.10 Median:85.50 stddev:12.54 (14.7%) stddev-sans-first:13.10 14:04:52 INFO - PROCESS | 2472 | Values: 84.0 87.0 86.0 84.0 84.0 84.0 85.0 97.0 124.0 86.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.63 (3.0%) stddev-sans-first:1.69 14:04:52 INFO - PROCESS | 2472 | Values: 54.0 53.0 55.0 58.0 57.0 55.0 56.0 55.0 54.0 53.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:71.50 stddev:4.19 (5.9%) stddev-sans-first:3.98 14:04:52 INFO - PROCESS | 2472 | Values: 67.0 70.0 71.0 83.0 74.0 73.0 71.0 71.0 71.0 72.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:85.50 stddev:9.42 (11.0%) stddev-sans-first:9.86 14:04:52 INFO - PROCESS | 2472 | Values: 87.0 85.0 90.0 97.0 106.0 109.0 83.0 85.0 85.0 86.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:139.00 stddev:6.03 (4.3%) stddev-sans-first:6.29 14:04:52 INFO - PROCESS | 2472 | Values: 137.0 139.0 138.0 140.0 139.0 139.0 137.0 138.0 157.0 137.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:168.00 stddev:28.80 (17.1%) stddev-sans-first:29.80 14:04:52 INFO - PROCESS | 2472 | Values: 159.0 164.0 149.0 222.0 238.0 168.0 168.0 168.0 167.0 167.0 14:04:52 INFO - PROCESS | 2472 | 14:04:52 INFO - PROCESS | 2472 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:68.50 stddev:6.93 (10.1%) stddev-sans-first:7.26 14:04:52 INFO - PROCESS | 2472 | Values: 68.0 67.0 80.0 87.0 71.0 68.0 69.0 66.0 67.0 67.0 14:04:52 INFO - PROCESS | 2472 | -------- Summary: end -------- 14:04:52 INFO - PROCESS | 2472 | 14:04:53 INFO - TEST-INFO | 2472: exit 0 14:04:53 INFO - TEST-OK | kraken | took 59886ms 14:04:53 INFO - SUITE-END | took 1178s 14:04:53 INFO - Completed test suite (00:19:38) 14:04:53 INFO - javascript benchmark 14:04:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.866765329387, 3351.7653764236507, 3354.312365964976, 3356.5972083350002, 3358.865645374187, 5615.157726341051, 5705.315362156537, 5712.086066229404, 5774.826755197346, 5786.768529258825, 5684.971575142118, 5753.513567487953, 5802.738876750545, 5810.331811841639, 5810.796622118227, 5049.621278404116, 5172.482751724835, 5192.376914770214, 5268.025415298163, 5272.446393128705, 4653.767311634405, 5380.408155102936, 5381.408045115034, 5389.622726409153, 5409.540189083933, 5150.201718733602, 5215.947840521584, 5216.7913283468615, 5268.157094864822, 5299.4435584263865, 3959.6238357356006, 5179.663321884103, 5190.0, 5242.344706911636, 5272.762725677316, 5243.2659427680155, 5255.185446255837, 5263.44733802949, 5263.4473380295285, 5281.63028587999, 5818.44724751148, 5819.970900145472, 5823.88352232953, 5829.329627092896, 5833.2416785817995, 6130.111133885562, 6228.0, 6272.529560282951, 6273.6235825850135, 6300.779472718433, 6288.842778930518, 6313.273973493061, 6321.146645202884, 6322.841928951813, 6343.111964324999, 5708.343540492897, 5883.911741323884, 5896.4398382153195, 5903.409659034105, 5917.644941303535, 6272.184615999982, 6310.5898116622275, 6317.1156038154695, 6324.9051264231075, 6374.84062898422, 6171.537134714914, 6238.688065596702, 6240.344763799826, 6248.750049997949, 6262.467690246289, 6319.652419116993, 6327.525435592349, 6328.082428047999, 6340.4321249893865, 6341.619502829845, 6087.634741915499, 6156.199694039746, 6170.197874276405, 6208.068789681584, 6232.8130156095385, 6157.291911430153, 6195.287541932646, 6258.74965001404, 6268.686565671698, 6312.24253089623, 4999.775010124553, 5000.399952005782, 5002.1496345621335, 5003.574696150795, 5007.574356179765, 4458.175237581057, 4466.196084704719, 4468.084042771289, 4484.394606728115, 4486.7083639563325, 5648.548116150698, 5726.226959360433, 5756.394884092709, 5783.91470448697, 5821.592488525763, 6310.274318453345, 6326.905096423558, 6328.398802113792, 6332.651704156224, 6334.5249106317215, 5847.649141051551, 5853.795117170878, 5866.2960444746905, 5906.911396329058, 5917.230760001173, 5370.597205209625, 5405.648632838932, 5430.728463576806, 5459.989901868167, 5494.230807686927, 6430.196225471816, 6435.935640643534, 6438.034294855809, 6444.709988050643, 6453.096566480696, 5582.581306401955, 6315.705280417412, 6329.7468101275435, 6334.904976425265, 6346.650934198478, 6264.718087686157, 6324.3991820776955, 6343.238811342669, 6383.234011918599, 6439.839004024937, 6197.659128747782, 6242.937570624235, 6244.562880598314, 6257.68711564429, 6268.937310626835, 6283.654399008098, 6332.208473940757, 6332.556721029484, 6334.90497642545, 6350.333215012357, 4898.0, 5111.923321150261, 5701.458361455655, 6005.519558435228, 6229.719662615103, 6122.724477398617, 6138.109974053827, 6149.292831324455, 6159.476444502088, 6165.198524191826, 5930.703464826655, 5952.196453478725, 5959.344472108151, 5961.225040744675, 5963.28440587115, 5997.220361352996, 6073.817785466443, 6092.543059270483, 6105.9694701526205, 6125.601835880653, 5986.281646202484, 6021.729022194099, 6029.668368239789, 6038.335783063947, 6041.24484439445], "value": 5728.149046012372, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.8021861583289, 516.5222169493219, 524.3890867139783, 532.7176596403905, 551.618196417973, 31540.684593154092, 31746.841265793668, 33375.33249335015, 33393.99818005458, 33431.498527522104, 42958.0, 44028.3395749064, 44193.77903110488, 46392.768036159774, 46503.534964650346, 9874.851877221847, 10027.59889604415, 10041.04610062043, 10053.296269261155, 10059.49702514875, 8928.37501374904, 10031.799364012715, 10101.444420556867, 10115.54480048396, 10117.696469105938, 8986.415882967618, 9165.175134237918, 9202.907970920272, 9205.355625106245, 9218.815623687522, 12003.579874704386, 12313.322767247797, 12331.445084971198, 12591.24452532851, 12594.559190428292, 10242.846357304647, 12103.576374826927, 12268.509259629604, 12301.384930753517, 12336.198147120409, 41376.793116034525, 41703.1659366811, 41712.37431438501, 41736.1652766946, 41765.58234417678, 3393.5927688677516, 3418.1967237699123, 3441.5353927219753, 3458.775179613317, 3533.116720819795, 31703.682963170304, 32797.0, 33083.3383332332, 33185.83407082949, 33455.6654433455, 3251.235959549504, 3329.0845017620104, 3379.273456206927, 3440.122768693992, 3468.8959331220103, 488934.0, 489994.99999999645, 501363.0, 519190.0, 625564.0, 58821.0, 60019.699901500215, 60068.79862402727, 60115.0, 60272.39727602756, 52090.73954630203, 63326.0501092484, 63515.68242158852, 63570.0, 63727.36272637307, 15958.1223032732, 16147.0, 16175.757363639555, 16213.351465941467, 16360.509184724477, 12722.236665800081, 12743.681407964876, 12753.744925101631, 12795.872041279654, 12796.93601532005, 17863.374781882572, 17868.374606888698, 17886.642267154843, 17933.820661793216, 17951.910240448713, 5550.611457198038, 6356.555041147075, 6413.9416996195105, 6787.7600391870565, 6959.652017399109, 749.5465243527588, 1004.7488127968008, 1031.664708969588, 1045.2213101239624, 1090.1278976818514, 28687.13938581846, 30321.393572128432, 30517.38965220683, 31196.702884975646, 31344.05967820969, 916.3127654259306, 1043.8799538053222, 1100.526773487408, 1109.1958330210532, 1152.9711757206137, 15879.755940274861, 15911.68176636484, 15915.761263581055, 16148.031118132949, 16359.83640163607, 660113.0, 666214.0, 667565.6621716861, 669909.0, 671050.0, 51929.701757456365, 52836.679083023235, 60095.69952150299, 60512.697436512535, 60524.0, 1371.8833899118686, 1383.6817531967906, 1422.1396055386342, 1422.3386125451507, 1431.5848403962734, 23525.836169142, 30669.079927602208, 31842.522362164123, 32200.35599288048, 32378.0, 52906.735466322425, 53603.19595206153, 53702.46297537131, 53770.46229537654, 53770.73114634402, 603798.0, 607098.0, 653574.0, 656147.0, 666034.0, 26220.606690900033, 26556.8672156638, 26569.468610627293, 26888.32779180536, 26932.922683092456, 46914.29628555506, 47156.29265561088, 47594.334198302895, 47774.0, 48322.758386207846, 32319.384030798836, 38358.23283534258, 38832.22335553329, 39198.0, 39676.206475870895, 39065.80467097646, 39971.9173853296, 40187.0, 40651.1869762609, 40962.0, 37569.248615027, 37647.81176094102, 38508.80745596254, 38598.42102368523, 38668.6133138665, 32750.181245469055, 34139.93677972945, 34490.86109666856, 34589.65410345864, 35434.822825885705, 19724.30964916221, 20903.12528091566, 25079.87460062688, 25885.09402170915, 26507.072252471073], "value": 20334.659742704505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7379552233583, 366.1322665283279, 370.85536640710126, 380.72397511803945, 394.5994815262509, 15779.28993195306, 16566.088865112415, 20614.1754329827, 21131.260405885798, 21235.25676601317, 20954.895225523887, 21111.472213194676, 21382.572348553018, 21455.356339309845, 21758.347249582497, 5986.73059712314, 6030.155778191057, 6169.722362493699, 6194.442500174983, 6252.093446450262, 6114.419130182626, 6213.974694175456, 6225.221847269091, 6245.406686364788, 6255.562110652256, 4183.707140500166, 5690.60165788395, 6714.328567143295, 6789.932100679004, 6820.35206655367, 6503.447206987412, 6724.462043036546, 6796.0, 6798.592084474898, 6805.013273075426, 5856.736446859902, 5860.267466566679, 5861.0, 5862.589618726691, 5867.677277749722, 24381.63427548588, 25450.0, 26661.0, 26795.73204267952, 27082.72917270822, 2436.1838784007377, 2492.700875894886, 2718.4563087382403, 3003.7446817020577, 3074.3851229753914, 10261.230407719451, 10287.022732840369, 10287.639932602398, 10342.7414314642, 14086.647833804134, 1623.902565846053, 1645.12808211648, 1758.9296428142986, 1838.0901453780464, 1940.7865134835156, 113812.0, 120224.0, 121051.0, 125492.0, 127245.45509089858, 9361.90638093624, 9647.807043859084, 10034.09693127622, 10050.899491005142, 11479.0, 12378.381080945917, 12461.940735037624, 12462.507498500336, 12485.063620228519, 12528.0, 7964.561949092856, 8139.918600814034, 8147.185281471805, 8242.546659933761, 8255.174482551696, 7054.294570542905, 8513.872291915626, 8514.318854491627, 8543.658253669908, 8678.435901666371, 3154.6845315468727, 3927.096767743435, 3929.685625149981, 3974.18529201513, 4006.7395619284653, 1868.9065546722882, 1915.4445210888964, 1916.377177417345, 1916.48254971157, 1920.1647283431473, 852.5609311204735, 856.246503077288, 883.4054531570577, 915.2037727177266, 969.8399764038995, 20922.267720629705, 21556.245531406326, 21921.01355439009, 21963.450913727283, 21995.23016694408, 894.3605322194662, 905.2034209895251, 950.5532399772096, 983.3460748602242, 994.5574219472265, 9726.610935562498, 9927.602895884229, 10942.781144377068, 11434.0, 11437.485313160762, 582471.0, 583686.0, 590949.0, 598656.0, 600096.0, 95798.0, 129185.0, 132918.00622990663, 132949.33525332506, 134977.0, 942.241495596038, 958.4249450329886, 979.6277414582413, 981.3964411886639, 984.4536282363214, 18108.818911810715, 18554.701170917906, 19106.235750570377, 19355.806441935965, 19414.902925485283, 50512.0, 53712.0, 56337.316449677106, 56762.0, 56904.29287121393, 77831.0, 78730.0, 80058.19941800507, 81997.59001204956, 83046.58476707578, 7914.129445761077, 8062.153473885158, 8123.512589244664, 8238.176182381714, 8375.288100511525, 12484.0, 13204.735905282032, 13257.0, 13570.253636049718, 13752.381142848795, 9104.453732776055, 11181.776364472827, 11431.542738290374, 11561.537538498365, 11643.88356116462, 10877.51051202682, 11223.326600404002, 11458.369789661649, 11647.660519040419, 11805.107748601647, 11033.558657653604, 11650.825237621611, 12023.338716370334, 12063.336516491678, 12105.031597472358, 7666.4633475656165, 7814.765557033071, 7836.2760192757505, 8230.176982301722, 8268.917310827055, 8897.555122243732, 9647.118811831626, 10679.626213082505, 11261.0, 11925.582604608797], "value": 9593.29329266681, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.6607881950692, 1331.9533816316432, 1467.5817392043266, 1589.984100158999, 1811.719183526553, 7783.0, 8607.870881936777, 8852.380333376666, 8862.734117976463, 8927.28581713463, 8664.263537599296, 8746.562671866413, 8753.824923501526, 8759.386842920998, 8777.64889404423, 2357.116081469453, 2385.654080158376, 2387.0929046962215, 2390.8924098415614, 2393.6050551658955, 1956.4228552576953, 1969.9212031518723, 2323.361075704178, 2369.609014512603, 2376.5959786836193, 2349.0955981946904, 2355.5995480768224, 2366.100881664965, 2386.3079706884982, 2386.78518933296, 1958.8237058664647, 2330.4057465346227, 2342.4261056041296, 2355.823313251513, 2356.5640356534095, 2304.5275718477837, 2352.9764702352927, 2357.1278626907983, 2357.4695693469002, 2358.740538540759, 8757.912420875773, 8828.779280517974, 8835.469871807712, 8841.425307355, 8846.955765221133, 5220.112580861262, 5400.324959380077, 5413.837584872421, 5422.728863556807, 5427.864303392407, 3568.57177138745, 3582.140286331288, 3632.3098611263777, 3650.0874781304674, 3650.653187947141, 1036.7045392063226, 1056.4717641179411, 1065.4406436620836, 1074.505727365413, 1074.8925107489267, 9941.35381200227, 11594.768104637944, 11697.94151029248, 11731.941340293244, 11801.586944456989, 3695.9630403696156, 4463.263561512379, 4611.538846115362, 4628.675992680547, 4631.768411579407, 9127.224185944136, 9128.634854605742, 9142.725718228463, 9151.039140890243, 9161.862572061424, 5191.273221748958, 5203.531682148625, 5203.687778733288, 5209.661372010883, 5210.21846722987, 4393.143337049245, 4574.359589657451, 4758.666893317434, 4857.6599638025, 4896.681715688469, 3758.379867321861, 3763.5295588051495, 3880.5925377835483, 3911.2568611963634, 3917.7061720370516, 1180.0677464802882, 1194.5759255464359, 1195.910306726999, 1198.5804968261214, 1209.9334536600572, 843.7468759372164, 905.8958219804675, 919.9632014719336, 924.6624981881565, 960.5485421851719, 5089.643724939293, 5746.13807928814, 6016.879662406727, 6130.448259656652, 6148.631082135086, 444.8665400379873, 458.0655462855737, 461.3610149942354, 465.234688936704, 471.0602348315133, 3611.5485564304463, 3868.535775706877, 3980.5223373195404, 4109.4246805447265, 4129.15352352767, 10325.9483702581, 10695.465226738632, 10754.139668826476, 10758.784824303626, 10784.838227426595, 9792.608295668237, 10671.626493072705, 10683.09193718527, 10713.196510261761, 10730.0, 7641.541507509567, 7724.7682569523, 8214.63034163464, 8214.8357032859, 8219.342452603778, 5578.7447824239225, 5727.1695604138, 6143.877122457615, 6186.443220110212, 6194.893736129738, 9512.191463725661, 9839.114479696862, 10005.299629025898, 10022.0, 10134.543945522322, 8605.784855378415, 9699.078587534173, 9878.950605246928, 10281.537330819981, 10303.639372621923, 5503.587230957614, 6511.544191906711, 6705.497087718538, 6965.442764578923, 7593.088829340514, 8256.422050456411, 8318.667253310043, 8326.167383261625, 8331.50010999342, 8354.832903342021, 7244.601546914971, 7285.963570182115, 7418.925810742039, 7511.0, 7527.247275272429, 7199.2440793715905, 7246.855062898818, 7270.6728197230195, 7287.744928927462, 7321.890171647536, 6895.862082758216, 6934.375906168469, 7019.894701579378, 7030.683619237045, 7037.648117594203, 5016.372953380827, 5065.392152941523, 5152.690838549699, 5163.199704045879, 5243.764030618555, 4991.201407774739, 5075.5864292751985, 5129.9743501282255, 5175.663581867167, 5296.099663057213], "value": 4432.93854887913, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.9254916074338, 294.95870578119053, 310.7467414057543, 312.0404755377216, 313.96389415217243, 4559.8404055858055, 5032.5219104185135, 5207.505286997739, 5242.580593552516, 5311.141100614787, 4959.752012399384, 5124.92312615311, 5170.224466330053, 5170.974145129269, 5211.817586384468, 3996.42051902474, 4060.21350605734, 4061.390791381294, 4061.57353477885, 4063.2889244382263, 3831.9042023949487, 4012.217617564576, 4038.0, 4046.9392959105635, 4047.494063242095, 3723.7021038317007, 3801.8669346572874, 3907.8241479133508, 3989.940150897739, 4006.559278479365, 3655.0131464504702, 3663.303972245265, 3998.820053097618, 3999.2401443725744, 4026.7583944963103, 2904.8547572621496, 3510.7542472026967, 3539.504469374264, 3550.396432606463, 3574.4459608760685, 4685.2972054191805, 5093.210552364352, 5120.564751996085, 5145.768440420153, 5170.844874653766, 4247.426597409341, 4442.755648439334, 4494.033782736695, 4495.561420345491, 4498.527654596253, 5241.528262456397, 5459.181122831607, 5481.945180548183, 5491.670499770026, 5508.6970216638065, 3833.271678381099, 3893.649571538575, 3900.258950799339, 3910.8658489038144, 3919.490466239399, 5020.560915772671, 5053.646244762905, 5106.387233531963, 5538.501534861842, 5796.333421656522, 3928.548216955087, 3952.9802350988634, 3953.940690889639, 3979.840806367713, 3997.3004724173734, 3508.4737289406794, 3867.0912335601674, 3959.208757825726, 4009.1580768038457, 4011.4183443400543, 4519.84180553679, 4564.1556312082375, 4610.515895830957, 4622.907541849144, 4637.420322459692, 5551.3893471718075, 5558.333000040021, 5580.330360356783, 5597.328320601554, 5650.74571644277, 1131.1968502363413, 1295.863934286905, 1310.5937159480402, 1312.4094157629106, 1335.6660834791303, 845.9154084591614, 949.9597940255409, 956.0296299256261, 958.4824194934797, 958.9212136346611, 5178.223266510054, 5180.870478238075, 5196.818111366084, 5212.713300768418, 5214.556762675215, 4622.0, 4961.900761984813, 5009.5741861941415, 5655.519280861173, 5686.914696279559, 5084.0, 5086.694798312039, 5099.311592934981, 5116.360454943132, 5133.794648214104, 2175.053327272114, 2317.8145748340094, 2328.2316835444262, 2392.377981724729, 2504.5742223821994, 4900.705957642553, 4909.509049095133, 5442.673439593637, 5954.761809527657, 6024.487918526975, 5148.279240906276, 5204.297419848348, 5471.398146203915, 5926.229590153339, 5929.495992840571, 4493.0115374617535, 5438.2325744134105, 5954.464098231093, 5993.910091348546, 6027.0, 5415.756290966995, 5802.883942321046, 5808.448197421239, 5823.631446610128, 5826.388229235869, 5759.596828221985, 5762.423757624204, 5764.884702306014, 5765.4522820332, 5791.82624521265, 5558.277423934863, 5689.34572524165, 5986.401359864153, 6029.57792954489, 6030.48740857032, 3463.1169051891607, 3605.7836529808296, 3853.3064048470424, 3876.573576906482, 3879.398693202558, 5836.562257830765, 5863.589548731547, 5865.736041878211, 5892.410758924073, 5893.734781934738, 5527.977324195037, 5630.97284977416, 5662.405447428126, 5680.602357834912, 5723.7424315905055, 4570.840020599263, 5319.813806516753, 5605.766731319103, 5619.550435965195, 5648.661080335193, 4310.241445474961, 4610.608098311547, 5350.9933774834435, 5498.175273708815, 5502.174673798802, 5158.303629010035, 5161.483851614958, 5647.915281270866, 5701.800436984686, 5731.713414329351, 4751.028323245952, 4772.976135119302, 5205.245239440335, 5372.435894231206, 5446.210299506629], "value": 4051.6932989563716, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.9235584085751, 717.0785540580354, 725.1298441869758, 747.8055705516567, 764.1288930619091, 15519.379224831007, 15899.52301430956, 16319.347226110956, 16557.337706491755, 16669.41657042004, 14790.630234244149, 14851.405943762236, 14986.0, 14990.625234369147, 15015.849841501582, 867.7787164273117, 881.951492667903, 886.5301390263138, 887.0641473245723, 888.8177923525661, 693.5491930245356, 718.7879575525233, 863.434450434963, 890.1721399098835, 902.9097090290984, 939.3143005605912, 941.7268991992314, 942.7784470649392, 942.9905700943006, 943.0522325063301, 931.483026920059, 934.0472717827846, 936.4334577580602, 936.5644975086617, 936.8641546975718, 542.5632365945412, 559.035663480497, 559.9384067752585, 562.9859253518695, 662.6057495789997, 12814.42335094923, 14982.10107393549, 15051.172185529791, 15116.697666046617, 15166.848331516654, 14040.789388159186, 14155.929220353832, 14316.713665726731, 14513.27433628325, 14562.344694488773, 12982.35088245584, 13221.86778132216, 13236.867631323756, 13304.800427993589, 13599.728005439934, 4464.15181115587, 4543.363929049936, 4589.173948689235, 4618.145643056046, 4686.2267725825195, 114829.4258528702, 114904.0, 115283.0, 115792.0, 116566.99999999916, 10444.582216711246, 10590.417527035934, 10944.945275273572, 11052.668419947415, 11540.076793856471, 21196.15215391367, 21534.78465215359, 21549.24577639775, 21558.353249402542, 21603.567928641653, 7743.5353878767455, 7820.843583128306, 7877.7636670899965, 8007.03827562251, 8022.237887400687, 4464.687471877002, 4474.451281795734, 4484.641228701761, 4591.0, 4675.649326300541, 3581.964180358163, 3708.517892673935, 3709.295233905549, 3709.499217605589, 3710.9443358349645, 567.9488846003796, 670.8591195848829, 677.5866721300004, 677.9084823548758, 679.2358596578849, 566.9886602268014, 570.403927895342, 578.673049726903, 603.408659513679, 628.1551313483449, 10148.594056237667, 10325.432101234504, 10380.429076400871, 10406.546021128737, 10484.318519296221, 574.8304250246188, 591.5504216795266, 595.4075694683818, 625.1185827982523, 689.5931400473745, 4508.684392092588, 4679.859604211879, 4695.835645752382, 4716.1510928033285, 4847.903041939212, 135569.0, 143439.28280358532, 149517.0, 153418.23290883473, 157268.0, 126621.0, 126864.0, 128905.35547322391, 129886.0, 131912.68087319197, 105030.94969050212, 108449.0, 108655.0, 109452.0, 113198.0, 8544.871826922725, 9052.683156089506, 9165.0, 9188.356815022884, 9286.72139835806, 53824.19263711126, 56002.0, 58126.0, 61930.38069619426, 62632.0, 88488.67266991129, 91205.0, 94682.52658736662, 96069.0, 99244.50377748066, 8630.741077767678, 12956.0, 13490.932545337211, 14004.369803359077, 14598.489052883522, 13813.102148360324, 14423.92788036095, 14894.404223830925, 15838.445654402041, 15858.841411586198, 14101.21257238193, 14461.421543138156, 14489.855101448851, 14490.927545362627, 14768.704625907636, 13936.651583710489, 14216.857831421554, 14235.0, 14498.927505362406, 14704.852951470777, 13466.865331346562, 13804.516841910485, 14039.719205616035, 14158.362873670916, 14436.494722684656, 10680.83978740335, 11470.19708620422, 11687.532498700297, 11694.532218711156, 11946.522139114339, 10722.89277107219, 11330.634143918065, 11547.191696581494, 11664.6500604982, 11736.70658233551], "value": 7946.367749638979, "unit": "score"}], "name": "dromaeo_css", "value": 7364.2306074225335, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 114.0, 108.0, 114.0, 114.0, 114.0, 116.0, 113.0, 114.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 137.0, 164.0, 167.0, 107.0, 129.0, 130.0, 107.0, 191.0, 110.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 183.0, 184.0, 184.0, 183.0, 180.0, 181.0, 183.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 111.0, 99.0, 73.0, 85.0, 86.0, 115.0, 87.0, 57.0], "value": 86.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 141.0, 71.0, 71.0, 74.0, 120.0, 56.0, 66.0, 143.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 88.0, 89.0, 86.0, 91.0, 90.0, 135.0, 130.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 95.0, 94.0, 94.0, 94.0, 95.0, 93.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 84.0, 84.0, 84.0, 85.0, 97.0, 124.0, 86.0], "value": 90.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 58.0, 57.0, 55.0, 56.0, 55.0, 54.0, 53.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 71.0, 83.0, 74.0, 73.0, 71.0, 71.0, 71.0, 72.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 90.0, 97.0, 106.0, 109.0, 83.0, 85.0, 85.0, 86.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 138.0, 140.0, 139.0, 139.0, 137.0, 138.0, 157.0, 137.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 149.0, 222.0, 238.0, 168.0, 168.0, 168.0, 167.0, 167.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 80.0, 87.0, 71.0, 68.0, 69.0, 66.0, 67.0, 67.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1501.6999999999998, "alertThreshold": 2.0}]} 14:04:54 INFO - Return code: 0 14:04:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:04:54 INFO - # TBPL SUCCESS # 14:04:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:04:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:04:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:04:54 INFO - 2016-08-23 14:04:54.110 screenresolution[2476:16401] starting screenresolution argv=screenresolution get 14:04:54 INFO - 2016-08-23 14:04:54.113 screenresolution[2476:16401] Display 0: 1600x1200x32@60 14:04:54 INFO - 2016-08-23 14:04:54.119 screenresolution[2477:16404] starting screenresolution argv=screenresolution list 14:04:54 INFO - Available Modes on Display 0 14:04:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:04:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:04:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:04:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:04:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:04:54 INFO - Intel Iris: 14:04:54 INFO - Chipset Model: Intel Iris 14:04:54 INFO - Type: GPU 14:04:54 INFO - Bus: Built-In 14:04:54 INFO - VRAM (Dynamic, Max): 1536 MB 14:04:54 INFO - Vendor: Intel (0x8086) 14:04:54 INFO - Device ID: 0x0a2e 14:04:54 INFO - Revision ID: 0x0009 14:04:54 INFO - Displays: 14:04:54 INFO - Display: 14:04:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:04:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:04:54 INFO - Display Serial Number: 1600x1200 60 14:04:54 INFO - Main Display: Yes 14:04:54 INFO - Mirror: Off 14:04:54 INFO - Online: Yes 14:04:54 INFO - Rotation: Supported 14:04:54 INFO - Return code: 0 14:04:54 INFO - Running post-action listener: _resource_record_post_action 14:04:54 INFO - [mozharness: 2016-08-23 21:04:54.355898Z] Finished run-tests step (success) 14:04:54 INFO - Running post-run listener: _resource_record_post_run 14:04:54 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 831488; Write bytes: 704862208; Read time: 12; Write time: 2087 14:04:54 INFO - TinderboxPrint: CPU usage
53.5% 14:04:54 INFO - TinderboxPrint: I/O read bytes / time
831,488 / 12 14:04:54 INFO - TinderboxPrint: I/O write bytes / time
704,862,208 / 2,087 14:04:54 INFO - TinderboxPrint: CPU idle
2,257.6 (46.5%) 14:04:54 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 14:04:54 INFO - TinderboxPrint: CPU user
1,622.3 (33.4%) 14:04:54 INFO - TinderboxPrint: Swap in / out
407,465,984 / 0 14:04:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393280000; Write bytes: 387454464; Read time: 29359; Write time: 683 14:04:54 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 823296; Write bytes: 295141888; Read time: 12; Write time: 1355 14:04:54 INFO - Running post-run listener: _upload_blobber_files 14:04:54 INFO - Blob upload gear active. 14:04:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:04:54 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 14:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:04:55 INFO - (blobuploader) - INFO - Open directory for files ... 14:04:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:04:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:04:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:56 INFO - (blobuploader) - INFO - Done attempting. 14:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:04:58 INFO - (blobuploader) - INFO - Done attempting. 14:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:05:00 INFO - (blobuploader) - INFO - Done attempting. 14:05:00 INFO - (blobuploader) - INFO - Iteration through files over. 14:05:00 INFO - Return code: 0 14:05:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:05:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:05:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73b5e7c72581828f74262ccfc44bc3146c18228e2e086d3db289c59c3187cf5401487e7d5965f5dcc9dbd504a8dcbeede5456f77f1951cef00338e5c55c72c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9133fdde2eb4ef0f6919d7469141126f68911a5b541c8fea496342f3fa858b92b626c2afce6a5c5f7ecd870e02b5961a84c3d818a53a7f75ff9293c8092c3579", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/10cef619879426a0489850aa272631836d17b2368887f6f4243b53988ab73bcbf099a3812d3984174acffef8c1d5d01138d1ae18c8fd1bacc3f29ebf80f29ca4"} 14:05:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:05:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:05:00 INFO - Contents: 14:05:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73b5e7c72581828f74262ccfc44bc3146c18228e2e086d3db289c59c3187cf5401487e7d5965f5dcc9dbd504a8dcbeede5456f77f1951cef00338e5c55c72c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9133fdde2eb4ef0f6919d7469141126f68911a5b541c8fea496342f3fa858b92b626c2afce6a5c5f7ecd870e02b5961a84c3d818a53a7f75ff9293c8092c3579", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/10cef619879426a0489850aa272631836d17b2368887f6f4243b53988ab73bcbf099a3812d3984174acffef8c1d5d01138d1ae18c8fd1bacc3f29ebf80f29ca4"} 14:05:00 INFO - Running post-run listener: copy_logs_to_upload_dir 14:05:00 INFO - Copying logs to upload dir... 14:05:00 INFO - mkdir: /builds/slave/test/build/upload/logs 14:05:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.008907 ========= master_lag: 9.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-08-23 14:05:09.757083) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-08-23 14:05:09.765641) ========= 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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73b5e7c72581828f74262ccfc44bc3146c18228e2e086d3db289c59c3187cf5401487e7d5965f5dcc9dbd504a8dcbeede5456f77f1951cef00338e5c55c72c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9133fdde2eb4ef0f6919d7469141126f68911a5b541c8fea496342f3fa858b92b626c2afce6a5c5f7ecd870e02b5961a84c3d818a53a7f75ff9293c8092c3579", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/10cef619879426a0489850aa272631836d17b2368887f6f4243b53988ab73bcbf099a3812d3984174acffef8c1d5d01138d1ae18c8fd1bacc3f29ebf80f29ca4"} build_url:https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011833 build_url: 'https://queue.taskcluster.net/v1/task/B4m9N7l8Sf24uKZZ7ZCcPA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73b5e7c72581828f74262ccfc44bc3146c18228e2e086d3db289c59c3187cf5401487e7d5965f5dcc9dbd504a8dcbeede5456f77f1951cef00338e5c55c72c2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9133fdde2eb4ef0f6919d7469141126f68911a5b541c8fea496342f3fa858b92b626c2afce6a5c5f7ecd870e02b5961a84c3d818a53a7f75ff9293c8092c3579", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/10cef619879426a0489850aa272631836d17b2368887f6f4243b53988ab73bcbf099a3812d3984174acffef8c1d5d01138d1ae18c8fd1bacc3f29ebf80f29ca4"}' ========= master_lag: 7.69 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-08-23 14:05:17.467761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 16 secs) (at 2016-08-23 14:05:17.468093) ========= 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.RKXtgP6BOQ/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.ol2lJBAnXm/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005304 ========= master_lag: 16.57 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 16 secs) (at 2016-08-23 14:05:34.041800) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 14:05:34.042117) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 14:05:34.718122) ========= ========= Total master_lag: 33.37 =========