builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0030 starttime: 1470736125.89 results: success (0) buildid: 20160809000920 builduid: b750acbbc39a478f8762334247c4ef18 revision: 2edc35144ebf50ca6339e686e766e200cc4b04b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:45.893321) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:45.893744) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-08-09 02:48:45.894035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-08-09 02:48:47.720088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:47.720449) ========= 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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-09 02:48:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.53M=0.001s 2016-08-09 02:48:47 (9.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087777 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:48.251969) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:48.252278) ========= 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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.031771 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:48.305610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 02:48:48.305942) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2edc35144ebf50ca6339e686e766e200cc4b04b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2edc35144ebf50ca6339e686e766e200cc4b04b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-09 02:48:48,378 truncating revision to first 12 chars 2016-08-09 02:48:48,378 Setting DEBUG logging. 2016-08-09 02:48:48,378 attempt 1/10 2016-08-09 02:48:48,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2edc35144ebf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 02:48:49,783 unpacking tar archive at: fx-team-2edc35144ebf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.666179 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 02:48:49.993963) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:49.994283) ========= script_repo_revision: 2edc35144ebf50ca6339e686e766e200cc4b04b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:49.994739) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:49.995007) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 02:48:50.013045) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-09 02:48:50.013341) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:48:50 INFO - MultiFileLogger online at 20160809 02:48:50 in /builds/slave/test 02:48:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:48:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:48:50 INFO - {'append_to_log': False, 02:48:50 INFO - 'base_work_dir': '/builds/slave/test', 02:48:50 INFO - 'blob_upload_branch': 'Fx-Team', 02:48:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:48:50 INFO - 'branch': 'Fx-Team', 02:48:50 INFO - 'buildbot_json_path': 'buildprops.json', 02:48:50 INFO - 'config_files': ('talos/mac_config.py',), 02:48:50 INFO - 'default_actions': ('clobber', 02:48:50 INFO - 'read-buildbot-config', 02:48:50 INFO - 'download-and-extract', 02:48:50 INFO - 'populate-webroot', 02:48:50 INFO - 'create-virtualenv', 02:48:50 INFO - 'install', 02:48:50 INFO - 'run-tests'), 02:48:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:48:50 INFO - 'download_minidump_stackwalk': True, 02:48:50 INFO - 'download_symbols': 'ondemand', 02:48:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:48:50 INFO - 'tooltool.py': '/tools/tooltool.py', 02:48:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:48:50 INFO - '/tools/misc-python/virtualenv.py')}, 02:48:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:48:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:48:50 INFO - 'installer_path': 'installer.exe', 02:48:50 INFO - 'log_level': 'info', 02:48:50 INFO - 'log_name': 'talos', 02:48:50 INFO - 'log_to_console': True, 02:48:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:48:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:48:50 INFO - 'opt_config_files': (), 02:48:50 INFO - 'pip_index': False, 02:48:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:48:50 INFO - 'cmd': ('bash', 02:48:50 INFO - '-c', 02:48:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:48:50 INFO - 'enabled': True, 02:48:50 INFO - 'halt_on_failure': False, 02:48:50 INFO - 'name': 'check_screen_resolution'},), 02:48:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:48:50 INFO - 'cmd': ('bash', 02:48:50 INFO - '-c', 02:48:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:48:50 INFO - 'enabled': True, 02:48:50 INFO - 'halt_on_failure': False, 02:48:50 INFO - 'name': 'check_screen_resolution'},), 02:48:50 INFO - 'run_cmd_checks_enabled': True, 02:48:50 INFO - 'sps_profile': False, 02:48:50 INFO - 'sps_profile_interval': 0, 02:48:50 INFO - 'suite': 'dromaeojs', 02:48:50 INFO - 'system_bits': '32', 02:48:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:48:50 INFO - 'title': 't-yosemite-r7-0030', 02:48:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:48:50 INFO - 'use_talos_json': True, 02:48:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:48:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:48:50 INFO - 'work_dir': 'build'} 02:48:50 INFO - [mozharness: 2016-08-09 09:48:50.203139Z] Running clobber step. 02:48:50 INFO - Running pre-action listener: _resource_record_pre_action 02:48:50 INFO - Running main action method: clobber 02:48:50 INFO - rmtree: /builds/slave/test/build 02:48:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:48:52 INFO - Running post-action listener: _resource_record_post_action 02:48:52 INFO - [mozharness: 2016-08-09 09:48:52.445908Z] Finished clobber step (success) 02:48:52 INFO - [mozharness: 2016-08-09 09:48:52.446032Z] Running read-buildbot-config step. 02:48:52 INFO - Running pre-action listener: _resource_record_pre_action 02:48:52 INFO - Running main action method: read_buildbot_config 02:48:52 INFO - Using buildbot properties: 02:48:52 INFO - { 02:48:52 INFO - "project": "", 02:48:52 INFO - "product": "firefox", 02:48:52 INFO - "script_repo_revision": "production", 02:48:52 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:48:52 INFO - "repository": "", 02:48:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:48:52 INFO - "buildid": "20160809000920", 02:48:52 INFO - "pgo_build": "False", 02:48:52 INFO - "basedir": "/builds/slave/test", 02:48:52 INFO - "buildnumber": 107, 02:48:52 INFO - "slavename": "t-yosemite-r7-0030", 02:48:52 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:48:52 INFO - "platform": "yosemite_r7", 02:48:52 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 02:48:52 INFO - "branch": "fx-team", 02:48:52 INFO - "repo_path": "integration/fx-team", 02:48:52 INFO - "revision": "2edc35144ebf50ca6339e686e766e200cc4b04b8", 02:48:52 INFO - "stage_platform": "macosx64", 02:48:52 INFO - "builduid": "b750acbbc39a478f8762334247c4ef18", 02:48:52 INFO - "slavebuilddir": "test" 02:48:52 INFO - } 02:48:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:48:52 INFO - Running post-action listener: _resource_record_post_action 02:48:52 INFO - [mozharness: 2016-08-09 09:48:52.449388Z] Finished read-buildbot-config step (success) 02:48:52 INFO - [mozharness: 2016-08-09 09:48:52.449500Z] Running download-and-extract step. 02:48:52 INFO - Running pre-action listener: _resource_record_pre_action 02:48:52 INFO - Running main action method: download_and_extract 02:48:52 INFO - mkdir: /builds/slave/test/build/tests 02:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:52 INFO - trying https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:48:52 INFO - Downloading https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:48:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:48:53 INFO - Downloaded 1199 bytes. 02:48:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:48:53 INFO - Using the following test package requirements: 02:48:53 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:48:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:48:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:48:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'jsshell-mac.zip'], 02:48:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:48:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:48:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:48:53 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:48:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:48:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:48:53 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:48:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:48:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:53 INFO - trying https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:48:53 INFO - Downloading https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:48:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:48:54 INFO - Downloaded 16324193 bytes. 02:48:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:48:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:48:57 INFO - Return code: 0 02:48:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:57 INFO - trying https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:48:57 INFO - Downloading https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:48:58 INFO - Downloaded 16324193 bytes. 02:48:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:48:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:49:02 INFO - Return code: 0 02:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:02 INFO - trying https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:49:02 INFO - Downloading https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:49:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:49:03 INFO - Downloaded 14413723 bytes. 02:49:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:49:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:49:03 INFO - Return code: 0 02:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:03 INFO - trying https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:49:03 INFO - Downloading https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:49:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/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 02:49:06 INFO - Downloaded 111949690 bytes. 02:49:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:49:06 INFO - mkdir: /builds/slave/test/properties 02:49:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:49:06 INFO - Writing to file /builds/slave/test/properties/build_url 02:49:06 INFO - Contents: 02:49:06 INFO - build_url:https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:49:07 INFO - Running post-action listener: _resource_record_post_action 02:49:07 INFO - Running post-action listener: set_extra_try_arguments 02:49:07 INFO - [mozharness: 2016-08-09 09:49:07.374600Z] Finished download-and-extract step (success) 02:49:07 INFO - [mozharness: 2016-08-09 09:49:07.374766Z] Running populate-webroot step. 02:49:07 INFO - Running pre-action listener: _resource_record_pre_action 02:49:07 INFO - Running main action method: populate_webroot 02:49:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:49:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:49:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:49:07 INFO - '--tppagecycles', 02:49:07 INFO - '7'], 02:49:07 INFO - 'tests': ['tsvgm']}}, 02:49:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:49:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:49:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:49:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:49:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['damp', 'tps']}, 02:49:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'tests': ['damp', 'tps']}, 02:49:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['dromaeo_dom']}, 02:49:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:49:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['basic_compositor_video']}, 02:49:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:49:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['a11yr', 02:49:07 INFO - 'ts_paint', 02:49:07 INFO - 'tpaint', 02:49:07 INFO - 'sessionrestore', 02:49:07 INFO - 'sessionrestore_no_auto_restore', 02:49:07 INFO - 'tabpaint']}, 02:49:07 INFO - 'other-e10s': {'tests': ['a11yr', 02:49:07 INFO - 'ts_paint', 02:49:07 INFO - 'tpaint', 02:49:07 INFO - 'sessionrestore', 02:49:07 INFO - 'sessionrestore_no_auto_restore', 02:49:07 INFO - 'tabpaint']}, 02:49:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:49:07 INFO - 'ts_paint', 02:49:07 INFO - 'tpaint', 02:49:07 INFO - 'sessionrestore', 02:49:07 INFO - 'sessionrestore_no_auto_restore', 02:49:07 INFO - 'tabpaint']}, 02:49:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:49:07 INFO - 'ts_paint', 02:49:07 INFO - 'tpaint', 02:49:07 INFO - 'sessionrestore', 02:49:07 INFO - 'sessionrestore_no_auto_restore', 02:49:07 INFO - 'tabpaint']}, 02:49:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['a11yr', 02:49:07 INFO - 'ts_paint', 02:49:07 INFO - 'tpaint', 02:49:07 INFO - 'sessionrestore', 02:49:07 INFO - 'sessionrestore_no_auto_restore', 02:49:07 INFO - 'tabpaint']}, 02:49:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['a11yr', 02:49:07 INFO - 'ts_paint', 02:49:07 INFO - 'tpaint', 02:49:07 INFO - 'sessionrestore', 02:49:07 INFO - 'sessionrestore_no_auto_restore', 02:49:07 INFO - 'tabpaint']}, 02:49:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['tsvgx', 02:49:07 INFO - 'tsvgr_opacity', 02:49:07 INFO - 'tart', 02:49:07 INFO - 'tscrollx', 02:49:07 INFO - 'cart']}, 02:49:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:49:07 INFO - 'tsvgr_opacity', 02:49:07 INFO - 'tart', 02:49:07 INFO - 'tscrollx', 02:49:07 INFO - 'cart']}, 02:49:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'talos_options': ['--disable-e10s'], 02:49:07 INFO - 'tests': ['tp5o']}, 02:49:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'tests': ['tp5o']}, 02:49:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'talos_options': ['--disable-e10s', 02:49:07 INFO - '--xperf_path', 02:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:07 INFO - 'tests': ['tp5n']}, 02:49:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:07 INFO - 'talos_options': ['--xperf_path', 02:49:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:07 INFO - 'tests': ['tp5n']}}, 02:49:07 INFO - 'talos.zip': {'path': '', 02:49:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:49:07 INFO - Running post-action listener: _resource_record_post_action 02:49:07 INFO - [mozharness: 2016-08-09 09:49:07.393875Z] Finished populate-webroot step (success) 02:49:07 INFO - [mozharness: 2016-08-09 09:49:07.394005Z] Running create-virtualenv step. 02:49:07 INFO - Running pre-action listener: _resource_record_pre_action 02:49:07 INFO - Running main action method: create_virtualenv 02:49:07 INFO - Creating virtualenv /builds/slave/test/build/venv 02:49:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:49:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:49:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:49:07 INFO - Using real prefix '/tools/python27' 02:49:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:49:08 INFO - Installing distribute.............................................................................................................................................................................................done. 02:49:11 INFO - Installing pip.................done. 02:49:11 INFO - Return code: 0 02:49:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:11 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 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:11 INFO - 'HOME': '/Users/cltbld', 02:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:11 INFO - 'LOGNAME': 'cltbld', 02:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:11 INFO - 'MOZ_NO_REMOTE': '1', 02:49:11 INFO - 'NO_EM_RESTART': '1', 02:49:11 INFO - 'PAGER': '/bin/cat', 02:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:11 INFO - 'PWD': '/builds/slave/test', 02:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:11 INFO - 'SHELL': '/bin/bash', 02:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:11 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:11 INFO - 'USER': 'cltbld', 02:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:11 INFO - 'XPC_FLAGS': '0x0', 02:49:11 INFO - 'XPC_SERVICE_NAME': '0', 02:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:49:11 INFO - Cleaning up... 02:49:11 INFO - Return code: 0 02:49:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:11 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 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:11 INFO - 'HOME': '/Users/cltbld', 02:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:11 INFO - 'LOGNAME': 'cltbld', 02:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:11 INFO - 'MOZ_NO_REMOTE': '1', 02:49:11 INFO - 'NO_EM_RESTART': '1', 02:49:11 INFO - 'PAGER': '/bin/cat', 02:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:11 INFO - 'PWD': '/builds/slave/test', 02:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:11 INFO - 'SHELL': '/bin/bash', 02:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:11 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:11 INFO - 'USER': 'cltbld', 02:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:11 INFO - 'XPC_FLAGS': '0x0', 02:49:11 INFO - 'XPC_SERVICE_NAME': '0', 02:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:11 INFO - Downloading/unpacking psutil>=3.1.1 02:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:49:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:14 INFO - Installing collected packages: psutil 02:49:14 INFO - Running setup.py install for psutil 02:49:15 INFO - building 'psutil._psutil_osx' extension 02:49:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:49:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:49:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:49:15 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:49:15 INFO - building 'psutil._psutil_posix' extension 02:49:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:49:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:49:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:49:15 INFO - ^ 02:49:16 INFO - 1 warning generated. 02:49:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:49:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:16 INFO - Successfully installed psutil 02:49:16 INFO - Cleaning up... 02:49:16 INFO - Return code: 0 02:49:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:16 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 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:16 INFO - 'HOME': '/Users/cltbld', 02:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:16 INFO - 'LOGNAME': 'cltbld', 02:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:16 INFO - 'MOZ_NO_REMOTE': '1', 02:49:16 INFO - 'NO_EM_RESTART': '1', 02:49:16 INFO - 'PAGER': '/bin/cat', 02:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:16 INFO - 'PWD': '/builds/slave/test', 02:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:16 INFO - 'SHELL': '/bin/bash', 02:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:16 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:16 INFO - 'USER': 'cltbld', 02:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:16 INFO - 'XPC_FLAGS': '0x0', 02:49:16 INFO - 'XPC_SERVICE_NAME': '0', 02:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:49:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:49:19 INFO - Installing collected packages: mozsystemmonitor 02:49:19 INFO - Running setup.py install for mozsystemmonitor 02:49:19 INFO - Successfully installed mozsystemmonitor 02:49:19 INFO - Cleaning up... 02:49:19 INFO - Return code: 0 02:49:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:19 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 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:49:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:19 INFO - 'HOME': '/Users/cltbld', 02:49:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:19 INFO - 'LOGNAME': 'cltbld', 02:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:19 INFO - 'MOZ_NO_REMOTE': '1', 02:49:19 INFO - 'NO_EM_RESTART': '1', 02:49:19 INFO - 'PAGER': '/bin/cat', 02:49:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:19 INFO - 'PWD': '/builds/slave/test', 02:49:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:19 INFO - 'SHELL': '/bin/bash', 02:49:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:19 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:19 INFO - 'USER': 'cltbld', 02:49:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:19 INFO - 'XPC_FLAGS': '0x0', 02:49:19 INFO - 'XPC_SERVICE_NAME': '0', 02:49:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:19 INFO - Downloading/unpacking jsonschema==2.5.1 02:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:49:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:22 INFO - Downloading functools32-3.2.3-2.tar.gz 02:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:49:22 INFO - warning: no files found matching '*.txt' 02:49:22 INFO - no previously-included directories found matching 'build' 02:49:22 INFO - no previously-included directories found matching 'dist' 02:49:22 INFO - no previously-included directories found matching '.git*' 02:49:22 INFO - Installing collected packages: jsonschema, functools32 02:49:22 INFO - Running setup.py install for functools32 02:49:22 INFO - warning: no files found matching '*.txt' 02:49:22 INFO - no previously-included directories found matching 'build' 02:49:22 INFO - no previously-included directories found matching 'dist' 02:49:22 INFO - no previously-included directories found matching '.git*' 02:49:22 INFO - Successfully installed jsonschema functools32 02:49:22 INFO - Cleaning up... 02:49:22 INFO - Return code: 0 02:49:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:22 INFO - 'HOME': '/Users/cltbld', 02:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:22 INFO - 'LOGNAME': 'cltbld', 02:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:22 INFO - 'MOZ_NO_REMOTE': '1', 02:49:22 INFO - 'NO_EM_RESTART': '1', 02:49:22 INFO - 'PAGER': '/bin/cat', 02:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:22 INFO - 'PWD': '/builds/slave/test', 02:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:22 INFO - 'SHELL': '/bin/bash', 02:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:22 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:22 INFO - 'USER': 'cltbld', 02:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:22 INFO - 'XPC_FLAGS': '0x0', 02:49:22 INFO - 'XPC_SERVICE_NAME': '0', 02:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:49:22 INFO - Cleaning up... 02:49:22 INFO - Return code: 0 02:49:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:22 INFO - 'HOME': '/Users/cltbld', 02:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:22 INFO - 'LOGNAME': 'cltbld', 02:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:22 INFO - 'MOZ_NO_REMOTE': '1', 02:49:22 INFO - 'NO_EM_RESTART': '1', 02:49:22 INFO - 'PAGER': '/bin/cat', 02:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:22 INFO - 'PWD': '/builds/slave/test', 02:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:22 INFO - 'SHELL': '/bin/bash', 02:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:22 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:22 INFO - 'USER': 'cltbld', 02:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:22 INFO - 'XPC_FLAGS': '0x0', 02:49:22 INFO - 'XPC_SERVICE_NAME': '0', 02:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:23 INFO - Downloading/unpacking blobuploader==1.2.4 02:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:25 INFO - Downloading blobuploader-1.2.4.tar.gz 02:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:49:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:49:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:26 INFO - Downloading docopt-0.6.1.tar.gz 02:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:49:26 INFO - Installing collected packages: blobuploader, requests, docopt 02:49:26 INFO - Running setup.py install for blobuploader 02:49:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:49:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:49:27 INFO - Running setup.py install for requests 02:49:27 INFO - Running setup.py install for docopt 02:49:27 INFO - Successfully installed blobuploader requests docopt 02:49:27 INFO - Cleaning up... 02:49:27 INFO - Return code: 0 02:49:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:27 INFO - 'HOME': '/Users/cltbld', 02:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:27 INFO - 'LOGNAME': 'cltbld', 02:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:27 INFO - 'MOZ_NO_REMOTE': '1', 02:49:27 INFO - 'NO_EM_RESTART': '1', 02:49:27 INFO - 'PAGER': '/bin/cat', 02:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:27 INFO - 'PWD': '/builds/slave/test', 02:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:27 INFO - 'SHELL': '/bin/bash', 02:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:27 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:27 INFO - 'USER': 'cltbld', 02:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:27 INFO - 'XPC_FLAGS': '0x0', 02:49:27 INFO - 'XPC_SERVICE_NAME': '0', 02:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-W7BJb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1rFGQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-p7vYZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-AEfdRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ALMPGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-vAFeA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-D8aHNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-TZmLRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:28 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-C5z5ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-6MP_At-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wIso_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-hIno8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-dqevJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-cz3qWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-aydb1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-YQ6Ush-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:29 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-jbIik0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:49:29 INFO - Running setup.py install for manifestparser 02:49:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Running setup.py install for mozcrash 02:49:30 INFO - Running setup.py install for mozdebug 02:49:30 INFO - Running setup.py install for mozdevice 02:49:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Running setup.py install for mozfile 02:49:30 INFO - Running setup.py install for mozhttpd 02:49:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Running setup.py install for mozinfo 02:49:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:49:30 INFO - Running setup.py install for mozInstall 02:49:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozleak 02:49:31 INFO - Running setup.py install for mozlog 02:49:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for moznetwork 02:49:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozprocess 02:49:31 INFO - Running setup.py install for mozprofile 02:49:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:49:31 INFO - Running setup.py install for mozrunner 02:49:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Running setup.py install for mozscreenshot 02:49:32 INFO - Running setup.py install for moztest 02:49:32 INFO - Running setup.py install for mozversion 02:49:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:49:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:49:32 INFO - Cleaning up... 02:49:32 INFO - Return code: 0 02:49:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:32 INFO - 'HOME': '/Users/cltbld', 02:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:32 INFO - 'LOGNAME': 'cltbld', 02:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:32 INFO - 'MOZ_NO_REMOTE': '1', 02:49:32 INFO - 'NO_EM_RESTART': '1', 02:49:32 INFO - 'PAGER': '/bin/cat', 02:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:32 INFO - 'PWD': '/builds/slave/test', 02:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:32 INFO - 'SHELL': '/bin/bash', 02:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:32 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:32 INFO - 'USER': 'cltbld', 02:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:32 INFO - 'XPC_FLAGS': '0x0', 02:49:32 INFO - 'XPC_SERVICE_NAME': '0', 02:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ev2Awh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PLLjX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:32 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)) 02:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:32 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-2ke8_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-n_RrMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Yra2AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KrU5A5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-G8jY0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-7VjKXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-lGuQ5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-dvPX95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:33 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)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PAs24M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:33 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wzrC88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-taMsYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-JKKOt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:34 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)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-bEttSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-NLcIDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8OPZf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:49:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:37 INFO - Downloading blessings-1.6.tar.gz 02:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:49:37 INFO - Installing collected packages: blessings 02:49:37 INFO - Running setup.py install for blessings 02:49:37 INFO - Successfully installed blessings 02:49:37 INFO - Cleaning up... 02:49:37 INFO - Return code: 0 02:49:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:49:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:49:37 INFO - Reading from file tmpfile_stdout 02:49:37 INFO - Current package versions: 02:49:37 INFO - blessings == 1.6 02:49:37 INFO - blobuploader == 1.2.4 02:49:37 INFO - docopt == 0.6.1 02:49:37 INFO - functools32 == 3.2.3-2 02:49:37 INFO - jsonschema == 2.5.1 02:49:37 INFO - manifestparser == 1.1 02:49:37 INFO - mozInstall == 1.12 02:49:37 INFO - mozcrash == 0.17 02:49:37 INFO - mozdebug == 0.1 02:49:37 INFO - mozdevice == 0.48 02:49:37 INFO - mozfile == 1.2 02:49:37 INFO - mozhttpd == 0.7 02:49:37 INFO - mozinfo == 0.9 02:49:37 INFO - mozleak == 0.1 02:49:37 INFO - mozlog == 3.3 02:49:37 INFO - moznetwork == 0.27 02:49:37 INFO - mozprocess == 0.23 02:49:37 INFO - mozprofile == 0.28 02:49:37 INFO - mozrunner == 6.12 02:49:37 INFO - mozscreenshot == 0.1 02:49:37 INFO - mozsystemmonitor == 0.3 02:49:37 INFO - moztest == 0.7 02:49:37 INFO - mozversion == 1.4 02:49:37 INFO - psutil == 3.1.1 02:49:37 INFO - requests == 1.2.3 02:49:37 INFO - wsgiref == 0.1.2 02:49:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:49:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:37 INFO - 'HOME': '/Users/cltbld', 02:49:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:37 INFO - 'LOGNAME': 'cltbld', 02:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:37 INFO - 'MOZ_NO_REMOTE': '1', 02:49:37 INFO - 'NO_EM_RESTART': '1', 02:49:37 INFO - 'PAGER': '/bin/cat', 02:49:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:37 INFO - 'PWD': '/builds/slave/test', 02:49:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:37 INFO - 'SHELL': '/bin/bash', 02:49:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:37 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:37 INFO - 'USER': 'cltbld', 02:49:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:37 INFO - 'XPC_FLAGS': '0x0', 02:49:37 INFO - 'XPC_SERVICE_NAME': '0', 02:49:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:49:38 INFO - Cleaning up... 02:49:38 INFO - Return code: 0 02:49:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d29ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d333618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd54bca9720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, '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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:38 INFO - 'HOME': '/Users/cltbld', 02:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:38 INFO - 'LOGNAME': 'cltbld', 02:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:38 INFO - 'MOZ_NO_REMOTE': '1', 02:49:38 INFO - 'NO_EM_RESTART': '1', 02:49:38 INFO - 'PAGER': '/bin/cat', 02:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:38 INFO - 'PWD': '/builds/slave/test', 02:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:38 INFO - 'SHELL': '/bin/bash', 02:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:49:38 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:38 INFO - 'USER': 'cltbld', 02:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:38 INFO - 'XPC_FLAGS': '0x0', 02:49:38 INFO - 'XPC_SERVICE_NAME': '0', 02:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:49:38 INFO - Cleaning up... 02:49:38 INFO - Return code: 0 02:49:38 INFO - Running post-action listener: _resource_record_post_action 02:49:38 INFO - Running post-action listener: _start_resource_monitoring 02:49:38 INFO - Starting resource monitoring. 02:49:38 INFO - [mozharness: 2016-08-09 09:49:38.312070Z] Finished create-virtualenv step (success) 02:49:38 INFO - [mozharness: 2016-08-09 09:49:38.313002Z] Running install step. 02:49:38 INFO - Running pre-action listener: _resource_record_pre_action 02:49:38 INFO - Running main action method: install 02:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:49:38 INFO - Reading from file tmpfile_stdout 02:49:38 INFO - Detecting whether we're running mozinstall >=1.0... 02:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:49:38 INFO - Reading from file tmpfile_stdout 02:49:38 INFO - Output received: 02:49:38 INFO - Usage: mozinstall [options] installer 02:49:38 INFO - Options: 02:49:38 INFO - -h, --help show this help message and exit 02:49:38 INFO - -d DEST, --destination=DEST 02:49:38 INFO - Directory to install application into. [default: 02:49:38 INFO - "/builds/slave/test"] 02:49:38 INFO - --app=APP Application being installed. [default: firefox] 02:49:38 INFO - mkdir: /builds/slave/test/build/application 02:49:38 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'] 02:49:38 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 02:50:12 INFO - Reading from file tmpfile_stdout 02:50:12 INFO - Output received: 02:50:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:50:12 INFO - Running post-action listener: _resource_record_post_action 02:50:12 INFO - [mozharness: 2016-08-09 09:50:12.889303Z] Finished install step (success) 02:50:12 INFO - [mozharness: 2016-08-09 09:50:12.889447Z] Running run-tests step. 02:50:12 INFO - Running pre-action listener: _resource_record_pre_action 02:50:12 INFO - Running main action method: run_tests 02:50:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:50:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:50:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:50:12 INFO - 2016-08-09 02:50:12.903 screenresolution[1640:7426] starting screenresolution argv=screenresolution get 02:50:12 INFO - 2016-08-09 02:50:12.906 screenresolution[1640:7426] Display 0: 1600x1200x32@60 02:50:12 INFO - 2016-08-09 02:50:12.912 screenresolution[1641:7428] starting screenresolution argv=screenresolution list 02:50:12 INFO - Available Modes on Display 0 02:50:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:50:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:50:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:50:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:50:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:50:13 INFO - Intel Iris: 02:50:13 INFO - Chipset Model: Intel Iris 02:50:13 INFO - Type: GPU 02:50:13 INFO - Bus: Built-In 02:50:13 INFO - VRAM (Dynamic, Max): 1536 MB 02:50:13 INFO - Vendor: Intel (0x8086) 02:50:13 INFO - Device ID: 0x0a2e 02:50:13 INFO - Revision ID: 0x0009 02:50:13 INFO - Displays: 02:50:13 INFO - Display: 02:50:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:50:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:50:13 INFO - Display Serial Number: 1600x1200 60 02:50:13 INFO - Main Display: Yes 02:50:13 INFO - Mirror: Off 02:50:13 INFO - Online: Yes 02:50:13 INFO - Rotation: Supported 02:50:13 INFO - Return code: 0 02:50:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:50:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:50:13 INFO - Python 2.7.3 02:50:13 INFO - Return code: 0 02:50:13 INFO - grabbing minidump binary from tooltool 02:50: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')]} 02:50:13 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 0x10d202d30>, '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 0x10cf67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:50:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:50:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:50:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:50:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:50:13 INFO - Return code: 0 02:50:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:50:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:50:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:50:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:50:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:50:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:50:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:50:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z3u6RL6yGV/Render', 02:50:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:13 INFO - 'HOME': '/Users/cltbld', 02:50:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:13 INFO - 'LOGNAME': 'cltbld', 02:50:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:50:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:13 INFO - 'MOZ_NO_REMOTE': '1', 02:50:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:50:13 INFO - 'NO_EM_RESTART': '1', 02:50:13 INFO - 'PAGER': '/bin/cat', 02:50:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:13 INFO - 'PWD': '/builds/slave/test', 02:50:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:50:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:13 INFO - 'SHELL': '/bin/bash', 02:50:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nnGU2rEqTX/Listeners', 02:50:13 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 02:50:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:13 INFO - 'USER': 'cltbld', 02:50:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:13 INFO - 'XPC_FLAGS': '0x0', 02:50:13 INFO - 'XPC_SERVICE_NAME': '0', 02:50:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:50:13 INFO - mozversion application_buildid: 20160809000920 02:50:13 INFO - mozversion application_changeset: 2edc35144ebf50ca6339e686e766e200cc4b04b8 02:50:13 INFO - mozversion application_display_name: Nightly 02:50:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:50:13 INFO - mozversion application_name: Firefox 02:50:13 INFO - mozversion application_remotingname: firefox 02:50:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:50:13 INFO - mozversion application_vendor: Mozilla 02:50:13 INFO - mozversion application_version: 51.0a1 02:50:13 INFO - mozversion platform_buildid: 20160809000920 02:50:13 INFO - mozversion platform_changeset: 2edc35144ebf50ca6339e686e766e200cc4b04b8 02:50:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:50:13 INFO - mozversion platform_version: 51.0a1 02:50:13 INFO - using testdate: 1470736213 02:50:13 INFO - actual date: 1470736213 02:50:13 INFO - starting webserver on 'localhost:49271' 02:50:13 INFO - SUITE-START | Running 2 tests 02:50:13 INFO - TEST-START | dromaeo_css 02:50:13 INFO - operating with platform_type : mac_ 02:50:13 INFO - Initialising browser for dromaeo_css test... 02:50:13 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpsNYtwN/profile http://localhost:49271/getInfo.html) 02:50:15 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 02:50:15 INFO - PROCESS | 1651 | colorDepth:24 02:50:15 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 02:50:15 INFO - PROCESS | 1651 | __metrics 02:50:16 INFO - PROCESS | 1651 | 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" 02:50:16 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:50:16 INFO - TEST-INFO | 1651: exit 0 02:50:16 INFO - Browser initialized. 02:50:16 INFO - Running cycle 1/1 for dromaeo_css test... 02:50:16 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpsNYtwN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:28 INFO - PROCESS | 1653 | RSS: Main: 243507200 02:50:28 INFO - PROCESS | 1653 | 02:51:20 INFO - PROCESS | 1653 | 1470736280343 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:53:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 02:53:33 INFO - PROCESS | 1653 | RSS: Main: 388874240 02:53:33 INFO - PROCESS | 1653 | 02:56:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 02:56:37 INFO - PROCESS | 1653 | RSS: Main: 399986688 02:56:37 INFO - PROCESS | 1653 | 02:59:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 02:59:40 INFO - PROCESS | 1653 | RSS: Main: 359776256 02:59:40 INFO - PROCESS | 1653 | 03:02:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 03:02:43 INFO - PROCESS | 1653 | RSS: Main: 362012672 03:02:43 INFO - PROCESS | 1653 | 03:05:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:05:47 INFO - PROCESS | 1653 | RSS: Main: 345489408 03:05:47 INFO - PROCESS | 1653 | 03:08:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:08:51 INFO - PROCESS | 1653 | RSS: Main: 391811072 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | __start_tp_report 03:08:51 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 03:08:51 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 03:08:51 INFO - PROCESS | 1653 | |i|pagename|runs| 03:08:51 INFO - PROCESS | 1653 | |0;dojo.html;3349.732021438285;3396.2528243786373;3414.180596656802;3418.4017796885537;3459.9308013839723;5658.660480371171;5738.254026976498;5766.394528574502;5780.3352614449295;5830.475257226849;5760.855978600526;5772.971135144329;5850.76596936122;5886;5891.116332550119;5171.870703232412;5174.249733788617;5254.579633629301;5271.525562699357;5370.516653501184;5313.867153321178;5346.786128554853;5350.946490535103;5365.704886231275;5402.297701298826;5204.063268611649;5212.895742085175;5275.446078161776;5317.441668624797;5325.680459172462;5260.289860868772;5271.920921186185;5275.208718692188;5280.392754833205;5307.310049693554;5233.895322093575;5259.158534634478;5280.014367386012;5292.867678308073;5304.416514183436;5588.329400471969;5742.913856292159;5783;5795.913061304083;5857.941420585782;5212.713300768456;6351.364863513658;6357.11000459936;6359.8092057238355;6380.106785050097;5790.334111577138;5979.671118088503;6328.588641738273;6385.648789316586;6406.1351717518;5779.739911703983;5874.941250587525;5894;5934.465898069108;5948.226730525004;6366.649834259068;6369.490440764727;6376.521760867953;6381.617102973836;6390.7763228286785;6260.5617606768;6292.811215663537;6299.7795077173;6301.180846489927;6305.873882522415;5290.867728306823;6221.626702397871;6247.187865577446;6285;6372.203474565679;6269.905951410732;6280.842978925563;6312.147860038836;6314.0844577536;6318.988961766096;6237.937620623827;6369.33122022181;6375;6381.425671689477;6385.7126429309865;4941;4994;4997.375328083989;5025.020121076355;5031.547160755549;4502.954970450319;4548.499665036843;4573.771311434415;4574.885627859264;4581.106684196617;5704.743286552115;5768.423157684198;5771.076627739625;5816.243888294494;5826.912596311059;6260.217472815898;6277.081157261895;6330.936690633127;6341.080543321193;6347.587406818542;5767.394423585547;5783.942160578425;5788.594798364158;5800.390958949334;5826.009578371687;5394.622376433612;5421.213923981;5457.454254574511;5457.536109430664;5495.34055913285;6339.904901426482;6372.07604897278;6390.073439351361;6410.1987251593555;6410.839729006719;5218.921716174336;6315.90526142099;6375.87248255023;6380.170577824853;6404.711787969646;6246.437820596168;6299.181106456132;6331.5251356147555;6415.005674120519;6421;6250.156228909221;6270.811875643738;6272.059191121186;6302.65335406557;6303.306636270098;5096.210087436453;6312.652804095819;6340.953742632599;6344;6361.363863613602;5947.881042379215;6144.863200307957;6164;6215.06773983906;6227.439530442282;6175.59858609189;6194.0708893666315;6196.659183744938;6199.504039676905;6201.906971395342;6041.456268935641;6048.849220327645;6066.810451929643;6070.588001134496;6075.362086980804;6043.2748070231855;6107.765388666889;6138.296437970319;6192.411720886508;6211.3167551570195;6047.395260473917;6069.817905462844;6072.362401947732;6082.756689732361;6092.421219984094 03:08:51 INFO - PROCESS | 1653 | |1;ext.html;522.3653261287535;533.3679589686221;539.700466241236;551.1539786427834;559.2198882558833;30537.236569085784;32258.032259032207;32316.19209519763;32352.67647323526;32432.351352972957;40975.97560061;42129.57870421295;42293.78853105738;44443.555564444345;44500.554994450045;9509.239260859149;9654.13112819846;9680.854787178198;9711.223102151811;9726.562304696306;8615.61229744663;9586.664466743665;9687.176589989824;9689.224862011022;9689.951550242238;8646.610902509403;11794.469248883777;12009.699757506089;12087.972522335613;12100.334481603508;9989.15200687875;11689.181757276994;11776.764464710743;11784.116191285602;11789.646310610653;8581;11607.200883863015;11690.47392867323;11705.180637355388;11718;30470;31635;31640;38856.28006039578;39172.60827391718;3438.0717206354393;3476.617572067071;3598.5501812273465;3884.6309600587897;4297.785110744481;25659.48681026369;30666.540001899768;31462.370752585048;31813.52279715806;32054.839725801456;3180;3328.251143492719;3330.983345083259;3354.245294808673;3367.2928684976187;514856.42571787274;521425;521956;522360;522738;56083.43916560864;56425;56439.43560564342;56480;56492;59443.702781485816;59452;59487.40512594905;59565;59726.40273597295;15537.533873983799;15754.291056902237;15758.054516729033;15763.290651920692;15793.842061579237;12412.56556020553;12415.130940834235;12430.56493022758;12449.875501244871;12458.190217635825;13395.397207125703;13686.452541898412;14764.187969661556;14799.778003329959;15151.318190681446;6398.968005159944;6471.2234531856475;6566.704498297588;6572.86854262912;6597.647482266124;921.6820197032014;945.6406565505064;1022.8390776468761;1068.6740544134113;1085.46812062089;28171.71828281732;29561.55657665137;29618.555721664194;30101.849490752407;30360.240993975327;889.8309321228945;981.4160574458186;1039.5374058544026;1076.3918386111823;1171.5782318365382;13914.09558378702;14468.638284042983;14717.337719802634;15447.30487128082;15610.490899991766;633442;637104;641188;641200;642497.17525422;49631;50405.747971260644;50516.98966020659;56255.718721406134;56549.151762723595;1367.309508698121;1391.2209162868826;1413.0673755321643;1413.279227593914;1415.1885586449362;29273.414531709674;30070.54894176633;30798.384032319675;31344.686553134175;31678.683213167573;48493.272600910306;49132.508674912795;49136.75431622819;49199;49298.50701492939;484882;486700;487313;488299;653482;24496.142635008404;25534.872325638255;25585.74414255908;25615;25620.103296384535;44281.778591106835;45747.54252457433;46237;47300;48953.755231223615;38592.80703596464;38799;39108;40010;40094.79715608536;38719.6128038716;39163;39652.80173599229;40442.59557404506;41614.37578436265;37625.24749505049;38043.048923777125;38165.61834381732;38274;38691.41962870503;32635.510467342534;34234.65765342315;34328.97563309537;34895.82552087223;34937.82531087328;25013.874930625232;25079;25130.649629389158;25411.745882540938;25794.96820127174 03:08:51 INFO - PROCESS | 1653 | |2;jquery.html;357.6209218228676;377.21350983200045;378.68947463080275;386.57476775546894;406.9308217603007;15748.685026299481;15890.602734931617;20896.37310880676;21348.25281115161;21459.785402145975;20652.17391304346;20992.05535750891;21075;21448.57102857946;21539.353819385404;5922.644641321513;5953.761849526025;6162.815115546518;6225.533085018642;6227.221597300338;4220.472440944882;6101;6216.471599914013;6234.438900498954;6533.705983230742;5782.595218334718;6849.554778939378;6861.794146175623;6878.380945714885;6889.069975553286;5859.238299021121;6169.383061693839;6205.565610407273;6234.251889773211;6842.1105256316605;5244.475552444763;6080.726367313438;6106.236720409949;6106.847328816771;6112.572119951606;13145.342732863413;13655.931720341334;13807.378667959965;13852.445902163901;13856.722865542733;2079.4905248214286;2195.132922495621;2278.305116939333;2282.5206706591634;2337.2170322941834;9466.677950745017;9975.501224938796;10023.298369114165;10447.216458765623;10461.058504734534;1752.9482310613657;1798.3705703003843;1812.900290484023;1867.4677716850767;1930.951726206842;120302;129620;132562;133849.3307533456;143808.28095859557;16182.75725864113;17305.39431119905;19868.60262794736;21366.252181173586;21475.892620536797;35699.821500892846;35753.96091332214;35825.28349432997;35904.64095359065;35922.64077359193;9031.322650801216;10624.840627390595;10810.891891081146;11128.276662016942;11170.162237832195;9077.954610226907;9086.227670647937;9101.226395756303;9222.492762897971;9286.582103805345;7923;8983.955080224558;9015.918089829262;9643.083907028962;9834;3173.254285242966;3707.462417949382;3723.925521489555;3742.644448777362;3788.8295026723863;882.8631562107884;907.5371560504191;926.2358554192817;942.5146049784366;1018.6892997635643;21476.24833130864;22063.2277870277;22095.33713988583;22130.668039979413;22132;934.5654270763991;958.6500927161536;967.7145242153582;1008.5007921079113;1023.698009087321;10833.512491937881;11531.596394126165;11613.419329033684;11618.360990145624;11621.07031437483;573442;589302.0534897298;614104;616044;629094;111705;127618.36190818987;141653;147863;149095.25452372886;987.2398253345028;989.1098011789447;1007.2294694558815;1008.4000019988242;1010.2019404670375;18805.341813036477;19370.128344224824;19391.709124362864;19412.514687132934;19496.610067798847;58921.70539147448;59102;60282.397176027676;61208;61610;71946.64026679833;77544.06139846324;83247;88108.5594572023;88762;8325.708600198965;8352.331813454788;8490.787730306793;8534.487930724177;8534.829303413773;11635.650930472098;11763.823617638167;11840.526378944745;12956.481740730265;13630.454781808616;10936.835947460635;11518.424078796193;11735.823962640396;11745.530178792753;11884.465199066235;11322;11439.370834604177;11458.255213411101;11872.406379681153;11920.225185362924;11781.52873885035;11793.29240245588;12029;12072;12097.455614497192;8412.747617571482;8478.23695867375;8513.87229191575;8519;8529;9580.233581313618;11663.300201987908;11892.345920974083;11936.76126477483;11977.281363118242 03:08:51 INFO - PROCESS | 1653 | |3;mootools.html;1331.7070244546203;1352.1143650908646;1438.7841823726442;1491.2394678713852;1640.909749963753;6951.582905025695;7641.159472458032;8596.44123131996;8664.740057798268;8752.431091979017;8512.787180320494;8629.91370086299;8643.2653224476;8644.870326945102;8655.307575393968;1892.725554794554;2321.4312493439047;2327.359976006595;2329.6272596384583;2332.288651961151;2285.6000199965024;2320.7004077716447;2323.500447403808;2328.1734984080663;2329.4642232286583;2277.39648993017;2297.8506397084134;2313.2944451942153;2322.3381336319153;2326.4532834783813;1886.735856980024;2266.286119872235;2289.7366802817724;2300.2409204962323;2301.171578231835;2283.7145356830397;2294.4952110535655;2295.483516208856;2298.954020919589;2301.9079236830507;8515.787105322355;8717.651293948235;8729.214370706602;8731.60707768152;8732.388732788708;5161.483851614846;5377.596680248997;5387.676739395649;5394.838154855322;5403.324584426947;3612.584552776438;3617.9276414471565;3684.2263124743836;3684.6868016218386;3691.6774902587617;1083.9349639021684;1084.7993121272589;1085.478970094359;1094.60047082815;1105.8175401058895;11325.660230193025;11378.032867206297;11447;11463.770724585462;11483.885161148448;4320.222359975234;4428.33574963758;4556.954430455719;4596.816127354935;4602.723836569817;9001.54992250385;9016.143466370684;9020.639174432948;9023.142801433853;9031.593578288992;5177.275181474596;5180.663256888291;5185.455527169668;5185.714785686823;5186.377634683787;3693;4517.706349087299;4693.78877950493;4834.178189707758;4847.054824309226;3455.982720086434;3502.9474557881654;3667.7065834733157;3791.4123310886857;3844.7693138411787;1144.713821544614;1153.2792004996877;1155.3299086529792;1171.4068866341768;1172.337629239494;781.7654703588901;867.5532100968006;882.9161229683169;904.7783293093235;962.8363178259713;5867.853303667443;5941.970290148522;6006.75635157019;6093.421124993119;6126.295476020226;859.9379765988998;896.1262768800498;899.7795540092588;924.191332583989;938.8826396700413;3500.6149323574386;3916.882493525142;4032.935307066595;4110.588941105865;4158.272302347077;9931.553080111413;10257.07686308221;10403.63587274457;10573.312734672376;10583.947080264703;10513.264071514921;10525.9473702631;10528;10535.578576856993;10540.736481588021;7771.883421748678;8009.479383840032;8052.758417247492;8063.395245356739;8091.959540202261;5804.564657650609;5862.94137058624;6038.305594856648;6115.113308570321;6151.169592105188;9618.567164477476;9907.157891579296;9907.554160062959;9908.900910990798;9953.203743700342;9464.574094165642;10132.594696212069;10139.493025348851;10157.84763228567;10177.98220178006;7469.477136600386;7697.345725613385;8046.275835174863;8077.111517733162;8246.917530824854;8146.796330091558;8360.623771930399;8362.414630975773;8370.790730231793;8377.45546539473;7298.452616053711;7352.632368381666;7415.666295016629;7479.065116860393;7479.887801683088;6233.252009758677;7303.087114110736;7338.949724876176;7360.66876990526;7398.11222653285;6897.068895699016;6897.310268973062;6959.164900212007;6963.199232088375;6981.406580440719;4901.166801643801;4921.557059864629;4946.431160416454;4953.950460495493;5083.822066227664;4960.479149689375;5003.499650034967;5075.847724568269;5087.949120508748;5114.207297868837 03:08:51 INFO - PROCESS | 1653 | |4;prototype.html;298.1354073187754;301.2423754258041;310.6163887598816;315.3519517391762;318.85651456844425;5418.295621569196;5444.428335024815;5449;5477.917831232535;5498.340199176099;4039.959600403995;5049;5156.891268377298;5244.842654720355;5342.278792363029;3865.8067096645195;4067.5932406759307;4110.965072289206;4115.300398932189;4139.606737359946;3409.9488507672404;3437.9828100859586;4105.44576482174;4108.239975604522;4141.316682747343;3344.4983252512075;3370.528126062341;3512.9636757156572;4065.857694980676;4095.5699651536606;3772.1701225730294;3844;4068.5524592294823;4084.89787755307;4086.671831654724;3544.521489598897;3549.4675798630155;3555.6622120898473;3567.4470457079196;3578.874739384109;4439.400680908068;5223.52988231061;5242.5281724644965;5299.443558426348;5318.574514038867;4612.446506419251;4619.937414394674;4623.445186577598;4623.936494606226;4659.534046595347;4930.756505789893;5641.915371269434;5654.293213348332;5655;5658.773649054033;3170.651228364878;3332.41682705528;3844.788536630484;3875.748076375027;3882.3594106972314;5556.805511807067;5890.204822349014;5933.317668468138;5947.672877991752;5966.910496342515;3292.506124081407;3873.3802591585213;3898.8556294038167;3907.5115610548683;3918.9020274493364;3628.784357240354;3706.592274849764;3958.5353419235066;3978.9403158952637;3984.143409167043;3647.6899463545365;3977.5028121484816;4468.329750537446;4558.339040839126;4666.673332866667;5649.079313033506;5665.348484924287;5674.716264186774;5680.289963754531;5681.801136960186;1182.39697754146;1186.2170967161633;1266.575697141468;1320.6434262749003;1323.9007074469453;801.5190885468788;812.634314558451;827.9254867061993;838.6393850644281;883.1168831168832;4410.6030457259;4574.833417478521;5262.973790070725;5268.947310526845;5273.2617433559335;4825.613950883921;5781.624194427349;5791.320517050092;5798.376014990631;5807.170741216503;4145.689073319513;4905.730184839868;5158.9939961707105;5221.451747566451;5227.947720522822;2422;2601.2196341097597;2945.21814302344;3047.207725991258;3047.445802640625;4777.808887644455;4982.501749824988;5205.92191117126;5924.555658325643;5931.525477961753;5662.716864156776;5943.613665111754;6050.848728781815;6064.393560643988;6129.478994285447;5573.749181286852;6111.8472038198515;6121.4490695837585;6130.877382452415;6149.477294430024;5584.636998595079;5905.4685078343155;5932.79235226765;5961.37405572426;5984.311804142405;4919.975400122977;5787.9421205789085;5866.882662346815;5869.4717475427415;5878.677297608175;5511.421300763523;5735.827925162252;6122.816315510542;6124.326324104256;6170.382961703794;3716.47969284295;3861.4014827701753;3872.1674839909556;3892.3188442021965;3901.317269477773;4869.70781753096;5853.619514731528;5867.06386892316;5871.297323776057;5907.232059832194;4850.393700787402;5535.335759708861;5744.3681195067675;5797.91303130445;5827.5629327801435;4563.311574717376;5689.800856969986;5741.741621626953;5757.337906140848;5794.1308803679785;4602.815887364468;4621.83823566187;5605.103183490622;5618.747156378055;5620.072688006597;4777.324698169036;5450.691833959901;5601.663900166003;5615.978247830696;5800.390958949419;5627.77702807397;5650.29371328584;5658.773649053992;5672.914906276492;5676.545876329967 03:08:51 INFO - PROCESS | 1653 | |5;yui.html;708.996455017725;715.4562532475317;732.5275197497614;820.3191351178523;823.7528741377587;15348.923255383721;15989.200539973013;15998.920005399972;16021.759673604905;16039.198040097977;14773.187474688888;14913.254337283146;14954.700905981874;14958.850411495881;14981.700365992674;693.8300116471446;712.1845486917476;845.2941793602349;845.9830803383959;865.2213008292524;859.6991053131385;865.2342676731107;871.4509858788954;872.9301655867547;874.899386570543;923.4643906534194;924.0389994405809;925.2875286029753;926.0924294191697;928.1229238369743;730.8428687832142;895.6551727584862;920.8342498350295;920.9079092090805;922.5110691333604;684.4114061906756;686.0463955102362;687.082744536045;687.3264201082967;688.8828899087117;15040.924795376062;15084.94405391623;15093.056853913491;15114.09315441066;15117;13856.376463059187;13986.930065349608;14077.64805879851;14143.434262629482;14231.430742770277;10876.401797901111;12760;12904.16122952005;12992.805107923388;13506.932465337708;4549.226631472625;4586.3808385867815;4594.1271158479785;4761.6904901181315;4766.451858036301;117371;119299;120004.39997800042;120365.39817300945;121408;11092.057175140204;11199.440027998568;11246.775064498665;11364.772704545863;11669.941650291694;22375;22408.439789005406;22427.551448970928;22430.775692242867;22437.55124897493;7492.850142997218;7744.883826742604;7810.258025487569;8005.319547838499;8043.959780201061;4351.058030625848;4428.13651337986;4443.4445694288215;4483.282674772021;4506;3494.598121216042;3615.367310720613;3665.706743460517;3666.5416822897137;3667.9266414671556;655.4035827397046;656.1994366872408;662.3441396508728;672.351181110231;684.3122661724934;523.9187925871496;557.345119484604;571.5541877335602;627.8304857688398;645.5384400153913;7189.663359408213;8944.5080520572;9192.494412807226;9204.907950920538;9244.630214791334;522.8771238758889;537.8440252326859;541.9566434685215;552.1827695011406;626.2891618013497;4257.489101307801;4296.957030429656;4477.529859364785;4565.634749220055;4589.724616523019;158162.20918895563;160841.19579402028;164698;164887;166787;91600;91823;110406;114000;126879;72343.63828180825;77627.61186194033;89765.20469590572;93004.53497732604;113415;9460.574274157541;9739.220862331138;9952.25358098131;9966.750831229278;10039.648612298535;43334.78332608317;51363.743181283855;53351;60487.69756151191;62380;76234;90418.54790726004;90807;95944.96632887307;97906;11686.415679215836;12127.75744485123;12317.384130793245;12410;12782.808257876326;14847.40610375573;14862.479813206486;14935.327910243848;15358.38566457374;15480.767788483407;14089.929550352183;14209.573712788633;14248.145111293356;14431.567052988426;14445;13431.291596613544;13457.327133643474;13780.862191378359;13945;13979.510717124851;13749.450021999008;13799.448022079005;14014.789778153132;14148.78776818369;14310.57068287953;10975.341479511255;11151.665450036511;11376.089912807121;11467.827982580435;11968.401579921143;8105;9539.47532885698;10282.406227034822;10361.430121343097;11692.94153529227 03:08:51 INFO - PROCESS | 1653 | __end_tp_report 03:08:51 INFO - PROCESS | 1653 | __start_cc_report 03:08:51 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1524 03:08:51 INFO - PROCESS | 1653 | __end_cc_report 03:08:51 INFO - PROCESS | 1653 | __startTimestamp1470737331059__endTimestamp 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | ------- Summary: start ------- 03:08:51 INFO - PROCESS | 1653 | Number of tests: 6 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5802.89 Median:6041.46 stddev:645.18 (10.7%) stddev-sans-first:617.79 03:08:51 INFO - PROCESS | 1653 | Values: 3349.7 3396.3 3414.2 3418.4 3459.9 5658.7 5738.3 5766.4 5780.3 5830.5 5760.9 5773.0 5850.8 5886.0 5891.1 5171.9 5174.2 5254.6 5271.5 5370.5 5313.9 5346.8 5350.9 5365.7 5402.3 5204.1 5212.9 5275.4 5317.4 5325.7 5260.3 5271.9 5275.2 5280.4 5307.3 5233.9 5259.2 5280.0 5292.9 5304.4 5588.3 5742.9 5783.0 5795.9 5857.9 5212.7 6351.4 6357.1 6359.8 6380.1 5790.3 5979.7 6328.6 6385.6 6406.1 5779.7 5874.9 5894.0 5934.5 5948.2 6366.6 6369.5 6376.5 6381.6 6390.8 6260.6 6292.8 6299.8 6301.2 6305.9 5290.9 6221.6 6247.2 6285.0 6372.2 6269.9 6280.8 6312.1 6314.1 6319.0 6237.9 6369.3 6375.0 6381.4 6385.7 4941.0 4994.0 4997.4 5025.0 5031.5 4503.0 4548.5 4573.8 4574.9 4581.1 5704.7 5768.4 5771.1 5816.2 5826.9 6260.2 6277.1 6330.9 6341.1 6347.6 5767.4 5783.9 5788.6 5800.4 5826.0 5394.6 5421.2 5457.5 5457.5 5495.3 6339.9 6372.1 6390.1 6410.2 6410.8 5218.9 6315.9 6375.9 6380.2 6404.7 6246.4 6299.2 6331.5 6415.0 6421.0 6250.2 6270.8 6272.1 6302.7 6303.3 5096.2 6312.7 6341.0 6344.0 6361.4 5947.9 6144.9 6164.0 6215.1 6227.4 6175.6 6194.1 6196.7 6199.5 6201.9 6041.5 6048.8 6066.8 6070.6 6075.4 6043.3 6107.8 6138.3 6192.4 6211.3 6047.4 6069.8 6072.4 6082.8 6092.4 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:68717.55 Median:38104.33 stddev:150783.07 (395.7%) stddev-sans-first:151119.12 03:08:51 INFO - PROCESS | 1653 | Values: 522.4 533.4 539.7 551.2 559.2 30537.2 32258.0 32316.2 32352.7 32432.4 40976.0 42129.6 42293.8 44443.6 44500.6 9509.2 9654.1 9680.9 9711.2 9726.6 8615.6 9586.7 9687.2 9689.2 9690.0 8646.6 11794.5 12009.7 12088.0 12100.3 9989.2 11689.2 11776.8 11784.1 11789.6 8581.0 11607.2 11690.5 11705.2 11718.0 30470.0 31635.0 31640.0 38856.3 39172.6 3438.1 3476.6 3598.6 3884.6 4297.8 25659.5 30666.5 31462.4 31813.5 32054.8 3180.0 3328.3 3331.0 3354.2 3367.3 514856.4 521425.0 521956.0 522360.0 522738.0 56083.4 56425.0 56439.4 56480.0 56492.0 59443.7 59452.0 59487.4 59565.0 59726.4 15537.5 15754.3 15758.1 15763.3 15793.8 12412.6 12415.1 12430.6 12449.9 12458.2 13395.4 13686.5 14764.2 14799.8 15151.3 6399.0 6471.2 6566.7 6572.9 6597.6 921.7 945.6 1022.8 1068.7 1085.5 28171.7 29561.6 29618.6 30101.8 30360.2 889.8 981.4 1039.5 1076.4 1171.6 13914.1 14468.6 14717.3 15447.3 15610.5 633442.0 637104.0 641188.0 641200.0 642497.2 49631.0 50405.7 50517.0 56255.7 56549.2 1367.3 1391.2 1413.1 1413.3 1415.2 29273.4 30070.5 30798.4 31344.7 31678.7 48493.3 49132.5 49136.8 49199.0 49298.5 484882.0 486700.0 487313.0 488299.0 653482.0 24496.1 25534.9 25585.7 25615.0 25620.1 44281.8 45747.5 46237.0 47300.0 48953.8 38592.8 38799.0 39108.0 40010.0 40094.8 38719.6 39163.0 39652.8 40442.6 41614.4 37625.2 38043.0 38165.6 38274.0 38691.4 32635.5 34234.7 34329.0 34895.8 34937.8 25013.9 25079.0 25130.6 25411.7 25795.0 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:37008.45 Median:2309.87 stddev:101377.80 (4388.9%) stddev-sans-first:101624.84 03:08:51 INFO - PROCESS | 1653 | Values: 357.6 377.2 378.7 386.6 406.9 15748.7 15890.6 20896.4 21348.3 21459.8 20652.2 20992.1 21075.0 21448.6 21539.4 5922.6 5953.8 6162.8 6225.5 6227.2 4220.5 6101.0 6216.5 6234.4 6533.7 5782.6 6849.6 6861.8 6878.4 6889.1 5859.2 6169.4 6205.6 6234.3 6842.1 5244.5 6080.7 6106.2 6106.8 6112.6 13145.3 13655.9 13807.4 13852.4 13856.7 2079.5 2195.1 2278.3 2282.5 2337.2 9466.7 9975.5 10023.3 10447.2 10461.1 1752.9 1798.4 1812.9 1867.5 1931.0 120302.0 129620.0 132562.0 133849.3 143808.3 16182.8 17305.4 19868.6 21366.3 21475.9 35699.8 35754.0 35825.3 35904.6 35922.6 9031.3 10624.8 10810.9 11128.3 11170.2 9078.0 9086.2 9101.2 9222.5 9286.6 7923.0 8984.0 9015.9 9643.1 9834.0 3173.3 3707.5 3723.9 3742.6 3788.8 882.9 907.5 926.2 942.5 1018.7 21476.2 22063.2 22095.3 22130.7 22132.0 934.6 958.7 967.7 1008.5 1023.7 10833.5 11531.6 11613.4 11618.4 11621.1 573442.0 589302.1 614104.0 616044.0 629094.0 111705.0 127618.4 141653.0 147863.0 149095.3 987.2 989.1 1007.2 1008.4 1010.2 18805.3 19370.1 19391.7 19412.5 19496.6 58921.7 59102.0 60282.4 61208.0 61610.0 71946.6 77544.1 83247.0 88108.6 88762.0 8325.7 8352.3 8490.8 8534.5 8534.8 11635.7 11763.8 11840.5 12956.5 13630.5 10936.8 11518.4 11735.8 11745.5 11884.5 11322.0 11439.4 11458.3 11872.4 11920.2 11781.5 11793.3 12029.0 12072.0 12097.5 8412.7 8478.2 8513.9 8519.0 8529.0 9580.2 11663.3 11892.3 11936.8 11977.3 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5610.60 Median:5085.89 stddev:3140.73 (61.8%) stddev-sans-first:3133.08 03:08:51 INFO - PROCESS | 1653 | Values: 1331.7 1352.1 1438.8 1491.2 1640.9 6951.6 7641.2 8596.4 8664.7 8752.4 8512.8 8629.9 8643.3 8644.9 8655.3 1892.7 2321.4 2327.4 2329.6 2332.3 2285.6 2320.7 2323.5 2328.2 2329.5 2277.4 2297.9 2313.3 2322.3 2326.5 1886.7 2266.3 2289.7 2300.2 2301.2 2283.7 2294.5 2295.5 2299.0 2301.9 8515.8 8717.7 8729.2 8731.6 8732.4 5161.5 5377.6 5387.7 5394.8 5403.3 3612.6 3617.9 3684.2 3684.7 3691.7 1083.9 1084.8 1085.5 1094.6 1105.8 11325.7 11378.0 11447.0 11463.8 11483.9 4320.2 4428.3 4557.0 4596.8 4602.7 9001.5 9016.1 9020.6 9023.1 9031.6 5177.3 5180.7 5185.5 5185.7 5186.4 3693.0 4517.7 4693.8 4834.2 4847.1 3456.0 3502.9 3667.7 3791.4 3844.8 1144.7 1153.3 1155.3 1171.4 1172.3 781.8 867.6 882.9 904.8 962.8 5867.9 5942.0 6006.8 6093.4 6126.3 859.9 896.1 899.8 924.2 938.9 3500.6 3916.9 4032.9 4110.6 4158.3 9931.6 10257.1 10403.6 10573.3 10583.9 10513.3 10525.9 10528.0 10535.6 10540.7 7771.9 8009.5 8052.8 8063.4 8092.0 5804.6 5862.9 6038.3 6115.1 6151.2 9618.6 9907.2 9907.6 9908.9 9953.2 9464.6 10132.6 10139.5 10157.8 10178.0 7469.5 7697.3 8046.3 8077.1 8246.9 8146.8 8360.6 8362.4 8370.8 8377.5 7298.5 7352.6 7415.7 7479.1 7479.9 6233.3 7303.1 7338.9 7360.7 7398.1 6897.1 6897.3 6959.2 6963.2 6981.4 4901.2 4921.6 4946.4 4954.0 5083.8 4960.5 5003.5 5075.8 5087.9 5114.2 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4562.36 Median:5213.69 stddev:1457.17 (27.9%) stddev-sans-first:1425.67 03:08:51 INFO - PROCESS | 1653 | Values: 298.1 301.2 310.6 315.4 318.9 5418.3 5444.4 5449.0 5477.9 5498.3 4040.0 5049.0 5156.9 5244.8 5342.3 3865.8 4067.6 4111.0 4115.3 4139.6 3409.9 3438.0 4105.4 4108.2 4141.3 3344.5 3370.5 3513.0 4065.9 4095.6 3772.2 3844.0 4068.6 4084.9 4086.7 3544.5 3549.5 3555.7 3567.4 3578.9 4439.4 5223.5 5242.5 5299.4 5318.6 4612.4 4619.9 4623.4 4623.9 4659.5 4930.8 5641.9 5654.3 5655.0 5658.8 3170.7 3332.4 3844.8 3875.7 3882.4 5556.8 5890.2 5933.3 5947.7 5966.9 3292.5 3873.4 3898.9 3907.5 3918.9 3628.8 3706.6 3958.5 3978.9 3984.1 3647.7 3977.5 4468.3 4558.3 4666.7 5649.1 5665.3 5674.7 5680.3 5681.8 1182.4 1186.2 1266.6 1320.6 1323.9 801.5 812.6 827.9 838.6 883.1 4410.6 4574.8 5263.0 5268.9 5273.3 4825.6 5781.6 5791.3 5798.4 5807.2 4145.7 4905.7 5159.0 5221.5 5227.9 2422.0 2601.2 2945.2 3047.2 3047.4 4777.8 4982.5 5205.9 5924.6 5931.5 5662.7 5943.6 6050.8 6064.4 6129.5 5573.7 6111.8 6121.4 6130.9 6149.5 5584.6 5905.5 5932.8 5961.4 5984.3 4920.0 5787.9 5866.9 5869.5 5878.7 5511.4 5735.8 6122.8 6124.3 6170.4 3716.5 3861.4 3872.2 3892.3 3901.3 4869.7 5853.6 5867.1 5871.3 5907.2 4850.4 5535.3 5744.4 5797.9 5827.6 4563.3 5689.8 5741.7 5757.3 5794.1 4602.8 4621.8 5605.1 5618.7 5620.1 4777.3 5450.7 5601.7 5616.0 5800.4 5627.8 5650.3 5658.8 5672.9 5676.5 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24186.11 Median:23842.92 stddev:38855.65 (163.0%) stddev-sans-first:38924.66 03:08:51 INFO - PROCESS | 1653 | Values: 709.0 715.5 732.5 820.3 823.8 15348.9 15989.2 15998.9 16021.8 16039.2 14773.2 14913.3 14954.7 14958.9 14981.7 693.8 712.2 845.3 846.0 865.2 859.7 865.2 871.5 872.9 874.9 923.5 924.0 925.3 926.1 928.1 730.8 895.7 920.8 920.9 922.5 684.4 686.0 687.1 687.3 688.9 15040.9 15084.9 15093.1 15114.1 15117.0 13856.4 13986.9 14077.6 14143.4 14231.4 10876.4 12760.0 12904.2 12992.8 13506.9 4549.2 4586.4 4594.1 4761.7 4766.5 117371.0 119299.0 120004.4 120365.4 121408.0 11092.1 11199.4 11246.8 11364.8 11669.9 22375.0 22408.4 22427.6 22430.8 22437.6 7492.9 7744.9 7810.3 8005.3 8044.0 4351.1 4428.1 4443.4 4483.3 4506.0 3494.6 3615.4 3665.7 3666.5 3667.9 655.4 656.2 662.3 672.4 684.3 523.9 557.3 571.6 627.8 645.5 7189.7 8944.5 9192.5 9204.9 9244.6 522.9 537.8 542.0 552.2 626.3 4257.5 4297.0 4477.5 4565.6 4589.7 158162.2 160841.2 164698.0 164887.0 166787.0 91600.0 91823.0 110406.0 114000.0 126879.0 72343.6 77627.6 89765.2 93004.5 113415.0 9460.6 9739.2 9952.3 9966.8 10039.6 43334.8 51363.7 53351.0 60487.7 62380.0 76234.0 90418.5 90807.0 95945.0 97906.0 11686.4 12127.8 12317.4 12410.0 12782.8 14847.4 14862.5 14935.3 15358.4 15480.8 14089.9 14209.6 14248.1 14431.6 14445.0 13431.3 13457.3 13780.9 13945.0 13979.5 13749.5 13799.4 14014.8 14148.8 14310.6 10975.3 11151.7 11376.1 11467.8 11968.4 8105.0 9539.5 10282.4 10361.4 11692.9 03:08:51 INFO - PROCESS | 1653 | -------- Summary: end -------- 03:08:51 INFO - PROCESS | 1653 | 03:08:51 INFO - TEST-INFO | 1653: exit 0 03:08:51 INFO - TEST-OK | dromaeo_css | took 1118217ms 03:08:51 INFO - TEST-START | kraken 03:08:51 INFO - operating with platform_type : mac_ 03:08:51 INFO - Initialising browser for kraken test... 03:08:51 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpqqXAAT/profile http://localhost:49271/getInfo.html) 03:08:53 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 03:08:53 INFO - PROCESS | 1663 | colorDepth:24 03:08:53 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 03:08:53 INFO - PROCESS | 1663 | __metrics 03:08:53 INFO - PROCESS | 1663 | 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" 03:08:53 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:08:54 INFO - TEST-INFO | 1663: exit 0 03:08:54 INFO - Browser initialized. 03:08:54 INFO - Running cycle 1/1 for kraken test... 03:08:54 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpqqXAAT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:09:06 INFO - PROCESS | 1664 | RSS: Main: 243564544 03:09:06 INFO - PROCESS | 1664 | 03:09:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:09:10 INFO - PROCESS | 1664 | RSS: Main: 294387712 03:09:10 INFO - PROCESS | 1664 | 03:09:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:09:13 INFO - PROCESS | 1664 | RSS: Main: 300900352 03:09:13 INFO - PROCESS | 1664 | 03:09:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:09:17 INFO - PROCESS | 1664 | RSS: Main: 396783616 03:09:17 INFO - PROCESS | 1664 | 03:09:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:09:20 INFO - PROCESS | 1664 | RSS: Main: 297562112 03:09:20 INFO - PROCESS | 1664 | 03:09:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:09:23 INFO - PROCESS | 1664 | RSS: Main: 302833664 03:09:23 INFO - PROCESS | 1664 | 03:09:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:09:26 INFO - PROCESS | 1664 | RSS: Main: 340029440 03:09:26 INFO - PROCESS | 1664 | 03:09:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:09:30 INFO - PROCESS | 1664 | RSS: Main: 340262912 03:09:30 INFO - PROCESS | 1664 | 03:09:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:09:33 INFO - PROCESS | 1664 | RSS: Main: 338497536 03:09:33 INFO - PROCESS | 1664 | 03:09:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:09:35 INFO - PROCESS | 1664 | RSS: Main: 313884672 03:09:35 INFO - PROCESS | 1664 | 03:09:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:09:38 INFO - PROCESS | 1664 | RSS: Main: 321339392 03:09:38 INFO - PROCESS | 1664 | 03:09:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:09:41 INFO - PROCESS | 1664 | RSS: Main: 326643712 03:09:41 INFO - PROCESS | 1664 | 03:09:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:09:44 INFO - PROCESS | 1664 | RSS: Main: 331190272 03:09:44 INFO - PROCESS | 1664 | 03:09:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:09:48 INFO - PROCESS | 1664 | RSS: Main: 332410880 03:09:48 INFO - PROCESS | 1664 | 03:09:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:09:50 INFO - PROCESS | 1664 | RSS: Main: 374992896 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | __start_tp_report 03:09:50 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:09:50 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:09:50 INFO - PROCESS | 1664 | |i|pagename|runs| 03:09:50 INFO - PROCESS | 1664 | |0;ai-astar;102;114;113;115;114;112;114;114;112;116 03:09:50 INFO - PROCESS | 1664 | |1;audio-beat-detection;142;108;108;165;107;105;130;108;141;109 03:09:50 INFO - PROCESS | 1664 | |2;audio-dft;183;208;266;180;194;206;183;183;183;183 03:09:50 INFO - PROCESS | 1664 | |3;audio-fft;77;58;60;86;58;57;63;65;83;56 03:09:50 INFO - PROCESS | 1664 | |4;audio-oscillator;86;81;129;96;71;68;96;70;64;100 03:09:50 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;87;86;90;87;89;86;126;132;116;86 03:09:50 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;93;94;104;151;94;94;96;94;94 03:09:50 INFO - PROCESS | 1664 | |7;imaging-desaturate;81;86;84;84;83;84;88;83;86;84 03:09:50 INFO - PROCESS | 1664 | |8;json-parse-financial;54;53;54;56;53;54;53;53;53;54 03:09:50 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;73;74;76;72;70;68;96;60;68 03:09:50 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;83;86;86;86;85;82;82;87;86;86 03:09:50 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;136;134;138;135;133;136;137;136;154;166 03:09:50 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;163;163;167;164;170;163;162;151;210;225 03:09:50 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;65;65;69;72;81;83;72;65;66 03:09:50 INFO - PROCESS | 1664 | __end_tp_report 03:09:50 INFO - PROCESS | 1664 | __start_cc_report 03:09:50 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2230 03:09:50 INFO - PROCESS | 1664 | __end_cc_report 03:09:50 INFO - PROCESS | 1664 | __startTimestamp1470737390193__endTimestamp 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:09:50 INFO - PROCESS | 1664 | Number of tests: 14 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.92 (3.4%) stddev-sans-first:1.30 03:09:50 INFO - PROCESS | 1664 | Values: 102.0 114.0 113.0 115.0 114.0 112.0 114.0 114.0 112.0 116.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:119.50 stddev:20.93 (17.5%) stddev-sans-first:20.95 03:09:50 INFO - PROCESS | 1664 | Values: 142.0 108.0 108.0 165.0 107.0 105.0 130.0 108.0 141.0 109.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:196.90 Median:188.50 stddev:26.33 (14.0%) stddev-sans-first:27.45 03:09:50 INFO - PROCESS | 1664 | Values: 183.0 208.0 266.0 180.0 194.0 206.0 183.0 183.0 183.0 183.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:66.30 Median:64.00 stddev:11.37 (17.8%) stddev-sans-first:11.38 03:09:50 INFO - PROCESS | 1664 | Values: 77.0 58.0 60.0 86.0 58.0 57.0 63.0 65.0 83.0 56.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:76.00 stddev:19.89 (26.2%) stddev-sans-first:21.09 03:09:50 INFO - PROCESS | 1664 | Values: 86.0 81.0 129.0 96.0 71.0 68.0 96.0 70.0 64.0 100.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:86.50 stddev:18.50 (21.4%) stddev-sans-first:19.15 03:09:50 INFO - PROCESS | 1664 | Values: 87.0 86.0 90.0 87.0 89.0 86.0 126.0 132.0 116.0 86.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.00 stddev:17.97 (19.1%) stddev-sans-first:18.84 03:09:50 INFO - PROCESS | 1664 | Values: 93.0 93.0 94.0 104.0 151.0 94.0 94.0 96.0 94.0 94.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:1.95 (2.3%) stddev-sans-first:1.66 03:09:50 INFO - PROCESS | 1664 | Values: 81.0 86.0 84.0 84.0 83.0 84.0 88.0 83.0 86.0 84.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 03:09:50 INFO - PROCESS | 1664 | Values: 54.0 53.0 54.0 56.0 53.0 54.0 53.0 53.0 53.0 54.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:9.58 (13.2%) stddev-sans-first:9.80 03:09:50 INFO - PROCESS | 1664 | Values: 65.0 73.0 74.0 76.0 72.0 70.0 68.0 96.0 60.0 68.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:86.00 stddev:1.85 (2.2%) stddev-sans-first:1.83 03:09:50 INFO - PROCESS | 1664 | Values: 83.0 86.0 86.0 86.0 85.0 82.0 82.0 87.0 86.0 86.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:136.50 stddev:10.75 (7.9%) stddev-sans-first:11.28 03:09:50 INFO - PROCESS | 1664 | Values: 136.0 134.0 138.0 135.0 133.0 136.0 137.0 136.0 154.0 166.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:165.50 stddev:23.80 (14.4%) stddev-sans-first:24.92 03:09:50 INFO - PROCESS | 1664 | Values: 163.0 163.0 167.0 164.0 170.0 163.0 162.0 151.0 210.0 225.0 03:09:50 INFO - PROCESS | 1664 | 03:09:50 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.50 stddev:6.64 (9.4%) stddev-sans-first:6.92 03:09:50 INFO - PROCESS | 1664 | Values: 67.0 65.0 65.0 69.0 72.0 81.0 83.0 72.0 65.0 66.0 03:09:50 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:09:50 INFO - PROCESS | 1664 | 03:09:51 INFO - TEST-INFO | 1664: exit 0 03:09:51 INFO - TEST-OK | kraken | took 59453ms 03:09:51 INFO - SUITE-END | took 1177s 03:09:51 INFO - Completed test suite (00:19:37) 03:09:51 INFO - javascript benchmark 03:09:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3349.732021438285, 3396.2528243786373, 3414.180596656802, 3418.4017796885537, 3459.9308013839723, 5658.660480371171, 5738.254026976498, 5766.394528574502, 5780.3352614449295, 5830.475257226849, 5760.855978600526, 5772.971135144329, 5850.76596936122, 5886.0, 5891.116332550119, 5171.870703232412, 5174.249733788617, 5254.579633629301, 5271.525562699357, 5370.516653501184, 5313.867153321178, 5346.786128554853, 5350.946490535103, 5365.704886231275, 5402.297701298826, 5204.063268611649, 5212.895742085175, 5275.446078161776, 5317.441668624797, 5325.680459172462, 5260.289860868772, 5271.920921186185, 5275.208718692188, 5280.392754833205, 5307.310049693554, 5233.895322093575, 5259.158534634478, 5280.014367386012, 5292.867678308073, 5304.416514183436, 5588.329400471969, 5742.913856292159, 5783.0, 5795.913061304083, 5857.941420585782, 5212.713300768456, 6351.364863513658, 6357.11000459936, 6359.8092057238355, 6380.106785050097, 5790.334111577138, 5979.671118088503, 6328.588641738273, 6385.648789316586, 6406.1351717518, 5779.739911703983, 5874.941250587525, 5894.0, 5934.465898069108, 5948.226730525004, 6366.649834259068, 6369.490440764727, 6376.521760867953, 6381.617102973836, 6390.7763228286785, 6260.5617606768, 6292.811215663537, 6299.7795077173, 6301.180846489927, 6305.873882522415, 5290.867728306823, 6221.626702397871, 6247.187865577446, 6285.0, 6372.203474565679, 6269.905951410732, 6280.842978925563, 6312.147860038836, 6314.0844577536, 6318.988961766096, 6237.937620623827, 6369.33122022181, 6375.0, 6381.425671689477, 6385.7126429309865, 4941.0, 4994.0, 4997.375328083989, 5025.020121076355, 5031.547160755549, 4502.954970450319, 4548.499665036843, 4573.771311434415, 4574.885627859264, 4581.106684196617, 5704.743286552115, 5768.423157684198, 5771.076627739625, 5816.243888294494, 5826.912596311059, 6260.217472815898, 6277.081157261895, 6330.936690633127, 6341.080543321193, 6347.587406818542, 5767.394423585547, 5783.942160578425, 5788.594798364158, 5800.390958949334, 5826.009578371687, 5394.622376433612, 5421.213923981, 5457.454254574511, 5457.536109430664, 5495.34055913285, 6339.904901426482, 6372.07604897278, 6390.073439351361, 6410.1987251593555, 6410.839729006719, 5218.921716174336, 6315.90526142099, 6375.87248255023, 6380.170577824853, 6404.711787969646, 6246.437820596168, 6299.181106456132, 6331.5251356147555, 6415.005674120519, 6421.0, 6250.156228909221, 6270.811875643738, 6272.059191121186, 6302.65335406557, 6303.306636270098, 5096.210087436453, 6312.652804095819, 6340.953742632599, 6344.0, 6361.363863613602, 5947.881042379215, 6144.863200307957, 6164.0, 6215.06773983906, 6227.439530442282, 6175.59858609189, 6194.0708893666315, 6196.659183744938, 6199.504039676905, 6201.906971395342, 6041.456268935641, 6048.849220327645, 6066.810451929643, 6070.588001134496, 6075.362086980804, 6043.2748070231855, 6107.765388666889, 6138.296437970319, 6192.411720886508, 6211.3167551570195, 6047.395260473917, 6069.817905462844, 6072.362401947732, 6082.756689732361, 6092.421219984094], "value": 5763.1500690061475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.3653261287535, 533.3679589686221, 539.700466241236, 551.1539786427834, 559.2198882558833, 30537.236569085784, 32258.032259032207, 32316.19209519763, 32352.67647323526, 32432.351352972957, 40975.97560061, 42129.57870421295, 42293.78853105738, 44443.555564444345, 44500.554994450045, 9509.239260859149, 9654.13112819846, 9680.854787178198, 9711.223102151811, 9726.562304696306, 8615.61229744663, 9586.664466743665, 9687.176589989824, 9689.224862011022, 9689.951550242238, 8646.610902509403, 11794.469248883777, 12009.699757506089, 12087.972522335613, 12100.334481603508, 9989.15200687875, 11689.181757276994, 11776.764464710743, 11784.116191285602, 11789.646310610653, 8581.0, 11607.200883863015, 11690.47392867323, 11705.180637355388, 11718.0, 30470.0, 31635.0, 31640.0, 38856.28006039578, 39172.60827391718, 3438.0717206354393, 3476.617572067071, 3598.5501812273465, 3884.6309600587897, 4297.785110744481, 25659.48681026369, 30666.540001899768, 31462.370752585048, 31813.52279715806, 32054.839725801456, 3180.0, 3328.251143492719, 3330.983345083259, 3354.245294808673, 3367.2928684976187, 514856.42571787274, 521425.0, 521956.0, 522360.0, 522738.0, 56083.43916560864, 56425.0, 56439.43560564342, 56480.0, 56492.0, 59443.702781485816, 59452.0, 59487.40512594905, 59565.0, 59726.40273597295, 15537.533873983799, 15754.291056902237, 15758.054516729033, 15763.290651920692, 15793.842061579237, 12412.56556020553, 12415.130940834235, 12430.56493022758, 12449.875501244871, 12458.190217635825, 13395.397207125703, 13686.452541898412, 14764.187969661556, 14799.778003329959, 15151.318190681446, 6398.968005159944, 6471.2234531856475, 6566.704498297588, 6572.86854262912, 6597.647482266124, 921.6820197032014, 945.6406565505064, 1022.8390776468761, 1068.6740544134113, 1085.46812062089, 28171.71828281732, 29561.55657665137, 29618.555721664194, 30101.849490752407, 30360.240993975327, 889.8309321228945, 981.4160574458186, 1039.5374058544026, 1076.3918386111823, 1171.5782318365382, 13914.09558378702, 14468.638284042983, 14717.337719802634, 15447.30487128082, 15610.490899991766, 633442.0, 637104.0, 641188.0, 641200.0, 642497.17525422, 49631.0, 50405.747971260644, 50516.98966020659, 56255.718721406134, 56549.151762723595, 1367.309508698121, 1391.2209162868826, 1413.0673755321643, 1413.279227593914, 1415.1885586449362, 29273.414531709674, 30070.54894176633, 30798.384032319675, 31344.686553134175, 31678.683213167573, 48493.272600910306, 49132.508674912795, 49136.75431622819, 49199.0, 49298.50701492939, 484882.0, 486700.0, 487313.0, 488299.0, 653482.0, 24496.142635008404, 25534.872325638255, 25585.74414255908, 25615.0, 25620.103296384535, 44281.778591106835, 45747.54252457433, 46237.0, 47300.0, 48953.755231223615, 38592.80703596464, 38799.0, 39108.0, 40010.0, 40094.79715608536, 38719.6128038716, 39163.0, 39652.80173599229, 40442.59557404506, 41614.37578436265, 37625.24749505049, 38043.048923777125, 38165.61834381732, 38274.0, 38691.41962870503, 32635.510467342534, 34234.65765342315, 34328.97563309537, 34895.82552087223, 34937.82531087328, 25013.874930625232, 25079.0, 25130.649629389158, 25411.745882540938, 25794.96820127174], "value": 19718.42898114935, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.6209218228676, 377.21350983200045, 378.68947463080275, 386.57476775546894, 406.9308217603007, 15748.685026299481, 15890.602734931617, 20896.37310880676, 21348.25281115161, 21459.785402145975, 20652.17391304346, 20992.05535750891, 21075.0, 21448.57102857946, 21539.353819385404, 5922.644641321513, 5953.761849526025, 6162.815115546518, 6225.533085018642, 6227.221597300338, 4220.472440944882, 6101.0, 6216.471599914013, 6234.438900498954, 6533.705983230742, 5782.595218334718, 6849.554778939378, 6861.794146175623, 6878.380945714885, 6889.069975553286, 5859.238299021121, 6169.383061693839, 6205.565610407273, 6234.251889773211, 6842.1105256316605, 5244.475552444763, 6080.726367313438, 6106.236720409949, 6106.847328816771, 6112.572119951606, 13145.342732863413, 13655.931720341334, 13807.378667959965, 13852.445902163901, 13856.722865542733, 2079.4905248214286, 2195.132922495621, 2278.305116939333, 2282.5206706591634, 2337.2170322941834, 9466.677950745017, 9975.501224938796, 10023.298369114165, 10447.216458765623, 10461.058504734534, 1752.9482310613657, 1798.3705703003843, 1812.900290484023, 1867.4677716850767, 1930.951726206842, 120302.0, 129620.0, 132562.0, 133849.3307533456, 143808.28095859557, 16182.75725864113, 17305.39431119905, 19868.60262794736, 21366.252181173586, 21475.892620536797, 35699.821500892846, 35753.96091332214, 35825.28349432997, 35904.64095359065, 35922.64077359193, 9031.322650801216, 10624.840627390595, 10810.891891081146, 11128.276662016942, 11170.162237832195, 9077.954610226907, 9086.227670647937, 9101.226395756303, 9222.492762897971, 9286.582103805345, 7923.0, 8983.955080224558, 9015.918089829262, 9643.083907028962, 9834.0, 3173.254285242966, 3707.462417949382, 3723.925521489555, 3742.644448777362, 3788.8295026723863, 882.8631562107884, 907.5371560504191, 926.2358554192817, 942.5146049784366, 1018.6892997635643, 21476.24833130864, 22063.2277870277, 22095.33713988583, 22130.668039979413, 22132.0, 934.5654270763991, 958.6500927161536, 967.7145242153582, 1008.5007921079113, 1023.698009087321, 10833.512491937881, 11531.596394126165, 11613.419329033684, 11618.360990145624, 11621.07031437483, 573442.0, 589302.0534897298, 614104.0, 616044.0, 629094.0, 111705.0, 127618.36190818987, 141653.0, 147863.0, 149095.25452372886, 987.2398253345028, 989.1098011789447, 1007.2294694558815, 1008.4000019988242, 1010.2019404670375, 18805.341813036477, 19370.128344224824, 19391.709124362864, 19412.514687132934, 19496.610067798847, 58921.70539147448, 59102.0, 60282.397176027676, 61208.0, 61610.0, 71946.64026679833, 77544.06139846324, 83247.0, 88108.5594572023, 88762.0, 8325.708600198965, 8352.331813454788, 8490.787730306793, 8534.487930724177, 8534.829303413773, 11635.650930472098, 11763.823617638167, 11840.526378944745, 12956.481740730265, 13630.454781808616, 10936.835947460635, 11518.424078796193, 11735.823962640396, 11745.530178792753, 11884.465199066235, 11322.0, 11439.370834604177, 11458.255213411101, 11872.406379681153, 11920.225185362924, 11781.52873885035, 11793.29240245588, 12029.0, 12072.0, 12097.455614497192, 8412.747617571482, 8478.23695867375, 8513.87229191575, 8519.0, 8529.0, 9580.233581313618, 11663.300201987908, 11892.345920974083, 11936.76126477483, 11977.281363118242], "value": 10533.833143752945, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.7070244546203, 1352.1143650908646, 1438.7841823726442, 1491.2394678713852, 1640.909749963753, 6951.582905025695, 7641.159472458032, 8596.44123131996, 8664.740057798268, 8752.431091979017, 8512.787180320494, 8629.91370086299, 8643.2653224476, 8644.870326945102, 8655.307575393968, 1892.725554794554, 2321.4312493439047, 2327.359976006595, 2329.6272596384583, 2332.288651961151, 2285.6000199965024, 2320.7004077716447, 2323.500447403808, 2328.1734984080663, 2329.4642232286583, 2277.39648993017, 2297.8506397084134, 2313.2944451942153, 2322.3381336319153, 2326.4532834783813, 1886.735856980024, 2266.286119872235, 2289.7366802817724, 2300.2409204962323, 2301.171578231835, 2283.7145356830397, 2294.4952110535655, 2295.483516208856, 2298.954020919589, 2301.9079236830507, 8515.787105322355, 8717.651293948235, 8729.214370706602, 8731.60707768152, 8732.388732788708, 5161.483851614846, 5377.596680248997, 5387.676739395649, 5394.838154855322, 5403.324584426947, 3612.584552776438, 3617.9276414471565, 3684.2263124743836, 3684.6868016218386, 3691.6774902587617, 1083.9349639021684, 1084.7993121272589, 1085.478970094359, 1094.60047082815, 1105.8175401058895, 11325.660230193025, 11378.032867206297, 11447.0, 11463.770724585462, 11483.885161148448, 4320.222359975234, 4428.33574963758, 4556.954430455719, 4596.816127354935, 4602.723836569817, 9001.54992250385, 9016.143466370684, 9020.639174432948, 9023.142801433853, 9031.593578288992, 5177.275181474596, 5180.663256888291, 5185.455527169668, 5185.714785686823, 5186.377634683787, 3693.0, 4517.706349087299, 4693.78877950493, 4834.178189707758, 4847.054824309226, 3455.982720086434, 3502.9474557881654, 3667.7065834733157, 3791.4123310886857, 3844.7693138411787, 1144.713821544614, 1153.2792004996877, 1155.3299086529792, 1171.4068866341768, 1172.337629239494, 781.7654703588901, 867.5532100968006, 882.9161229683169, 904.7783293093235, 962.8363178259713, 5867.853303667443, 5941.970290148522, 6006.75635157019, 6093.421124993119, 6126.295476020226, 859.9379765988998, 896.1262768800498, 899.7795540092588, 924.191332583989, 938.8826396700413, 3500.6149323574386, 3916.882493525142, 4032.935307066595, 4110.588941105865, 4158.272302347077, 9931.553080111413, 10257.07686308221, 10403.63587274457, 10573.312734672376, 10583.947080264703, 10513.264071514921, 10525.9473702631, 10528.0, 10535.578576856993, 10540.736481588021, 7771.883421748678, 8009.479383840032, 8052.758417247492, 8063.395245356739, 8091.959540202261, 5804.564657650609, 5862.94137058624, 6038.305594856648, 6115.113308570321, 6151.169592105188, 9618.567164477476, 9907.157891579296, 9907.554160062959, 9908.900910990798, 9953.203743700342, 9464.574094165642, 10132.594696212069, 10139.493025348851, 10157.84763228567, 10177.98220178006, 7469.477136600386, 7697.345725613385, 8046.275835174863, 8077.111517733162, 8246.917530824854, 8146.796330091558, 8360.623771930399, 8362.414630975773, 8370.790730231793, 8377.45546539473, 7298.452616053711, 7352.632368381666, 7415.666295016629, 7479.065116860393, 7479.887801683088, 6233.252009758677, 7303.087114110736, 7338.949724876176, 7360.66876990526, 7398.11222653285, 6897.068895699016, 6897.310268973062, 6959.164900212007, 6963.199232088375, 6981.406580440719, 4901.166801643801, 4921.557059864629, 4946.431160416454, 4953.950460495493, 5083.822066227664, 4960.479149689375, 5003.499650034967, 5075.847724568269, 5087.949120508748, 5114.207297868837], "value": 4492.65048871151, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.1354073187754, 301.2423754258041, 310.6163887598816, 315.3519517391762, 318.85651456844425, 5418.295621569196, 5444.428335024815, 5449.0, 5477.917831232535, 5498.340199176099, 4039.959600403995, 5049.0, 5156.891268377298, 5244.842654720355, 5342.278792363029, 3865.8067096645195, 4067.5932406759307, 4110.965072289206, 4115.300398932189, 4139.606737359946, 3409.9488507672404, 3437.9828100859586, 4105.44576482174, 4108.239975604522, 4141.316682747343, 3344.4983252512075, 3370.528126062341, 3512.9636757156572, 4065.857694980676, 4095.5699651536606, 3772.1701225730294, 3844.0, 4068.5524592294823, 4084.89787755307, 4086.671831654724, 3544.521489598897, 3549.4675798630155, 3555.6622120898473, 3567.4470457079196, 3578.874739384109, 4439.400680908068, 5223.52988231061, 5242.5281724644965, 5299.443558426348, 5318.574514038867, 4612.446506419251, 4619.937414394674, 4623.445186577598, 4623.936494606226, 4659.534046595347, 4930.756505789893, 5641.915371269434, 5654.293213348332, 5655.0, 5658.773649054033, 3170.651228364878, 3332.41682705528, 3844.788536630484, 3875.748076375027, 3882.3594106972314, 5556.805511807067, 5890.204822349014, 5933.317668468138, 5947.672877991752, 5966.910496342515, 3292.506124081407, 3873.3802591585213, 3898.8556294038167, 3907.5115610548683, 3918.9020274493364, 3628.784357240354, 3706.592274849764, 3958.5353419235066, 3978.9403158952637, 3984.143409167043, 3647.6899463545365, 3977.5028121484816, 4468.329750537446, 4558.339040839126, 4666.673332866667, 5649.079313033506, 5665.348484924287, 5674.716264186774, 5680.289963754531, 5681.801136960186, 1182.39697754146, 1186.2170967161633, 1266.575697141468, 1320.6434262749003, 1323.9007074469453, 801.5190885468788, 812.634314558451, 827.9254867061993, 838.6393850644281, 883.1168831168832, 4410.6030457259, 4574.833417478521, 5262.973790070725, 5268.947310526845, 5273.2617433559335, 4825.613950883921, 5781.624194427349, 5791.320517050092, 5798.376014990631, 5807.170741216503, 4145.689073319513, 4905.730184839868, 5158.9939961707105, 5221.451747566451, 5227.947720522822, 2422.0, 2601.2196341097597, 2945.21814302344, 3047.207725991258, 3047.445802640625, 4777.808887644455, 4982.501749824988, 5205.92191117126, 5924.555658325643, 5931.525477961753, 5662.716864156776, 5943.613665111754, 6050.848728781815, 6064.393560643988, 6129.478994285447, 5573.749181286852, 6111.8472038198515, 6121.4490695837585, 6130.877382452415, 6149.477294430024, 5584.636998595079, 5905.4685078343155, 5932.79235226765, 5961.37405572426, 5984.311804142405, 4919.975400122977, 5787.9421205789085, 5866.882662346815, 5869.4717475427415, 5878.677297608175, 5511.421300763523, 5735.827925162252, 6122.816315510542, 6124.326324104256, 6170.382961703794, 3716.47969284295, 3861.4014827701753, 3872.1674839909556, 3892.3188442021965, 3901.317269477773, 4869.70781753096, 5853.619514731528, 5867.06386892316, 5871.297323776057, 5907.232059832194, 4850.393700787402, 5535.335759708861, 5744.3681195067675, 5797.91303130445, 5827.5629327801435, 4563.311574717376, 5689.800856969986, 5741.741621626953, 5757.337906140848, 5794.1308803679785, 4602.815887364468, 4621.83823566187, 5605.103183490622, 5618.747156378055, 5620.072688006597, 4777.324698169036, 5450.691833959901, 5601.663900166003, 5615.978247830696, 5800.390958949419, 5627.77702807397, 5650.29371328584, 5658.773649053992, 5672.914906276492, 5676.545876329967], "value": 4093.8175442426564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.996455017725, 715.4562532475317, 732.5275197497614, 820.3191351178523, 823.7528741377587, 15348.923255383721, 15989.200539973013, 15998.920005399972, 16021.759673604905, 16039.198040097977, 14773.187474688888, 14913.254337283146, 14954.700905981874, 14958.850411495881, 14981.700365992674, 693.8300116471446, 712.1845486917476, 845.2941793602349, 845.9830803383959, 865.2213008292524, 859.6991053131385, 865.2342676731107, 871.4509858788954, 872.9301655867547, 874.899386570543, 923.4643906534194, 924.0389994405809, 925.2875286029753, 926.0924294191697, 928.1229238369743, 730.8428687832142, 895.6551727584862, 920.8342498350295, 920.9079092090805, 922.5110691333604, 684.4114061906756, 686.0463955102362, 687.082744536045, 687.3264201082967, 688.8828899087117, 15040.924795376062, 15084.94405391623, 15093.056853913491, 15114.09315441066, 15117.0, 13856.376463059187, 13986.930065349608, 14077.64805879851, 14143.434262629482, 14231.430742770277, 10876.401797901111, 12760.0, 12904.16122952005, 12992.805107923388, 13506.932465337708, 4549.226631472625, 4586.3808385867815, 4594.1271158479785, 4761.6904901181315, 4766.451858036301, 117371.0, 119299.0, 120004.39997800042, 120365.39817300945, 121408.0, 11092.057175140204, 11199.440027998568, 11246.775064498665, 11364.772704545863, 11669.941650291694, 22375.0, 22408.439789005406, 22427.551448970928, 22430.775692242867, 22437.55124897493, 7492.850142997218, 7744.883826742604, 7810.258025487569, 8005.319547838499, 8043.959780201061, 4351.058030625848, 4428.13651337986, 4443.4445694288215, 4483.282674772021, 4506.0, 3494.598121216042, 3615.367310720613, 3665.706743460517, 3666.5416822897137, 3667.9266414671556, 655.4035827397046, 656.1994366872408, 662.3441396508728, 672.351181110231, 684.3122661724934, 523.9187925871496, 557.345119484604, 571.5541877335602, 627.8304857688398, 645.5384400153913, 7189.663359408213, 8944.5080520572, 9192.494412807226, 9204.907950920538, 9244.630214791334, 522.8771238758889, 537.8440252326859, 541.9566434685215, 552.1827695011406, 626.2891618013497, 4257.489101307801, 4296.957030429656, 4477.529859364785, 4565.634749220055, 4589.724616523019, 158162.20918895563, 160841.19579402028, 164698.0, 164887.0, 166787.0, 91600.0, 91823.0, 110406.0, 114000.0, 126879.0, 72343.63828180825, 77627.61186194033, 89765.20469590572, 93004.53497732604, 113415.0, 9460.574274157541, 9739.220862331138, 9952.25358098131, 9966.750831229278, 10039.648612298535, 43334.78332608317, 51363.743181283855, 53351.0, 60487.69756151191, 62380.0, 76234.0, 90418.54790726004, 90807.0, 95944.96632887307, 97906.0, 11686.415679215836, 12127.75744485123, 12317.384130793245, 12410.0, 12782.808257876326, 14847.40610375573, 14862.479813206486, 14935.327910243848, 15358.38566457374, 15480.767788483407, 14089.929550352183, 14209.573712788633, 14248.145111293356, 14431.567052988426, 14445.0, 13431.291596613544, 13457.327133643474, 13780.862191378359, 13945.0, 13979.510717124851, 13749.450021999008, 13799.448022079005, 14014.789778153132, 14148.78776818369, 14310.57068287953, 10975.341479511255, 11151.665450036511, 11376.089912807121, 11467.827982580435, 11968.401579921143, 8105.0, 9539.47532885698, 10282.406227034822, 10361.430121343097, 11692.94153529227], "value": 7783.982029958137, "unit": "score"}], "name": "dromaeo_css", "value": 7453.035485760178, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 113.0, 115.0, 114.0, 112.0, 114.0, 114.0, 112.0, 116.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 108.0, 165.0, 107.0, 105.0, 130.0, 108.0, 141.0, 109.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 208.0, 266.0, 180.0, 194.0, 206.0, 183.0, 183.0, 183.0, 183.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 60.0, 86.0, 58.0, 57.0, 63.0, 65.0, 83.0, 56.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 129.0, 96.0, 71.0, 68.0, 96.0, 70.0, 64.0, 100.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 90.0, 87.0, 89.0, 86.0, 126.0, 132.0, 116.0, 86.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 104.0, 151.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 84.0, 84.0, 83.0, 84.0, 88.0, 83.0, 86.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 56.0, 53.0, 54.0, 53.0, 53.0, 53.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 74.0, 76.0, 72.0, 70.0, 68.0, 96.0, 60.0, 68.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 86.0, 85.0, 82.0, 82.0, 87.0, 86.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 138.0, 135.0, 133.0, 136.0, 137.0, 136.0, 154.0, 166.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 167.0, 164.0, 170.0, 163.0, 162.0, 151.0, 210.0, 225.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 69.0, 72.0, 81.0, 83.0, 72.0, 65.0, 66.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1463.3, "alertThreshold": 2.0}]} 03:09:51 INFO - Return code: 0 03:09:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:09:51 INFO - # TBPL SUCCESS # 03:09:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:09:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:09:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:09:51 INFO - 2016-08-09 03:09:51.513 screenresolution[1668:9558] starting screenresolution argv=screenresolution get 03:09:51 INFO - 2016-08-09 03:09:51.516 screenresolution[1668:9558] Display 0: 1600x1200x32@60 03:09:51 INFO - 2016-08-09 03:09:51.522 screenresolution[1669:9561] starting screenresolution argv=screenresolution list 03:09:51 INFO - Available Modes on Display 0 03:09:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:09:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:09:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:09:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:09:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:09:51 INFO - Intel Iris: 03:09:51 INFO - Chipset Model: Intel Iris 03:09:51 INFO - Type: GPU 03:09:51 INFO - Bus: Built-In 03:09:51 INFO - VRAM (Dynamic, Max): 1536 MB 03:09:51 INFO - Vendor: Intel (0x8086) 03:09:51 INFO - Device ID: 0x0a2e 03:09:51 INFO - Revision ID: 0x0009 03:09:51 INFO - Displays: 03:09:51 INFO - Display: 03:09:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:09:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:09:51 INFO - Display Serial Number: 1600x1200 60 03:09:51 INFO - Main Display: Yes 03:09:51 INFO - Mirror: Off 03:09:51 INFO - Online: Yes 03:09:51 INFO - Rotation: Supported 03:09:51 INFO - Return code: 0 03:09:51 INFO - Running post-action listener: _resource_record_post_action 03:09:51 INFO - [mozharness: 2016-08-09 10:09:51.764232Z] Finished run-tests step (success) 03:09:51 INFO - Running post-run listener: _resource_record_post_run 03:09:51 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38360064; Write bytes: 702139392; Read time: 375; Write time: 2041 03:09:51 INFO - TinderboxPrint: CPU usage
53.5% 03:09:51 INFO - TinderboxPrint: I/O read bytes / time
38,360,064 / 375 03:09:51 INFO - TinderboxPrint: I/O write bytes / time
702,139,392 / 2,041 03:09:51 INFO - TinderboxPrint: CPU idle
2,253.6 (46.5%) 03:09:51 INFO - TinderboxPrint: CPU system
972.5 (20.1%) 03:09:51 INFO - TinderboxPrint: CPU user
1,623.5 (33.5%) 03:09:51 INFO - TinderboxPrint: Swap in / out
445,562,880 / 0 03:09:51 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396491264; Write bytes: 390215168; Read time: 29484; Write time: 721 03:09:51 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36434944; Write bytes: 292058624; Read time: 353; Write time: 1281 03:09:52 INFO - Running post-run listener: _upload_blobber_files 03:09:52 INFO - Blob upload gear active. 03:09:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:09:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:09:52 INFO - (blobuploader) - INFO - Open directory for files ... 03:09:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:09:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:09:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:09:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:09:53 INFO - (blobuploader) - INFO - Done attempting. 03:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:09:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:09:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:09:54 INFO - (blobuploader) - INFO - Done attempting. 03:09:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:09:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:09:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:09:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:09:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:09:57 INFO - (blobuploader) - INFO - Done attempting. 03:09:57 INFO - (blobuploader) - INFO - Iteration through files over. 03:09:57 INFO - Return code: 0 03:09:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:09:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:09:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/184e584d51ff39a7f1eec08a2194532790c38ce87a95fef9316a7d7596c1f478e311178971115664a87f9db2a07a39f4b8a6959b96676de2f94bc309545aaacb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d81b14ad0e8b0767ba0a9e86c29d6f22c09bb21f1002237132e02845451fc4136439ebe4045ac926cc45eae19f71147b941458cca6ad90b60f37fb60d5c825ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1c972950244173854c1aaac8b209a98c9ec27822ef82f772987a25971cbe0f8c6c42ff62e52f61361c04d0192b457d4ac12314405f6abd24597c60b3f88227"} 03:09:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:09:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:09:57 INFO - Contents: 03:09:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/184e584d51ff39a7f1eec08a2194532790c38ce87a95fef9316a7d7596c1f478e311178971115664a87f9db2a07a39f4b8a6959b96676de2f94bc309545aaacb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d81b14ad0e8b0767ba0a9e86c29d6f22c09bb21f1002237132e02845451fc4136439ebe4045ac926cc45eae19f71147b941458cca6ad90b60f37fb60d5c825ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1c972950244173854c1aaac8b209a98c9ec27822ef82f772987a25971cbe0f8c6c42ff62e52f61361c04d0192b457d4ac12314405f6abd24597c60b3f88227"} 03:09:57 INFO - Running post-run listener: copy_logs_to_upload_dir 03:09:57 INFO - Copying logs to upload dir... 03:09:57 INFO - mkdir: /builds/slave/test/build/upload/logs 03:09:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.082029 ========= master_lag: 0.48 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-09 03:09:57.573742) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 03:09:57.577529) ========= 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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/Fx-Team/sha512/184e584d51ff39a7f1eec08a2194532790c38ce87a95fef9316a7d7596c1f478e311178971115664a87f9db2a07a39f4b8a6959b96676de2f94bc309545aaacb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d81b14ad0e8b0767ba0a9e86c29d6f22c09bb21f1002237132e02845451fc4136439ebe4045ac926cc45eae19f71147b941458cca6ad90b60f37fb60d5c825ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1c972950244173854c1aaac8b209a98c9ec27822ef82f772987a25971cbe0f8c6c42ff62e52f61361c04d0192b457d4ac12314405f6abd24597c60b3f88227"} build_url:https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/YIAIgRaTTWCwrxDbr9yK7Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/184e584d51ff39a7f1eec08a2194532790c38ce87a95fef9316a7d7596c1f478e311178971115664a87f9db2a07a39f4b8a6959b96676de2f94bc309545aaacb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d81b14ad0e8b0767ba0a9e86c29d6f22c09bb21f1002237132e02845451fc4136439ebe4045ac926cc45eae19f71147b941458cca6ad90b60f37fb60d5c825ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1c972950244173854c1aaac8b209a98c9ec27822ef82f772987a25971cbe0f8c6c42ff62e52f61361c04d0192b457d4ac12314405f6abd24597c60b3f88227"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 03:09:57.611721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-08-09 03:09:57.612024) ========= 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.Z3u6RL6yGV/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.nnGU2rEqTX/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005160 ========= master_lag: 4.67 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-08-09 03:10:02.291175) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 03:10:02.291446) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 03:10:02.311320) ========= ========= Total master_lag: 5.66 =========