builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0039 starttime: 1471429875.87 results: success (0) buildid: 20160816133815 builduid: a0ea22c36d5a48e5a6e847d4d0201bdc revision: bb2261b4ac8bcf7ea068e1fc765765985f851dfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:15.869078) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:15.869511) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:15.869783) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:15.897087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:15.897786) ========= 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.xWeV7Up1eu/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.MlOdPZl26c/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-17 03:31:15-- 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% 12.1M=0.001s 2016-08-17 03:31:16 (12.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125200 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:16.050788) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:16.051513) ========= 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.xWeV7Up1eu/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.MlOdPZl26c/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.029131 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:16.104590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 03:31:16.105430) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev bb2261b4ac8bcf7ea068e1fc765765985f851dfd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev bb2261b4ac8bcf7ea068e1fc765765985f851dfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xWeV7Up1eu/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.MlOdPZl26c/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-17 03:31:16,183 truncating revision to first 12 chars 2016-08-17 03:31:16,184 Setting DEBUG logging. 2016-08-17 03:31:16,184 attempt 1/10 2016-08-17 03:31:16,184 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/bb2261b4ac8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 03:31:17,213 unpacking tar archive at: autoland-bb2261b4ac8b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.289215 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-17 03:31:17.481956) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:17.482310) ========= script_repo_revision: bb2261b4ac8bcf7ea068e1fc765765985f851dfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:17.482776) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:17.483042) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 03:31:17.503851) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-17 03:31:17.504570) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xWeV7Up1eu/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.MlOdPZl26c/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:17 INFO - MultiFileLogger online at 20160817 03:31:17 in /builds/slave/test 03:31:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:31:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:31:17 INFO - {'append_to_log': False, 03:31:17 INFO - 'base_work_dir': '/builds/slave/test', 03:31:17 INFO - 'blob_upload_branch': 'Autoland', 03:31:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:31:17 INFO - 'branch': 'Autoland', 03:31:17 INFO - 'buildbot_json_path': 'buildprops.json', 03:31:17 INFO - 'config_files': ('talos/mac_config.py',), 03:31:17 INFO - 'default_actions': ('clobber', 03:31:17 INFO - 'read-buildbot-config', 03:31:17 INFO - 'download-and-extract', 03:31:17 INFO - 'populate-webroot', 03:31:17 INFO - 'create-virtualenv', 03:31:17 INFO - 'install', 03:31:17 INFO - 'run-tests'), 03:31:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:31:17 INFO - 'download_minidump_stackwalk': True, 03:31:17 INFO - 'download_symbols': 'ondemand', 03:31:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:31:17 INFO - 'tooltool.py': '/tools/tooltool.py', 03:31:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:31:17 INFO - '/tools/misc-python/virtualenv.py')}, 03:31:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:31:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:31:17 INFO - 'installer_path': 'installer.exe', 03:31:17 INFO - 'log_level': 'info', 03:31:17 INFO - 'log_name': 'talos', 03:31:17 INFO - 'log_to_console': True, 03:31:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:31:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:31:17 INFO - 'opt_config_files': (), 03:31:17 INFO - 'pip_index': False, 03:31:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:31:17 INFO - 'cmd': ('bash', 03:31:17 INFO - '-c', 03:31:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:31:17 INFO - 'enabled': True, 03:31:17 INFO - 'halt_on_failure': False, 03:31:17 INFO - 'name': 'check_screen_resolution'},), 03:31:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:31:17 INFO - 'cmd': ('bash', 03:31:17 INFO - '-c', 03:31:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:31:17 INFO - 'enabled': True, 03:31:17 INFO - 'halt_on_failure': False, 03:31:17 INFO - 'name': 'check_screen_resolution'},), 03:31:17 INFO - 'run_cmd_checks_enabled': True, 03:31:17 INFO - 'sps_profile': False, 03:31:17 INFO - 'sps_profile_interval': 0, 03:31:17 INFO - 'suite': 'dromaeojs', 03:31:17 INFO - 'system_bits': '32', 03:31:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:31:17 INFO - 'title': 't-yosemite-r7-0039', 03:31:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:31:17 INFO - 'use_talos_json': True, 03:31:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:31:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:31:17 INFO - 'work_dir': 'build'} 03:31:17 INFO - [mozharness: 2016-08-17 10:31:17.723285Z] Running clobber step. 03:31:17 INFO - Running pre-action listener: _resource_record_pre_action 03:31:17 INFO - Running main action method: clobber 03:31:17 INFO - rmtree: /builds/slave/test/build 03:31:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:31:19 INFO - Running post-action listener: _resource_record_post_action 03:31:19 INFO - [mozharness: 2016-08-17 10:31:19.843361Z] Finished clobber step (success) 03:31:19 INFO - [mozharness: 2016-08-17 10:31:19.843572Z] Running read-buildbot-config step. 03:31:19 INFO - Running pre-action listener: _resource_record_pre_action 03:31:19 INFO - Running main action method: read_buildbot_config 03:31:19 INFO - Using buildbot properties: 03:31:19 INFO - { 03:31:19 INFO - "project": "", 03:31:19 INFO - "product": "firefox", 03:31:19 INFO - "script_repo_revision": "production", 03:31:19 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:31:19 INFO - "repository": "", 03:31:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:31:19 INFO - "buildid": "20160816133815", 03:31:19 INFO - "pgo_build": "False", 03:31:19 INFO - "basedir": "/builds/slave/test", 03:31:19 INFO - "buildnumber": 121, 03:31:19 INFO - "slavename": "t-yosemite-r7-0039", 03:31:19 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:31:19 INFO - "platform": "yosemite_r7", 03:31:19 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 03:31:19 INFO - "branch": "autoland", 03:31:19 INFO - "repo_path": "integration/autoland", 03:31:19 INFO - "revision": "bb2261b4ac8bcf7ea068e1fc765765985f851dfd", 03:31:19 INFO - "stage_platform": "macosx64", 03:31:19 INFO - "builduid": "a0ea22c36d5a48e5a6e847d4d0201bdc", 03:31:19 INFO - "slavebuilddir": "test" 03:31:19 INFO - } 03:31:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:31:19 INFO - Running post-action listener: _resource_record_post_action 03:31:19 INFO - [mozharness: 2016-08-17 10:31:19.847066Z] Finished read-buildbot-config step (success) 03:31:19 INFO - [mozharness: 2016-08-17 10:31:19.847226Z] Running download-and-extract step. 03:31:19 INFO - Running pre-action listener: _resource_record_pre_action 03:31:19 INFO - Running main action method: download_and_extract 03:31:19 INFO - mkdir: /builds/slave/test/build/tests 03:31: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')]} 03:31:19 INFO - trying https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:31:19 INFO - Downloading https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:20 INFO - Downloaded 1199 bytes. 03:31:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:31:20 INFO - Using the following test package requirements: 03:31:20 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:31:20 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:31:20 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:31:20 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'jsshell-mac.zip'], 03:31:20 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:31:20 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:31:20 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:31:20 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:31:20 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:31:20 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:31:20 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:31:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:20 INFO - trying https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:31:20 INFO - Downloading https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:21 INFO - Downloaded 16333829 bytes. 03:31:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:31:28 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 03:31:28 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')]} 03:31:28 INFO - trying https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:31:28 INFO - Downloading https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:29 INFO - Downloaded 16333829 bytes. 03:31:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:31:36 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')]} 03:31:36 INFO - trying https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:31:36 INFO - Downloading https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:37 INFO - Downloaded 14413719 bytes. 03:31:37 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 03:31: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')]} 03:31:37 INFO - trying https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:31:37 INFO - Downloading https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:31:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/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 03:31:40 INFO - Downloaded 111039666 bytes. 03:31:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:31:40 INFO - mkdir: /builds/slave/test/properties 03:31:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:40 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:40 INFO - Contents: 03:31:40 INFO - build_url:https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:31:41 INFO - Running post-action listener: _resource_record_post_action 03:31:41 INFO - Running post-action listener: set_extra_try_arguments 03:31:41 INFO - [mozharness: 2016-08-17 10:31:41.306891Z] Finished download-and-extract step (success) 03:31:41 INFO - [mozharness: 2016-08-17 10:31:41.307059Z] Running populate-webroot step. 03:31:41 INFO - Running pre-action listener: _resource_record_pre_action 03:31:41 INFO - Running main action method: populate_webroot 03:31:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:31:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:31:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:31:41 INFO - '--tppagecycles', 03:31:41 INFO - '7'], 03:31:41 INFO - 'tests': ['tsvgm']}}, 03:31:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:31:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['damp', 'tps']}, 03:31:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'tests': ['damp', 'tps']}, 03:31:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['dromaeo_dom']}, 03:31:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['basic_compositor_video']}, 03:31:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:31:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['a11yr', 03:31:41 INFO - 'ts_paint', 03:31:41 INFO - 'tpaint', 03:31:41 INFO - 'sessionrestore', 03:31:41 INFO - 'sessionrestore_no_auto_restore', 03:31:41 INFO - 'tabpaint']}, 03:31:41 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:41 INFO - 'ts_paint', 03:31:41 INFO - 'tpaint', 03:31:41 INFO - 'sessionrestore', 03:31:41 INFO - 'sessionrestore_no_auto_restore', 03:31:41 INFO - 'tabpaint']}, 03:31:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:31:41 INFO - 'ts_paint', 03:31:41 INFO - 'tpaint', 03:31:41 INFO - 'sessionrestore', 03:31:41 INFO - 'sessionrestore_no_auto_restore', 03:31:41 INFO - 'tabpaint']}, 03:31:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:31:41 INFO - 'ts_paint', 03:31:41 INFO - 'tpaint', 03:31:41 INFO - 'sessionrestore', 03:31:41 INFO - 'sessionrestore_no_auto_restore', 03:31:41 INFO - 'tabpaint']}, 03:31:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['a11yr', 03:31:41 INFO - 'ts_paint', 03:31:41 INFO - 'tpaint', 03:31:41 INFO - 'sessionrestore', 03:31:41 INFO - 'sessionrestore_no_auto_restore', 03:31:41 INFO - 'tabpaint']}, 03:31:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['a11yr', 03:31:41 INFO - 'ts_paint', 03:31:41 INFO - 'tpaint', 03:31:41 INFO - 'sessionrestore', 03:31:41 INFO - 'sessionrestore_no_auto_restore', 03:31:41 INFO - 'tabpaint']}, 03:31:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['tsvgx', 03:31:41 INFO - 'tsvgr_opacity', 03:31:41 INFO - 'tart', 03:31:41 INFO - 'tscrollx', 03:31:41 INFO - 'cart']}, 03:31:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:41 INFO - 'tsvgr_opacity', 03:31:41 INFO - 'tart', 03:31:41 INFO - 'tscrollx', 03:31:41 INFO - 'cart']}, 03:31:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'talos_options': ['--disable-e10s'], 03:31:41 INFO - 'tests': ['tp5o']}, 03:31:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'tests': ['tp5o']}, 03:31:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'talos_options': ['--disable-e10s', 03:31:41 INFO - '--xperf_path', 03:31:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:41 INFO - 'tests': ['tp5n']}, 03:31:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:41 INFO - 'talos_options': ['--xperf_path', 03:31:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:41 INFO - 'tests': ['tp5n']}}, 03:31:41 INFO - 'talos.zip': {'path': '', 03:31:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:31:41 INFO - Running post-action listener: _resource_record_post_action 03:31:41 INFO - [mozharness: 2016-08-17 10:31:41.325996Z] Finished populate-webroot step (success) 03:31:41 INFO - [mozharness: 2016-08-17 10:31:41.326109Z] Running create-virtualenv step. 03:31:41 INFO - Running pre-action listener: _resource_record_pre_action 03:31:41 INFO - Running main action method: create_virtualenv 03:31:41 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:41 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 03:31:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:31:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:41 INFO - Using real prefix '/tools/python27' 03:31:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:42 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:45 INFO - Installing pip.................done. 03:31:45 INFO - Return code: 0 03:31:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:45 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:45 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 03:31:45 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 03:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:45 INFO - 'HOME': '/Users/cltbld', 03:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:45 INFO - 'LOGNAME': 'cltbld', 03:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:45 INFO - 'MOZ_NO_REMOTE': '1', 03:31:45 INFO - 'NO_EM_RESTART': '1', 03:31:45 INFO - 'PAGER': '/bin/cat', 03:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:45 INFO - 'PWD': '/builds/slave/test', 03:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:45 INFO - 'SHELL': '/bin/bash', 03:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:31:45 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:45 INFO - 'USER': 'cltbld', 03:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:45 INFO - 'XPC_FLAGS': '0x0', 03:31:45 INFO - 'XPC_SERVICE_NAME': '0', 03:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:45 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 03:31:45 INFO - Cleaning up... 03:31:45 INFO - Return code: 0 03:31:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:45 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:45 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 03:31:45 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 03:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:45 INFO - 'HOME': '/Users/cltbld', 03:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:45 INFO - 'LOGNAME': 'cltbld', 03:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:45 INFO - 'MOZ_NO_REMOTE': '1', 03:31:45 INFO - 'NO_EM_RESTART': '1', 03:31:45 INFO - 'PAGER': '/bin/cat', 03:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:45 INFO - 'PWD': '/builds/slave/test', 03:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:45 INFO - 'SHELL': '/bin/bash', 03:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:31:45 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:45 INFO - 'USER': 'cltbld', 03:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:45 INFO - 'XPC_FLAGS': '0x0', 03:31:45 INFO - 'XPC_SERVICE_NAME': '0', 03:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:45 INFO - Downloading/unpacking psutil>=3.1.1 03:31:45 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 03:31:45 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 03:31:45 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 03:31:45 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 03:31:45 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 03:31:45 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 03:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:31:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:48 INFO - Installing collected packages: psutil 03:31:48 INFO - Running setup.py install for psutil 03:31:48 INFO - building 'psutil._psutil_osx' extension 03:31:48 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 03:31:49 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 03:31:49 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 03:31:49 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 03:31:49 INFO - building 'psutil._psutil_posix' extension 03:31:49 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 03:31:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:31:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:31:49 INFO - ^ 03:31:49 INFO - 1 warning generated. 03:31:49 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 03:31:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:50 INFO - Successfully installed psutil 03:31:50 INFO - Cleaning up... 03:31:50 INFO - Return code: 0 03:31:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:31:50 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')]} 03:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:50 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')]} 03:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:50 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:50 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 03:31:50 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 03:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:50 INFO - 'HOME': '/Users/cltbld', 03:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:50 INFO - 'LOGNAME': 'cltbld', 03:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:50 INFO - 'MOZ_NO_REMOTE': '1', 03:31:50 INFO - 'NO_EM_RESTART': '1', 03:31:50 INFO - 'PAGER': '/bin/cat', 03:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:50 INFO - 'PWD': '/builds/slave/test', 03:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:50 INFO - 'SHELL': '/bin/bash', 03:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:31:50 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:50 INFO - 'USER': 'cltbld', 03:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:50 INFO - 'XPC_FLAGS': '0x0', 03:31:50 INFO - 'XPC_SERVICE_NAME': '0', 03:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:50 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 03:31:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:31:52 INFO - Installing collected packages: mozsystemmonitor 03:31:52 INFO - Running setup.py install for mozsystemmonitor 03:31:53 INFO - Successfully installed mozsystemmonitor 03:31:53 INFO - Cleaning up... 03:31:53 INFO - Return code: 0 03:31:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:53 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:53 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 03:31:53 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 03:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:53 INFO - 'HOME': '/Users/cltbld', 03:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:53 INFO - 'LOGNAME': 'cltbld', 03:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:53 INFO - 'MOZ_NO_REMOTE': '1', 03:31:53 INFO - 'NO_EM_RESTART': '1', 03:31:53 INFO - 'PAGER': '/bin/cat', 03:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:53 INFO - 'PWD': '/builds/slave/test', 03:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:53 INFO - 'SHELL': '/bin/bash', 03:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:31:53 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:53 INFO - 'USER': 'cltbld', 03:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:53 INFO - 'XPC_FLAGS': '0x0', 03:31:53 INFO - 'XPC_SERVICE_NAME': '0', 03:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:53 INFO - Downloading/unpacking jsonschema==2.5.1 03:31:53 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 03:31:53 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 03:31:53 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 03:31:53 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 03:31:53 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 03:31:53 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 03:31:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:31:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:31:55 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 03:31:55 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 03:31:55 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 03:31:55 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 03:31:55 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 03:31:55 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 03:31:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:31:56 INFO - warning: no files found matching '*.txt' 03:31:56 INFO - no previously-included directories found matching 'build' 03:31:56 INFO - no previously-included directories found matching 'dist' 03:31:56 INFO - no previously-included directories found matching '.git*' 03:31:56 INFO - Installing collected packages: jsonschema, functools32 03:31:56 INFO - Running setup.py install for functools32 03:31:56 INFO - warning: no files found matching '*.txt' 03:31:56 INFO - no previously-included directories found matching 'build' 03:31:56 INFO - no previously-included directories found matching 'dist' 03:31:56 INFO - no previously-included directories found matching '.git*' 03:31:56 INFO - Successfully installed jsonschema functools32 03:31:56 INFO - Cleaning up... 03:31:56 INFO - Return code: 0 03:31:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:31:56 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')]} 03:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:56 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')]} 03:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:56 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:56 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 03:31:56 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 03:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:56 INFO - 'HOME': '/Users/cltbld', 03:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:56 INFO - 'LOGNAME': 'cltbld', 03:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:56 INFO - 'MOZ_NO_REMOTE': '1', 03:31:56 INFO - 'NO_EM_RESTART': '1', 03:31:56 INFO - 'PAGER': '/bin/cat', 03:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:56 INFO - 'PWD': '/builds/slave/test', 03:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:56 INFO - 'SHELL': '/bin/bash', 03:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:31:56 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:56 INFO - 'USER': 'cltbld', 03:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:56 INFO - 'XPC_FLAGS': '0x0', 03:31:56 INFO - 'XPC_SERVICE_NAME': '0', 03:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:31:56 INFO - Cleaning up... 03:31:56 INFO - Return code: 0 03:31:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:31:56 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')]} 03:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:56 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')]} 03:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:56 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:31:56 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 03:31:56 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 03:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:31:56 INFO - 'HOME': '/Users/cltbld', 03:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:31:56 INFO - 'LOGNAME': 'cltbld', 03:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:56 INFO - 'MOZ_NO_REMOTE': '1', 03:31:56 INFO - 'NO_EM_RESTART': '1', 03:31:56 INFO - 'PAGER': '/bin/cat', 03:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:56 INFO - 'PWD': '/builds/slave/test', 03:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:31:56 INFO - 'SHELL': '/bin/bash', 03:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:31:56 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:31:56 INFO - 'USER': 'cltbld', 03:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:56 INFO - 'XPC_FLAGS': '0x0', 03:31:56 INFO - 'XPC_SERVICE_NAME': '0', 03:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:57 INFO - Downloading/unpacking blobuploader==1.2.4 03:31:57 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 03:31:57 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 03:31:57 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 03:31:57 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 03:31:57 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 03:31:57 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 03:31:59 INFO - Downloading blobuploader-1.2.4.tar.gz 03:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:31:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:31:59 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 03:31:59 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 03:31:59 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 03:31:59 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 03:31:59 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 03:31:59 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 03:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:32:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:32:00 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 03:32:00 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 03:32:00 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 03:32:00 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 03:32:00 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 03:32:00 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 03:32:00 INFO - Downloading docopt-0.6.1.tar.gz 03:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:32:00 INFO - Installing collected packages: blobuploader, requests, docopt 03:32:00 INFO - Running setup.py install for blobuploader 03:32:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:32:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:32:00 INFO - Running setup.py install for requests 03:32:01 INFO - Running setup.py install for docopt 03:32:01 INFO - Successfully installed blobuploader requests docopt 03:32:01 INFO - Cleaning up... 03:32:01 INFO - Return code: 0 03:32:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:01 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:32:01 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 03:32:01 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 03:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:01 INFO - 'HOME': '/Users/cltbld', 03:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:01 INFO - 'LOGNAME': 'cltbld', 03:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:01 INFO - 'MOZ_NO_REMOTE': '1', 03:32:01 INFO - 'NO_EM_RESTART': '1', 03:32:01 INFO - 'PAGER': '/bin/cat', 03:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:01 INFO - 'PWD': '/builds/slave/test', 03:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:01 INFO - 'SHELL': '/bin/bash', 03:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:32:01 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:01 INFO - 'USER': 'cltbld', 03:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:01 INFO - 'XPC_FLAGS': '0x0', 03:32:01 INFO - 'XPC_SERVICE_NAME': '0', 03:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-SoG_ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-H_Lefh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fCWPLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-89a7hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-m_4cEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ew5HOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-IbpCbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fZt3Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-yxL_Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:02 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-d78uKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-rlaa0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-WGV4xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-IhINC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-VOBoGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ukFeSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Vlexja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:03 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-TDHzmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:32:03 INFO - Running setup.py install for manifestparser 03:32:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:32:03 INFO - Running setup.py install for mozcrash 03:32:04 INFO - Running setup.py install for mozdebug 03:32:04 INFO - Running setup.py install for mozdevice 03:32:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozfile 03:32:04 INFO - Running setup.py install for mozhttpd 03:32:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozinfo 03:32:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozInstall 03:32:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:32:04 INFO - Running setup.py install for mozleak 03:32:05 INFO - Running setup.py install for mozlog 03:32:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for moznetwork 03:32:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for mozprocess 03:32:05 INFO - Running setup.py install for mozprofile 03:32:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for mozrunner 03:32:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:32:05 INFO - Running setup.py install for mozscreenshot 03:32:06 INFO - Running setup.py install for moztest 03:32:06 INFO - Running setup.py install for mozversion 03:32:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:32:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:32:06 INFO - Cleaning up... 03:32:06 INFO - Return code: 0 03:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:06 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:32:06 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 03:32:06 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 03:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:06 INFO - 'HOME': '/Users/cltbld', 03:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:06 INFO - 'LOGNAME': 'cltbld', 03:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:06 INFO - 'MOZ_NO_REMOTE': '1', 03:32:06 INFO - 'NO_EM_RESTART': '1', 03:32:06 INFO - 'PAGER': '/bin/cat', 03:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:06 INFO - 'PWD': '/builds/slave/test', 03:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:06 INFO - 'SHELL': '/bin/bash', 03:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:32:06 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:06 INFO - 'USER': 'cltbld', 03:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:06 INFO - 'XPC_FLAGS': '0x0', 03:32:06 INFO - 'XPC_SERVICE_NAME': '0', 03:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:32:06 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-LmsG0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:32:06 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-kM5J4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:32:06 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)) 03:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:32:06 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Xaipjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-TxdqJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-4N9CgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-FqZkZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-v3xlSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-FQnaT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-SdWU84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-xjauWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-w9pEJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-T4BBGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:32:07 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)) 03:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:32:07 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-c5zUEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:32:08 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)) 03:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:32:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-pg2WIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:32:08 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)) 03:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-iafddY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:32:08 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)) 03:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:32:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jfBMBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:32:08 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)) 03:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:32:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-rUxk0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 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)) 03:32:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:32:08 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 03:32:08 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 03:32:08 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 03:32:08 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 03:32:08 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 03:32:08 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 03:32:10 INFO - Downloading blessings-1.6.tar.gz 03:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:32:11 INFO - Installing collected packages: blessings 03:32:11 INFO - Running setup.py install for blessings 03:32:11 INFO - Successfully installed blessings 03:32:11 INFO - Cleaning up... 03:32:11 INFO - Return code: 0 03:32:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:11 INFO - Reading from file tmpfile_stdout 03:32:11 INFO - Current package versions: 03:32:11 INFO - blessings == 1.6 03:32:11 INFO - blobuploader == 1.2.4 03:32:11 INFO - docopt == 0.6.1 03:32:11 INFO - functools32 == 3.2.3-2 03:32:11 INFO - jsonschema == 2.5.1 03:32:11 INFO - manifestparser == 1.1 03:32:11 INFO - mozInstall == 1.12 03:32:11 INFO - mozcrash == 0.17 03:32:11 INFO - mozdebug == 0.1 03:32:11 INFO - mozdevice == 0.48 03:32:11 INFO - mozfile == 1.2 03:32:11 INFO - mozhttpd == 0.7 03:32:11 INFO - mozinfo == 0.9 03:32:11 INFO - mozleak == 0.1 03:32:11 INFO - mozlog == 3.3 03:32:11 INFO - moznetwork == 0.27 03:32:11 INFO - mozprocess == 0.23 03:32:11 INFO - mozprofile == 0.28 03:32:11 INFO - mozrunner == 6.12 03:32:11 INFO - mozscreenshot == 0.1 03:32:11 INFO - mozsystemmonitor == 0.3 03:32:11 INFO - moztest == 0.7 03:32:11 INFO - mozversion == 1.4 03:32:11 INFO - psutil == 3.1.1 03:32:11 INFO - requests == 1.2.3 03:32:11 INFO - wsgiref == 0.1.2 03:32:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32:11 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 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:32:11 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 03:32:11 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 03:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:11 INFO - 'HOME': '/Users/cltbld', 03:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:11 INFO - 'LOGNAME': 'cltbld', 03:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:11 INFO - 'MOZ_NO_REMOTE': '1', 03:32:11 INFO - 'NO_EM_RESTART': '1', 03:32:11 INFO - 'PAGER': '/bin/cat', 03:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:11 INFO - 'PWD': '/builds/slave/test', 03:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:11 INFO - 'SHELL': '/bin/bash', 03:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:32:11 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:11 INFO - 'USER': 'cltbld', 03:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:11 INFO - 'XPC_FLAGS': '0x0', 03:32:11 INFO - 'XPC_SERVICE_NAME': '0', 03:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 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)) 03:32:11 INFO - Cleaning up... 03:32:11 INFO - Return code: 0 03:32:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:32: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')]} 03:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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')]} 03:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:32: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb38da0140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, '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.xWeV7Up1eu/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.MlOdPZl26c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 03:32: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', 'jsonschema'] in /builds/slave/test/build 03:32: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 jsonschema 03:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:11 INFO - 'HOME': '/Users/cltbld', 03:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:11 INFO - 'LOGNAME': 'cltbld', 03:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:11 INFO - 'MOZ_NO_REMOTE': '1', 03:32:11 INFO - 'NO_EM_RESTART': '1', 03:32:11 INFO - 'PAGER': '/bin/cat', 03:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:11 INFO - 'PWD': '/builds/slave/test', 03:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:11 INFO - 'SHELL': '/bin/bash', 03:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:32:11 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:11 INFO - 'USER': 'cltbld', 03:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:11 INFO - 'XPC_FLAGS': '0x0', 03:32:11 INFO - 'XPC_SERVICE_NAME': '0', 03:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:32:12 INFO - Cleaning up... 03:32:12 INFO - Return code: 0 03:32:12 INFO - Running post-action listener: _resource_record_post_action 03:32:12 INFO - Running post-action listener: _start_resource_monitoring 03:32:12 INFO - Starting resource monitoring. 03:32:12 INFO - [mozharness: 2016-08-17 10:32:12.131600Z] Finished create-virtualenv step (success) 03:32:12 INFO - [mozharness: 2016-08-17 10:32:12.132625Z] Running install step. 03:32:12 INFO - Running pre-action listener: _resource_record_pre_action 03:32:12 INFO - Running main action method: install 03:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:32:12 INFO - Reading from file tmpfile_stdout 03:32:12 INFO - Detecting whether we're running mozinstall >=1.0... 03:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:32:12 INFO - Reading from file tmpfile_stdout 03:32:12 INFO - Output received: 03:32:12 INFO - Usage: mozinstall [options] installer 03:32:12 INFO - Options: 03:32:12 INFO - -h, --help show this help message and exit 03:32:12 INFO - -d DEST, --destination=DEST 03:32:12 INFO - Directory to install application into. [default: 03:32:12 INFO - "/builds/slave/test"] 03:32:12 INFO - --app=APP Application being installed. [default: firefox] 03:32:12 INFO - mkdir: /builds/slave/test/build/application 03:32:12 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'] 03:32:12 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 03:32:46 INFO - Reading from file tmpfile_stdout 03:32:46 INFO - Output received: 03:32:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:32:46 INFO - Running post-action listener: _resource_record_post_action 03:32:46 INFO - [mozharness: 2016-08-17 10:32:46.367311Z] Finished install step (success) 03:32:46 INFO - [mozharness: 2016-08-17 10:32:46.367454Z] Running run-tests step. 03:32:46 INFO - Running pre-action listener: _resource_record_pre_action 03:32:46 INFO - Running main action method: run_tests 03:32:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:32:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:32:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:32:46 INFO - 2016-08-17 03:32:46.382 screenresolution[1634:7664] starting screenresolution argv=screenresolution get 03:32:46 INFO - 2016-08-17 03:32:46.384 screenresolution[1634:7664] Display 0: 1600x1200x32@60 03:32:46 INFO - 2016-08-17 03:32:46.390 screenresolution[1635:7667] starting screenresolution argv=screenresolution list 03:32:46 INFO - Available Modes on Display 0 03:32:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:32:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:32:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:32:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:32:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:32:46 INFO - Intel Iris: 03:32:46 INFO - Chipset Model: Intel Iris 03:32:46 INFO - Type: GPU 03:32:46 INFO - Bus: Built-In 03:32:46 INFO - VRAM (Dynamic, Max): 1536 MB 03:32:46 INFO - Vendor: Intel (0x8086) 03:32:46 INFO - Device ID: 0x0a2e 03:32:46 INFO - Revision ID: 0x0009 03:32:46 INFO - Displays: 03:32:46 INFO - Display: 03:32:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:32:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:32:46 INFO - Display Serial Number: 1600x1200 60 03:32:46 INFO - Main Display: Yes 03:32:46 INFO - Mirror: Off 03:32:46 INFO - Online: Yes 03:32:46 INFO - Rotation: Supported 03:32:46 INFO - Return code: 0 03:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:32:46 INFO - Python 2.7.3 03:32:46 INFO - Return code: 0 03:32:46 INFO - grabbing minidump binary from tooltool 03:32:46 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')]} 03:32:46 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 0x104ee7d30>, '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 0x104c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104edfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:32:46 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 03:32:46 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 03:32:46 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 03:32:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:32:46 INFO - Return code: 0 03:32:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 03:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --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 03:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xWeV7Up1eu/Render', 03:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:32:46 INFO - 'HOME': '/Users/cltbld', 03:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:32:46 INFO - 'LOGNAME': 'cltbld', 03:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:32:46 INFO - 'MOZ_NO_REMOTE': '1', 03:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:32:46 INFO - 'NO_EM_RESTART': '1', 03:32:46 INFO - 'PAGER': '/bin/cat', 03:32:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:32:46 INFO - 'PWD': '/builds/slave/test', 03:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:32:46 INFO - 'SHELL': '/bin/bash', 03:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MlOdPZl26c/Listeners', 03:32:46 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 03:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:32:46 INFO - 'USER': 'cltbld', 03:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:32:46 INFO - 'XPC_FLAGS': '0x0', 03:32:46 INFO - 'XPC_SERVICE_NAME': '0', 03:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:32:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 03:32:46 INFO - mozversion application_buildid: 20160816133815 03:32:46 INFO - mozversion application_changeset: bb2261b4ac8bcf7ea068e1fc765765985f851dfd 03:32:46 INFO - mozversion application_display_name: Nightly 03:32:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:32:46 INFO - mozversion application_name: Firefox 03:32:46 INFO - mozversion application_remotingname: firefox 03:32:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:32:46 INFO - mozversion application_vendor: Mozilla 03:32:46 INFO - mozversion application_version: 51.0a1 03:32:46 INFO - mozversion platform_buildid: 20160816133815 03:32:46 INFO - mozversion platform_changeset: bb2261b4ac8bcf7ea068e1fc765765985f851dfd 03:32:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:32:46 INFO - mozversion platform_version: 51.0a1 03:32:46 INFO - using testdate: 1471429966 03:32:46 INFO - actual date: 1471429966 03:32:46 INFO - starting webserver on 'localhost:49268' 03:32:46 INFO - SUITE-START | Running 2 tests 03:32:46 INFO - TEST-START | dromaeo_css 03:32:46 INFO - operating with platform_type : mac_ 03:32:46 INFO - Initialising browser for dromaeo_css test... 03:32:46 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmptGcNbl/profile http://localhost:49268/getInfo.html) 03:32:49 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 03:32:49 INFO - PROCESS | 1645 | colorDepth:24 03:32:49 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 03:32:49 INFO - PROCESS | 1645 | __metrics 03:32:49 INFO - PROCESS | 1645 | 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:32:49 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:32:50 INFO - TEST-INFO | 1645: exit 0 03:32:50 INFO - Browser initialized. 03:32:50 INFO - Running cycle 1/1 for dromaeo_css test... 03:32:50 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmptGcNbl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:33:02 INFO - PROCESS | 1647 | RSS: Main: 228335616 03:33:02 INFO - PROCESS | 1647 | 03:33:53 INFO - PROCESS | 1647 | 1471430033758 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] 03:36:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 03:36:07 INFO - PROCESS | 1647 | RSS: Main: 335384576 03:36:07 INFO - PROCESS | 1647 | 03:39:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 03:39:10 INFO - PROCESS | 1647 | RSS: Main: 372232192 03:39:10 INFO - PROCESS | 1647 | 03:42:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 03:42:13 INFO - PROCESS | 1647 | RSS: Main: 340770816 03:42:13 INFO - PROCESS | 1647 | 03:45:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 03:45:16 INFO - PROCESS | 1647 | RSS: Main: 341151744 03:45:16 INFO - PROCESS | 1647 | 03:48:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 03:48:20 INFO - PROCESS | 1647 | RSS: Main: 332787712 03:48:20 INFO - PROCESS | 1647 | 03:51:23 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 03:51:24 INFO - PROCESS | 1647 | RSS: Main: 487763968 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | __start_tp_report 03:51:24 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 03:51:24 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 03:51:24 INFO - PROCESS | 1647 | |i|pagename|runs| 03:51:24 INFO - PROCESS | 1647 | |0;dojo.html;2590.872970257938;2798.524250877351;3263.494158405445;3303.834808259587;3320.7675462717602;5391.595630327726;5403.135498320269;5466.808661696847;5617.325920889489;5773.249477567921;5817.970910145454;5826.359100498952;5828.154917536955;5830.212921255629;5862.765489380419;5186.92219616706;5213.113770658978;5257.158854583267;5300.761465734051;5315.946840531584;4080.7551546907134;5300.231466437364;5311.442298558653;5325.467453254682;5419.810306639269;5250.18622113573;5262.947370526284;5288.312519372476;5310.73446327684;5330.546903513206;5345.30511033567;5366.838994830161;5369;5380.515753582196;5391;5132.024915266087;5164.870878228075;5227.398849132361;5247.976644554314;5271.920921186185;5687.687177205252;5722.570807189436;5727.398623144593;5763.962486752383;5848.502877255439;6175.166352542395;6201.441870231701;6208.81373558794;6228.781992630236;6230.221222347207;6113.899498090342;6148.678034222664;6221.626702397871;6263.373662633746;6276.560640755149;5922.5558083143505;6026.457618814327;6029.095635654599;6051.364606716319;6051.697415129226;6340.207474065742;6342.714577844008;6343.207099112611;6351.841203969938;6358.332375100548;6087.604305720114;6109.114178444101;6143.723532441051;6196.380361963767;6203.937960620427;6166.229221347332;6202.844928876724;6204.410580994799;6209.658468784261;6243.687815609202;6093.6039157454625;6157.476614487729;6162.753489860445;6179.660118693515;6186.350433204449;6110.558759196641;6153.753849846045;6175.59858609189;6178.567500274938;6194.101855230966;4253.595908388698;4970.478099799541;4979.601631869442;5001.0998020355955;5054.090263752486;4464.888377790581;4471.485779135376;4482.9775851121185;4498.077894031716;4538.863834084983;5844.795432159939;5858.677772722456;5877.35349111606;5909.409059094056;5957.6127551709;6105.459747278077;6130.877382452326;6161.938380616226;6208.379162083756;6241.313455519889;5833.270841144857;5839.124131380327;5851.882962340729;5909.261342332208;5936.525077993837;5522.447755224526;5526.143447765522;5553.666779993213;5556.833295001075;5569.136783798518;6398.584092034003;6412.647304398303;6426.068220108152;6431;6437.871242575216;6114.206128133634;6164.506839452743;6190.288116866618;6282.434580887742;6283.654399008098;6207.379262073757;6236.594621349598;6261.060840873905;6282.465990390685;6348.746050158126;6128.325884152829;6173.876522469615;6181.598196117238;6183.3816618337805;6203.379662033761;6198.256209254918;6213.161223234986;6226.066090086523;6244.094606281973;6245.750169993331;6011.128386384037;6051.243594550681;6135.110179431366;6144.692765361803;6213.813585592439;6196.47329976939;6204.441600255998;6206.596571222856;6219.533534984804;6221.782237621662;6083.695815209133;6089.970928780387;6095.969520152371;6099.419031586226;6103.969480152571;6038.215032045806;6125.418593525661;6142.161149281699;6145.754169833157;6156.569040167145;6101.047669718005;6117.370119345116;6117.541184411276;6118.141359432008;6126.672418005965 03:51:24 INFO - PROCESS | 1647 | |1;ext.html;515.837511183977;538.4669177514262;539.6411386428023;550.4660479335045;557.8465921871488;31143.065708028767;40167.99580010501;41789.164216715646;41851.79074104633;43777.343339849926;43161.784191079;43587.564124358745;43597.7820110899;43622.781886090524;43660.12679746403;9661.227101831853;9883.159931405839;9890.357126786772;9894.85157722631;9895.950520247388;9826.557804898797;9973.401595904234;9973.850392244121;9977.401355918668;9979.900200998007;9922.90077099227;9954.751131221741;9988.850167247498;10005.149562287177;10013.89986100137;9889.50552472377;9962.153216976565;9973.301868869146;9986.800263994752;9996.90003099967;9714.368566043184;9806.509674516317;9807.607695692164;9819.705408837746;9832.65585704504;40838.183236335106;41125.79437102795;41131.1773764526;41136.79431602853;41232;3641.18073433478;3752.568454627698;3940.2316548273097;4142.337226043848;4288.785560721952;31648.841755791305;31971.680283197104;32373;32766.836165819255;32944;3257.690519400653;3288.128645908836;3323.93352132956;3437.535932649085;3489.91275218119;511640;512784;513368;514067;514828.9999999963;57828.71085644545;58313;58491.122633160536;58552;58623;34737.478937815955;35483.82258088693;35539.111522211635;36376.72681456136;36778.44832327517;16556.58608534772;16688.082155481323;16714.749278760828;16792.66414671724;16828.24272907722;12774.425150868234;12865.871341286655;12877.678058048436;12889.677758056123;12891.419886105148;19176.904115479614;19196;19198;19287.93916334587;19297.61404771925;6646.933530664728;7657.846843063107;7836;7891.447598668153;7901.446898717035;859.6776208921655;1016.0956748493846;1060.8620879285797;1071.164491696478;1092.9672109836717;26120;30028;30400.54399184014;30747;30821.69178308233;991.6975322526555;1022.7647641042602;1076.0907033556632;1123.5282378766747;1128.3963079752457;15166.848331516543;15618.062916225063;15736.662383697285;15798.447054353042;16198.271077801528;633040;636731;639036;639368;639430;58528.707356462946;59081.704591476766;59204.70397647984;59249.70375148183;59373.40626593765;1349.1702602899147;1376.2361889151416;1386.7226554689103;1389.0415613226842;1396.4972609860442;28905;29436.55845162367;29481.410371792874;29518.852405737834;30386;50945.6317960255;51452;51538.48461515337;51570.22644660258;51674.224886625976;588933;589552;590166;590268;592454.0754592399;26870.865645671645;27376.589351160146;27881.860590696917;27962.86018569894;28830.855845720638;47729.522704772506;48105.03789924112;48295;48613.5138648609;49296.75351623219;34256.82871585626;35731.35492385973;41071;42687.35968960406;44017.04078327785;39621.60378396179;39664.24868394009;40009.59990400175;40819.183616328104;41734.58265417307;32205;33539.4969075469;34226.65773342235;38909.221815564095;39857;33354.4996825043;34619.65380346265;34851.12872178216;35248.82375588106;35320.82339588286;25401;25692.614610781227;25701.485970280115;25915;26289.21132366032 03:51:24 INFO - PROCESS | 1647 | |2;jquery.html;364.4387643029734;372.1050872651274;373.1696974232335;377.92063666630014;398.6073717388374;15837.445689400873;15874.523764287062;19217;20436.89781551092;20689.068991895365;16228.513144605651;18214.180361883715;20238.08928598213;20853.165873365084;21745.3476395708;5860.912086318709;6055.394460553954;6065;6066.181065556138;6075.2405949256345;4214.831406743727;4432.601065904068;5995.640261584297;6054.455099041085;6430.903536446934;6561.606303621798;6742.494312926526;6768;6771.661416929159;6772.762953296636;6579.736810527573;6627.1716035495565;6645.3354664533645;6688.699008544627;6724.991251312294;5707.77168913243;5750.05124154514;5796.449337312949;5806.912896306559;5812.9709351452975;24919.875400623063;25463.490730185476;25494.107706230192;25751.742482575122;26527.46945061107;1929.5754933914516;2097.5070858348427;2315.166540045599;2318.48993221491;2443.4135807406274;10215.591376345012;10368.740781480448;10410;10415.583376664925;10497.63258285964;1800.8559315254747;1846.778386593604;1883.265526444674;1909.7601142879757;1960.235508151822;92943;98853.5171972421;99028;99745;100151.49924250405;9511.429314241168;11341.829872551918;11480.827787583194;11536.538538458368;11992.160548761678;14496.85503144976;14502.85497145036;14515.491957781638;14532.564023079325;14532.782008269884;7806.765797026098;8391.748247552583;8424.452410593292;8578.528180950108;8590.226879580743;8487.405881588229;8518.87221691675;8523.445976011539;8668.566571671392;8738.86891696625;4496.460424749051;4504.594586487232;4519.728816271035;4521.61566266871;4569.195167908724;1854.2397616976975;1900.1354383755634;1904.828565429118;1912.7609048868892;1913.5598812273256;768.8692922203239;842.9409941304173;884.6420744157687;895.1585509620935;960.3037797596686;16045.919770401073;20420.897895510723;21359.359219223446;21631.89184054101;21682.241121560986;882.4572887674034;907.4237858959476;926.305271046715;959.203860795538;963.3064193780467;10807.67576972692;11253.549858005588;11255.887441125647;11260.211785174959;11269.774604507864;562518;564472;575329;595915;609004.9549752222;122778.38610807067;123892.60201658639;128388.35805821099;129143;131617;927.0590350793949;933.2533972821851;949.8432758594893;952.2239374909427;952.514217748953;18371.816281837546;18722.438326850217;18793;18928;18943.621127577648;52106.739466302424;52235.73882130565;53142.2028669578;53408.1988770161;53571;74097.88853167315;74115.25884741083;74291.62854185695;77518;77659.61170194113;7994.2805147537;8286.917130828615;8311.335093192409;8327.875081873655;8362.665493380196;12119.792938175377;12487.188332758342;12609.054320925783;12735.363231838186;12784;8337.916620833714;10944.452777361259;11059.059979901798;11125.554977800797;11204.66386008421;10926.743862162371;11153.665390038312;11199.104071674083;11371.658850234506;11562.30626162433;10759.623413180814;11016.669499915015;11082.16883733707;11230;11266.155038371991;11415.942920285346;11867.643970680894;11996.220245684352;12176.39118044112;12210.023198144305;10957;11404.885951140383;11417.486213120263;11543.711407214887;11834.349110798988 03:51:24 INFO - PROCESS | 1647 | |3;mootools.html;1310.7706151423502;1350.372076984202;1514.053716427233;1650.009994003598;1882.153031135989;7772.883406748896;8263.669453221864;8296.460730052557;8296.834063318738;8303.792405189874;8103.635336409861;8543.06026337104;8770.824583508325;8793.34049946255;8896.377253592234;2386.0932845518723;2417.4769894966234;2421.9394515137174;2424.636304554322;2427.0413186791206;2059.927902523412;2356.45801465663;2400.95198096038;2405.278416475059;2415.1546958564536;2364.3852598324474;2392.7846493815555;2394.8443351182204;2408.3738228060656;2413.734489206186;2357.3635118518077;2374.6912901322803;2380.2264264114233;2387.1764241336623;2400.387901085212;2359.1742889988536;2387.952240955171;2388.4267775733874;2389.319044072431;2395.2933884504114;8941.910580894173;8951.955240223822;9041.502717350542;9054.77363065922;9066.818663626756;5244.318238628954;5294.338207724035;5414.025475414423;5443.782248710087;5455.263539422167;3148.9527657085164;3211.71094601487;3280.885169019097;3589.3718599245026;3631.2374401375478;1095.917806164533;1106.3527836215824;1127.8026345389605;1132.1508868348737;1142.2004596782203;11957.641270761891;12055.33695646739;12059.397030148546;12071.456784444634;12107.334096624681;4552.339910712994;4559.475660299042;4708.576228139415;4758.595519380822;4778.092162489116;9402.670906518239;9412.341136120544;9415.62337506492;9433.151016408556;9434.858477122849;5356.062689029404;5359.276497672842;5364.7049412282695;5366.463353664602;5371.167469042305;4416.227160246944;4439.778011099496;4473.753943533072;5038.294638750578;5042.9747851261245;3835.5972622874756;3854.69162467002;3908.687305015592;3916.921661566753;3929.0766669832565;1186.5787645385935;1186.792311345511;1188.1385995153616;1200.951961921531;1203.2419575667273;773.4315278270467;866.5797088412115;898.7303808857316;914.2092090341806;947.4457442396138;5848.824535263949;5892.202878122128;6057.062909109114;6062.545309101835;6136.8772624547255;920.3649481857497;929.7907970706536;934.6775362499926;984.0897170117613;1027.8766548014287;4015.7188996769946;4037.2329257440992;4050.0887300357185;4128.545859955402;4137.007118291588;10794;11251.09991200702;11253.212275140662;11274;11281.943590281997;11198.160137989684;11211.831822522669;11215.66353009411;11216.158788090763;11222.989930906258;7780.1052878919645;8036.598170091472;8153;8179.713710020121;8190.017197936167;5641.351244606923;6052.19597775765;6076.8784624308155;6131.754729810757;6199.752009919553;10105.646302379382;10377.273590848568;10396.948015259875;10417.739556511147;10419.218558607983;10000.499975001367;10144.746381340525;10371.377717336984;10512.26414151002;10614.310069845435;7672.846543068996;7921.247481489249;7933.9603301983125;7936.722214722457;7969.242921922408;8482.363822713445;8492.872606910778;8536.530490823065;8556.18716221958;8569.571521424028;7711.652975616223;7729.07251129868;7761.844763104819;7828.178041305581;7848.489848159851;7525.887111693439;7540.057492813398;7556.282153195438;7734.574598397142;7744.186860379579;7100.822479438056;7173.1392232932385;7253.093363329584;7253.56478611285;7269.527480713737;5063.949360506348;5090.720010399464;5131.512506311894;5176.352955880515;5200.803815122467;4763.523647635208;4927.655064145475;5134.306868572695;5156.2523434101095;5348.090824559912 03:51:24 INFO - PROCESS | 1647 | |4;prototype.html;255.31702695290093;290.3090644266645;295.45783487300804;298.7699471407017;302.3635247803374;5103.438621751604;5109.386873575171;5110.821121260757;5143.177091665334;5288.973555132228;4657.720536767788;4765.261384485401;5251.921221181676;5369.62412631116;5385.865353366168;3720.9255814883686;4022;4061.8172182251724;4072.5316588592245;4143.585641435848;3551.3607550640872;3847.4228865670234;3889.241597888413;3985.501812273466;4136.21411931732;3318.867245310185;3328.7170590499836;3341.365140623274;3988.165443895811;4046.2514434829504;3990.2418540477215;4005.6595189408645;4006.759594424344;4007.6192761687594;4021.8592349267783;3495.528103706018;3501.142220156078;3527.0124365177535;3527.4003419418505;3530.1704099536587;4273.786310684453;4756.619470442356;4828.493008234155;4916.532929371704;5157.871053223699;3953.7034722395933;4047.8380864765372;4079.51045874494;4502.8874278142985;4539.409876716006;4634.513376095529;4664.976675116603;5512.310961129859;5528.75120619573;5559.499645031926;3471.9132021699406;3762.3039737648623;3762.7930463824478;3838.213166300902;3870.271458621665;5029.64792464524;5240.502152295526;5627.831165065055;5734.942650573483;5775.729339545293;3479.443289073736;3597.6042635310096;3814.6185381461632;3839.520059992501;3854.807259637007;3267.3138640885204;3296.456084746038;3738.9065273368383;3865.7100717446306;3899.0699603695784;3784.4890939723336;4008.5390180129075;4519.118771839459;4536.79584418702;4567.429071366079;5477.561795056386;5556;5556.416266436046;5557.472040156179;5577.74500737339;1157.4779165397476;1231.7866901102407;1247.6943148928572;1270.39021269791;1325.9337033148304;707.4906067631297;782.6478084861835;801.8155824160359;802.2258520527602;824.5959479854828;3837.869982159928;3903.6681882039775;4127.4015267786;4175.726403446978;4285.61429471349;3797.6392242736897;3986.4652108938267;4248.895287225344;4312.116016216668;4411.404460397805;3788.3931780963776;3877.269730928263;4037.3757057912594;4161.729487583298;4198.769067701244;2174.6520556710852;2215.5347377051;2267.8866056697098;2503.68703912011;2511.033252197917;4371.344298355209;4808.158572249842;4885.438174609894;4900.411950565955;4916.680415772963;4379.693421460531;4609.594073807454;4746.477887432379;4792.274781078809;4854.417469903564;3948.9210215795524;4550.797649034399;4814.927776083291;4844.854654360375;4890.682105663121;4215.2811436623815;4298.946758044299;4642.2108241598335;4701.388819453586;4865.367502224688;4212.7893605319;4690.343351930801;4734.928976065293;5345.797195630952;5527;5442.108892141979;5497;5830.367497100819;5876.442742673281;5924.822255332347;3725.5343082114737;3772.8681395581766;3819.9045023873514;3822.7132965027176;3828.674562662205;4831.782569784438;5424;5625.774969001358;5699.230603868425;5703.458171473703;5491.1171634892;5496.31296087989;5500.972495137498;5529.336479622471;5532.2254884317035;4619.445666520039;5258.842234732964;5478.479544443271;5500.862478438071;5525.309336332958;4221.303484924953;5376.677399356051;5418.945810541844;5421.484958928895;5432.130859062531;5540.307461567304;5540.418256083053;5549.750261238173;5554.944450555443;5566.610337276352;5152.922706159336;5419.972900135632;5433.972830135824;5463.235147079492;5473.72631368422 03:51:24 INFO - PROCESS | 1647 | |5;yui.html;662.7349060375849;716.6094478509212;780.2821404308036;786.5713186313459;848.919352661497;14801.629959251024;15514.60816787988;16160.434384796534;16235.350585976561;16236.75644865328;12563.434645440955;14801.407943682267;14808.851911480882;15013.099214047164;15094.320755566;836.9163083691643;868.695956415256;870.2341939093589;870.2863651805523;873.8995015573195;678.413172605695;679.1171477079802;705.6048612776862;864.5893200729666;867.7049803066956;914.9817003659923;917.2203626917134;917.6467060181844;918.7060140754961;919.3886065766258;911.8039621481413;912.0150237743221;912.3659056955419;913.2602591900582;913.7715571107257;525.5165247971875;547.6440313796024;652.3118110393533;673.6227712481026;680.9863802723968;15215.847841521665;15268;15271.083734975937;15304.234788260654;15309.61725956849;11586.884131158666;13532.526361577298;14240.359183836648;14399.352029158714;14415.927920360436;11280.153988450898;11618.128640351923;11648.184627076109;12991.025673074462;13748.65628359299;4396.494403143647;4429.914670905617;4721.5750582447745;4863.635227357962;4949.158643030693;87117.56441217817;88629;89429.55285223533;89893;90073;10763.461826908624;11196.160287978435;11201.327920324644;11234.3259404436;11699.415029248503;21430.785692143192;21472.24847130343;21474.57050859005;21502.032408541654;21523;7735.574543400167;7799.298063174341;7835.725749598737;7853.175416581291;7881.763547093597;4444.911101777946;4472.753998530047;4486.327050942385;4622.699524530894;4660.836870709509;3014.8191108533556;3096.303331750383;3116.6104236970377;3131.029380891931;3131.6711745266875;614.8192292411584;632.6457183976987;637.1112298343785;639.0861068671751;639.3893831391104;543.2476020711343;574.3136951343105;589.6933594530819;623.8970569856014;654.5549026661915;8034.638441270157;8745.731868878976;8890.86663700045;8964.462132272085;9169.449833009907;621.5400603553428;626.232864740689;628.5976974736082;640.7885397818709;648.1994736500418;4335.869923902288;5031.899362012739;5051.343325367752;5163.277141200235;5169.121249387613;143291;144769;149643;155331;158253;122416;131824.34087829498;139389;140327;142279.28315125513;120058;122055.38972305259;125694;126046.36976815057;126245;10128.03783640553;10145.9492702536;10186.592536298465;10206;10209.030142136486;49351.753241233564;59005.40994589999;59805.759243431174;68633.65683171552;69085.81856360893;91857.11714954293;94032;96450;103332.93334133124;106476;11652.708682283012;13600.319984000562;13642.386092625657;13807.654808629864;14327.713445731235;12625.494980200689;14984.700305993601;15039;15331.386744530093;15749.212539372757;12110.03136202924;13955.441782328593;14745.410183592536;14807.555773326818;14816.40734370613;8249.711260105867;13155.868441315464;13844.307784610528;13879.72240555163;13991.300434978008;13658.453661853415;13669.658258543615;13750.587482375544;13982.300884955916;14365.20991345486;9769.364991275545;10974.561017559208;11311.7737645245;11438.428078596204;11930.284182949052;11312.640887809888;11345.526937912156;11536.884631153582;11694.356810375511;11723.179377443497 03:51:24 INFO - PROCESS | 1647 | __end_tp_report 03:51:24 INFO - PROCESS | 1647 | __start_cc_report 03:51:24 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1311 03:51:24 INFO - PROCESS | 1647 | __end_cc_report 03:51:24 INFO - PROCESS | 1647 | __startTimestamp1471431084064__endTimestamp 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | ------- Summary: start ------- 03:51:24 INFO - PROCESS | 1647 | Number of tests: 6 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5775.72 Median:6095.97 stddev:684.86 (11.2%) stddev-sans-first:639.76 03:51:24 INFO - PROCESS | 1647 | Values: 2590.9 2798.5 3263.5 3303.8 3320.8 5391.6 5403.1 5466.8 5617.3 5773.2 5818.0 5826.4 5828.2 5830.2 5862.8 5186.9 5213.1 5257.2 5300.8 5315.9 4080.8 5300.2 5311.4 5325.5 5419.8 5250.2 5262.9 5288.3 5310.7 5330.5 5345.3 5366.8 5369.0 5380.5 5391.0 5132.0 5164.9 5227.4 5248.0 5271.9 5687.7 5722.6 5727.4 5764.0 5848.5 6175.2 6201.4 6208.8 6228.8 6230.2 6113.9 6148.7 6221.6 6263.4 6276.6 5922.6 6026.5 6029.1 6051.4 6051.7 6340.2 6342.7 6343.2 6351.8 6358.3 6087.6 6109.1 6143.7 6196.4 6203.9 6166.2 6202.8 6204.4 6209.7 6243.7 6093.6 6157.5 6162.8 6179.7 6186.4 6110.6 6153.8 6175.6 6178.6 6194.1 4253.6 4970.5 4979.6 5001.1 5054.1 4464.9 4471.5 4483.0 4498.1 4538.9 5844.8 5858.7 5877.4 5909.4 5957.6 6105.5 6130.9 6161.9 6208.4 6241.3 5833.3 5839.1 5851.9 5909.3 5936.5 5522.4 5526.1 5553.7 5556.8 5569.1 6398.6 6412.6 6426.1 6431.0 6437.9 6114.2 6164.5 6190.3 6282.4 6283.7 6207.4 6236.6 6261.1 6282.5 6348.7 6128.3 6173.9 6181.6 6183.4 6203.4 6198.3 6213.2 6226.1 6244.1 6245.8 6011.1 6051.2 6135.1 6144.7 6213.8 6196.5 6204.4 6206.6 6219.5 6221.8 6083.7 6090.0 6096.0 6099.4 6104.0 6038.2 6125.4 6142.2 6145.8 6156.6 6101.0 6117.4 6117.5 6118.1 6126.7 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:70748.96 Median:40828.68 stddev:155721.09 (381.4%) stddev-sans-first:156068.65 03:51:24 INFO - PROCESS | 1647 | Values: 515.8 538.5 539.6 550.5 557.8 31143.1 40168.0 41789.2 41851.8 43777.3 43161.8 43587.6 43597.8 43622.8 43660.1 9661.2 9883.2 9890.4 9894.9 9896.0 9826.6 9973.4 9973.9 9977.4 9979.9 9922.9 9954.8 9988.9 10005.1 10013.9 9889.5 9962.2 9973.3 9986.8 9996.9 9714.4 9806.5 9807.6 9819.7 9832.7 40838.2 41125.8 41131.2 41136.8 41232.0 3641.2 3752.6 3940.2 4142.3 4288.8 31648.8 31971.7 32373.0 32766.8 32944.0 3257.7 3288.1 3323.9 3437.5 3489.9 511640.0 512784.0 513368.0 514067.0 514829.0 57828.7 58313.0 58491.1 58552.0 58623.0 34737.5 35483.8 35539.1 36376.7 36778.4 16556.6 16688.1 16714.7 16792.7 16828.2 12774.4 12865.9 12877.7 12889.7 12891.4 19176.9 19196.0 19198.0 19287.9 19297.6 6646.9 7657.8 7836.0 7891.4 7901.4 859.7 1016.1 1060.9 1071.2 1093.0 26120.0 30028.0 30400.5 30747.0 30821.7 991.7 1022.8 1076.1 1123.5 1128.4 15166.8 15618.1 15736.7 15798.4 16198.3 633040.0 636731.0 639036.0 639368.0 639430.0 58528.7 59081.7 59204.7 59249.7 59373.4 1349.2 1376.2 1386.7 1389.0 1396.5 28905.0 29436.6 29481.4 29518.9 30386.0 50945.6 51452.0 51538.5 51570.2 51674.2 588933.0 589552.0 590166.0 590268.0 592454.1 26870.9 27376.6 27881.9 27962.9 28830.9 47729.5 48105.0 48295.0 48613.5 49296.8 34256.8 35731.4 41071.0 42687.4 44017.0 39621.6 39664.2 40009.6 40819.2 41734.6 32205.0 33539.5 34226.7 38909.2 39857.0 33354.5 34619.7 34851.1 35248.8 35320.8 25401.0 25692.6 25701.5 25915.0 26289.2 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:33975.15 Median:25478.80 stddev:96753.22 (379.7%) stddev-sans-first:96991.73 03:51:24 INFO - PROCESS | 1647 | Values: 364.4 372.1 373.2 377.9 398.6 15837.4 15874.5 19217.0 20436.9 20689.1 16228.5 18214.2 20238.1 20853.2 21745.3 5860.9 6055.4 6065.0 6066.2 6075.2 4214.8 4432.6 5995.6 6054.5 6430.9 6561.6 6742.5 6768.0 6771.7 6772.8 6579.7 6627.2 6645.3 6688.7 6725.0 5707.8 5750.1 5796.4 5806.9 5813.0 24919.9 25463.5 25494.1 25751.7 26527.5 1929.6 2097.5 2315.2 2318.5 2443.4 10215.6 10368.7 10410.0 10415.6 10497.6 1800.9 1846.8 1883.3 1909.8 1960.2 92943.0 98853.5 99028.0 99745.0 100151.5 9511.4 11341.8 11480.8 11536.5 11992.2 14496.9 14502.9 14515.5 14532.6 14532.8 7806.8 8391.7 8424.5 8578.5 8590.2 8487.4 8518.9 8523.4 8668.6 8738.9 4496.5 4504.6 4519.7 4521.6 4569.2 1854.2 1900.1 1904.8 1912.8 1913.6 768.9 842.9 884.6 895.2 960.3 16045.9 20420.9 21359.4 21631.9 21682.2 882.5 907.4 926.3 959.2 963.3 10807.7 11253.5 11255.9 11260.2 11269.8 562518.0 564472.0 575329.0 595915.0 609005.0 122778.4 123892.6 128388.4 129143.0 131617.0 927.1 933.3 949.8 952.2 952.5 18371.8 18722.4 18793.0 18928.0 18943.6 52106.7 52235.7 53142.2 53408.2 53571.0 74097.9 74115.3 74291.6 77518.0 77659.6 7994.3 8286.9 8311.3 8327.9 8362.7 12119.8 12487.2 12609.1 12735.4 12784.0 8337.9 10944.5 11059.1 11125.6 11204.7 10926.7 11153.7 11199.1 11371.7 11562.3 10759.6 11016.7 11082.2 11230.0 11266.2 11415.9 11867.6 11996.2 12176.4 12210.0 10957.0 11404.9 11417.5 11543.7 11834.3 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5796.43 Median:4982.97 stddev:3283.74 (65.9%) stddev-sans-first:3275.64 03:51:24 INFO - PROCESS | 1647 | Values: 1310.8 1350.4 1514.1 1650.0 1882.2 7772.9 8263.7 8296.5 8296.8 8303.8 8103.6 8543.1 8770.8 8793.3 8896.4 2386.1 2417.5 2421.9 2424.6 2427.0 2059.9 2356.5 2401.0 2405.3 2415.2 2364.4 2392.8 2394.8 2408.4 2413.7 2357.4 2374.7 2380.2 2387.2 2400.4 2359.2 2388.0 2388.4 2389.3 2395.3 8941.9 8952.0 9041.5 9054.8 9066.8 5244.3 5294.3 5414.0 5443.8 5455.3 3149.0 3211.7 3280.9 3589.4 3631.2 1095.9 1106.4 1127.8 1132.2 1142.2 11957.6 12055.3 12059.4 12071.5 12107.3 4552.3 4559.5 4708.6 4758.6 4778.1 9402.7 9412.3 9415.6 9433.2 9434.9 5356.1 5359.3 5364.7 5366.5 5371.2 4416.2 4439.8 4473.8 5038.3 5043.0 3835.6 3854.7 3908.7 3916.9 3929.1 1186.6 1186.8 1188.1 1201.0 1203.2 773.4 866.6 898.7 914.2 947.4 5848.8 5892.2 6057.1 6062.5 6136.9 920.4 929.8 934.7 984.1 1027.9 4015.7 4037.2 4050.1 4128.5 4137.0 10794.0 11251.1 11253.2 11274.0 11281.9 11198.2 11211.8 11215.7 11216.2 11223.0 7780.1 8036.6 8153.0 8179.7 8190.0 5641.4 6052.2 6076.9 6131.8 6199.8 10105.6 10377.3 10396.9 10417.7 10419.2 10000.5 10144.7 10371.4 10512.3 10614.3 7672.8 7921.2 7934.0 7936.7 7969.2 8482.4 8492.9 8536.5 8556.2 8569.6 7711.7 7729.1 7761.8 7828.2 7848.5 7525.9 7540.1 7556.3 7734.6 7744.2 7100.8 7173.1 7253.1 7253.6 7269.5 5063.9 5090.7 5131.5 5176.4 5200.8 4763.5 4927.7 5134.3 5156.3 5348.1 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4224.50 Median:4538.10 stddev:1315.21 (29.0%) stddev-sans-first:1284.72 03:51:24 INFO - PROCESS | 1647 | Values: 255.3 290.3 295.5 298.8 302.4 5103.4 5109.4 5110.8 5143.2 5289.0 4657.7 4765.3 5251.9 5369.6 5385.9 3720.9 4022.0 4061.8 4072.5 4143.6 3551.4 3847.4 3889.2 3985.5 4136.2 3318.9 3328.7 3341.4 3988.2 4046.3 3990.2 4005.7 4006.8 4007.6 4021.9 3495.5 3501.1 3527.0 3527.4 3530.2 4273.8 4756.6 4828.5 4916.5 5157.9 3953.7 4047.8 4079.5 4502.9 4539.4 4634.5 4665.0 5512.3 5528.8 5559.5 3471.9 3762.3 3762.8 3838.2 3870.3 5029.6 5240.5 5627.8 5734.9 5775.7 3479.4 3597.6 3814.6 3839.5 3854.8 3267.3 3296.5 3738.9 3865.7 3899.1 3784.5 4008.5 4519.1 4536.8 4567.4 5477.6 5556.0 5556.4 5557.5 5577.7 1157.5 1231.8 1247.7 1270.4 1325.9 707.5 782.6 801.8 802.2 824.6 3837.9 3903.7 4127.4 4175.7 4285.6 3797.6 3986.5 4248.9 4312.1 4411.4 3788.4 3877.3 4037.4 4161.7 4198.8 2174.7 2215.5 2267.9 2503.7 2511.0 4371.3 4808.2 4885.4 4900.4 4916.7 4379.7 4609.6 4746.5 4792.3 4854.4 3948.9 4550.8 4814.9 4844.9 4890.7 4215.3 4298.9 4642.2 4701.4 4865.4 4212.8 4690.3 4734.9 5345.8 5527.0 5442.1 5497.0 5830.4 5876.4 5924.8 3725.5 3772.9 3819.9 3822.7 3828.7 4831.8 5424.0 5625.8 5699.2 5703.5 5491.1 5496.3 5501.0 5529.3 5532.2 4619.4 5258.8 5478.5 5500.9 5525.3 4221.3 5376.7 5418.9 5421.5 5432.1 5540.3 5540.4 5549.8 5554.9 5566.6 5152.9 5420.0 5434.0 5463.2 5473.7 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25120.35 Median:3055.56 stddev:40327.77 (1319.8%) stddev-sans-first:40399.09 03:51:24 INFO - PROCESS | 1647 | Values: 662.7 716.6 780.3 786.6 848.9 14801.6 15514.6 16160.4 16235.4 16236.8 12563.4 14801.4 14808.9 15013.1 15094.3 836.9 868.7 870.2 870.3 873.9 678.4 679.1 705.6 864.6 867.7 915.0 917.2 917.6 918.7 919.4 911.8 912.0 912.4 913.3 913.8 525.5 547.6 652.3 673.6 681.0 15215.8 15268.0 15271.1 15304.2 15309.6 11586.9 13532.5 14240.4 14399.4 14415.9 11280.2 11618.1 11648.2 12991.0 13748.7 4396.5 4429.9 4721.6 4863.6 4949.2 87117.6 88629.0 89429.6 89893.0 90073.0 10763.5 11196.2 11201.3 11234.3 11699.4 21430.8 21472.2 21474.6 21502.0 21523.0 7735.6 7799.3 7835.7 7853.2 7881.8 4444.9 4472.8 4486.3 4622.7 4660.8 3014.8 3096.3 3116.6 3131.0 3131.7 614.8 632.6 637.1 639.1 639.4 543.2 574.3 589.7 623.9 654.6 8034.6 8745.7 8890.9 8964.5 9169.4 621.5 626.2 628.6 640.8 648.2 4335.9 5031.9 5051.3 5163.3 5169.1 143291.0 144769.0 149643.0 155331.0 158253.0 122416.0 131824.3 139389.0 140327.0 142279.3 120058.0 122055.4 125694.0 126046.4 126245.0 10128.0 10145.9 10186.6 10206.0 10209.0 49351.8 59005.4 59805.8 68633.7 69085.8 91857.1 94032.0 96450.0 103332.9 106476.0 11652.7 13600.3 13642.4 13807.7 14327.7 12625.5 14984.7 15039.0 15331.4 15749.2 12110.0 13955.4 14745.4 14807.6 14816.4 8249.7 13155.9 13844.3 13879.7 13991.3 13658.5 13669.7 13750.6 13982.3 14365.2 9769.4 10974.6 11311.8 11438.4 11930.3 11312.6 11345.5 11536.9 11694.4 11723.2 03:51:24 INFO - PROCESS | 1647 | -------- Summary: end -------- 03:51:24 INFO - PROCESS | 1647 | 03:51:24 INFO - TEST-INFO | 1647: exit 0 03:51:24 INFO - TEST-OK | dromaeo_css | took 1117728ms 03:51:24 INFO - TEST-START | kraken 03:51:24 INFO - operating with platform_type : mac_ 03:51:24 INFO - Initialising browser for kraken test... 03:51:24 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpGJoSVC/profile http://localhost:49268/getInfo.html) 03:51:26 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 03:51:26 INFO - PROCESS | 1678 | colorDepth:24 03:51:26 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 03:51:26 INFO - PROCESS | 1678 | __metrics 03:51:27 INFO - PROCESS | 1678 | 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:51:27 INFO - PROCESS | 1678 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:51:27 INFO - TEST-INFO | 1678: exit 0 03:51:27 INFO - Browser initialized. 03:51:27 INFO - Running cycle 1/1 for kraken test... 03:51:27 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpGJoSVC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:39 INFO - PROCESS | 1679 | RSS: Main: 228405248 03:51:39 INFO - PROCESS | 1679 | 03:51:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 03:51:43 INFO - PROCESS | 1679 | RSS: Main: 287293440 03:51:43 INFO - PROCESS | 1679 | 03:51:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 03:51:46 INFO - PROCESS | 1679 | RSS: Main: 293265408 03:51:46 INFO - PROCESS | 1679 | 03:51:50 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 03:51:50 INFO - PROCESS | 1679 | RSS: Main: 386441216 03:51:50 INFO - PROCESS | 1679 | 03:51:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 03:51:53 INFO - PROCESS | 1679 | RSS: Main: 288899072 03:51:53 INFO - PROCESS | 1679 | 03:51:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:51:56 INFO - PROCESS | 1679 | RSS: Main: 293089280 03:51:56 INFO - PROCESS | 1679 | 03:51:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 03:51:59 INFO - PROCESS | 1679 | RSS: Main: 331481088 03:51:59 INFO - PROCESS | 1679 | 03:52:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 03:52:03 INFO - PROCESS | 1679 | RSS: Main: 332304384 03:52:03 INFO - PROCESS | 1679 | 03:52:05 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 03:52:06 INFO - PROCESS | 1679 | RSS: Main: 333910016 03:52:06 INFO - PROCESS | 1679 | 03:52:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:52:08 INFO - PROCESS | 1679 | RSS: Main: 302313472 03:52:08 INFO - PROCESS | 1679 | 03:52:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:52:11 INFO - PROCESS | 1679 | RSS: Main: 308875264 03:52:11 INFO - PROCESS | 1679 | 03:52:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:52:14 INFO - PROCESS | 1679 | RSS: Main: 301805568 03:52:14 INFO - PROCESS | 1679 | 03:52:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:52:18 INFO - PROCESS | 1679 | RSS: Main: 310902784 03:52:18 INFO - PROCESS | 1679 | 03:52:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:52:21 INFO - PROCESS | 1679 | RSS: Main: 313520128 03:52:21 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 03:52:23 INFO - PROCESS | 1679 | RSS: Main: 356941824 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | __start_tp_report 03:52:23 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 03:52:23 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 03:52:23 INFO - PROCESS | 1679 | |i|pagename|runs| 03:52:23 INFO - PROCESS | 1679 | |0;ai-astar;103;114;114;114;108;114;113;113;113;113 03:52:23 INFO - PROCESS | 1679 | |1;audio-beat-detection;141;107;106;162;107;117;131;103;140;109 03:52:23 INFO - PROCESS | 1679 | |2;audio-dft;184;185;184;182;182;183;183;182;194;259 03:52:23 INFO - PROCESS | 1679 | |3;audio-fft;78;58;61;88;59;71;89;64;84;58 03:52:23 INFO - PROCESS | 1679 | |4;audio-oscillator;83;69;102;72;79;68;96;71;68;101 03:52:23 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;87;85;86;84;86;84;87;84;89;129 03:52:23 INFO - PROCESS | 1679 | |6;imaging-darkroom;92;93;93;99;121;93;92;93;92;93 03:52:23 INFO - PROCESS | 1679 | |7;imaging-desaturate;83;86;85;84;84;84;85;85;84;90 03:52:23 INFO - PROCESS | 1679 | |8;json-parse-financial;55;54;56;54;54;56;55;54;52;54 03:52:23 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;63;64;77;75;71;69;67;70;68;70 03:52:23 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;96;85;84;86;85;96;105;102;75;93 03:52:23 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;140;140;137;138;139;145;150;146;203;150 03:52:23 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;168;166;166;170;167;166;168;167;166;167 03:52:23 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;67;66;68;66;66;66;66;66;67 03:52:23 INFO - PROCESS | 1679 | __end_tp_report 03:52:23 INFO - PROCESS | 1679 | __start_cc_report 03:52:23 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2091 03:52:23 INFO - PROCESS | 1679 | __end_cc_report 03:52:23 INFO - PROCESS | 1679 | __startTimestamp1471431143416__endTimestamp 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | ------- Summary: start ------- 03:52:23 INFO - PROCESS | 1679 | Number of tests: 14 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.50 stddev:3.60 (3.2%) stddev-sans-first:1.90 03:52:23 INFO - PROCESS | 1679 | Values: 103.0 114.0 114.0 114.0 108.0 114.0 113.0 113.0 113.0 113.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:124.00 stddev:20.07 (16.2%) stddev-sans-first:20.12 03:52:23 INFO - PROCESS | 1679 | Values: 141.0 107.0 106.0 162.0 107.0 117.0 131.0 103.0 140.0 109.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:191.80 Median:184.00 stddev:23.88 (13.0%) stddev-sans-first:25.16 03:52:23 INFO - PROCESS | 1679 | Values: 184.0 185.0 184.0 182.0 182.0 183.0 183.0 182.0 194.0 259.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:71.00 Median:74.50 stddev:12.75 (17.1%) stddev-sans-first:13.26 03:52:23 INFO - PROCESS | 1679 | Values: 78.0 58.0 61.0 88.0 59.0 71.0 89.0 64.0 84.0 58.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:71.50 stddev:13.89 (19.4%) stddev-sans-first:14.71 03:52:23 INFO - PROCESS | 1679 | Values: 83.0 69.0 102.0 72.0 79.0 68.0 96.0 71.0 68.0 101.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:86.00 stddev:13.76 (16.0%) stddev-sans-first:14.55 03:52:23 INFO - PROCESS | 1679 | Values: 87.0 85.0 86.0 84.0 86.0 84.0 87.0 84.0 89.0 129.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:93.00 stddev:8.99 (9.7%) stddev-sans-first:9.41 03:52:23 INFO - PROCESS | 1679 | Values: 92.0 93.0 93.0 99.0 121.0 93.0 92.0 93.0 92.0 93.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.94 (2.3%) stddev-sans-first:1.92 03:52:23 INFO - PROCESS | 1679 | Values: 83.0 86.0 85.0 84.0 84.0 84.0 85.0 85.0 84.0 90.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.17 (2.2%) stddev-sans-first:1.22 03:52:23 INFO - PROCESS | 1679 | Values: 55.0 54.0 56.0 54.0 54.0 56.0 55.0 54.0 52.0 54.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.00 stddev:4.35 (6.2%) stddev-sans-first:3.95 03:52:23 INFO - PROCESS | 1679 | Values: 63.0 64.0 77.0 75.0 71.0 69.0 67.0 70.0 68.0 70.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:85.50 stddev:9.26 (10.8%) stddev-sans-first:9.62 03:52:23 INFO - PROCESS | 1679 | Values: 96.0 85.0 84.0 86.0 85.0 96.0 105.0 102.0 75.0 93.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:145.50 stddev:19.63 (13.5%) stddev-sans-first:20.57 03:52:23 INFO - PROCESS | 1679 | Values: 140.0 140.0 137.0 138.0 139.0 145.0 150.0 146.0 203.0 150.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:167.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 03:52:23 INFO - PROCESS | 1679 | Values: 168.0 166.0 166.0 170.0 167.0 166.0 168.0 167.0 166.0 167.0 03:52:23 INFO - PROCESS | 1679 | 03:52:23 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 03:52:23 INFO - PROCESS | 1679 | Values: 67.0 67.0 66.0 68.0 66.0 66.0 66.0 66.0 66.0 67.0 03:52:23 INFO - PROCESS | 1679 | -------- Summary: end -------- 03:52:23 INFO - PROCESS | 1679 | 03:52:24 INFO - TEST-INFO | 1679: exit 0 03:52:24 INFO - TEST-OK | kraken | took 59555ms 03:52:24 INFO - SUITE-END | took 1177s 03:52:24 INFO - Completed test suite (00:19:37) 03:52:24 INFO - javascript benchmark 03:52:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2590.872970257938, 2798.524250877351, 3263.494158405445, 3303.834808259587, 3320.7675462717602, 5391.595630327726, 5403.135498320269, 5466.808661696847, 5617.325920889489, 5773.249477567921, 5817.970910145454, 5826.359100498952, 5828.154917536955, 5830.212921255629, 5862.765489380419, 5186.92219616706, 5213.113770658978, 5257.158854583267, 5300.761465734051, 5315.946840531584, 4080.7551546907134, 5300.231466437364, 5311.442298558653, 5325.467453254682, 5419.810306639269, 5250.18622113573, 5262.947370526284, 5288.312519372476, 5310.73446327684, 5330.546903513206, 5345.30511033567, 5366.838994830161, 5369.0, 5380.515753582196, 5391.0, 5132.024915266087, 5164.870878228075, 5227.398849132361, 5247.976644554314, 5271.920921186185, 5687.687177205252, 5722.570807189436, 5727.398623144593, 5763.962486752383, 5848.502877255439, 6175.166352542395, 6201.441870231701, 6208.81373558794, 6228.781992630236, 6230.221222347207, 6113.899498090342, 6148.678034222664, 6221.626702397871, 6263.373662633746, 6276.560640755149, 5922.5558083143505, 6026.457618814327, 6029.095635654599, 6051.364606716319, 6051.697415129226, 6340.207474065742, 6342.714577844008, 6343.207099112611, 6351.841203969938, 6358.332375100548, 6087.604305720114, 6109.114178444101, 6143.723532441051, 6196.380361963767, 6203.937960620427, 6166.229221347332, 6202.844928876724, 6204.410580994799, 6209.658468784261, 6243.687815609202, 6093.6039157454625, 6157.476614487729, 6162.753489860445, 6179.660118693515, 6186.350433204449, 6110.558759196641, 6153.753849846045, 6175.59858609189, 6178.567500274938, 6194.101855230966, 4253.595908388698, 4970.478099799541, 4979.601631869442, 5001.0998020355955, 5054.090263752486, 4464.888377790581, 4471.485779135376, 4482.9775851121185, 4498.077894031716, 4538.863834084983, 5844.795432159939, 5858.677772722456, 5877.35349111606, 5909.409059094056, 5957.6127551709, 6105.459747278077, 6130.877382452326, 6161.938380616226, 6208.379162083756, 6241.313455519889, 5833.270841144857, 5839.124131380327, 5851.882962340729, 5909.261342332208, 5936.525077993837, 5522.447755224526, 5526.143447765522, 5553.666779993213, 5556.833295001075, 5569.136783798518, 6398.584092034003, 6412.647304398303, 6426.068220108152, 6431.0, 6437.871242575216, 6114.206128133634, 6164.506839452743, 6190.288116866618, 6282.434580887742, 6283.654399008098, 6207.379262073757, 6236.594621349598, 6261.060840873905, 6282.465990390685, 6348.746050158126, 6128.325884152829, 6173.876522469615, 6181.598196117238, 6183.3816618337805, 6203.379662033761, 6198.256209254918, 6213.161223234986, 6226.066090086523, 6244.094606281973, 6245.750169993331, 6011.128386384037, 6051.243594550681, 6135.110179431366, 6144.692765361803, 6213.813585592439, 6196.47329976939, 6204.441600255998, 6206.596571222856, 6219.533534984804, 6221.782237621662, 6083.695815209133, 6089.970928780387, 6095.969520152371, 6099.419031586226, 6103.969480152571, 6038.215032045806, 6125.418593525661, 6142.161149281699, 6145.754169833157, 6156.569040167145, 6101.047669718005, 6117.370119345116, 6117.541184411276, 6118.141359432008, 6126.672418005965], "value": 5726.412955581798, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.837511183977, 538.4669177514262, 539.6411386428023, 550.4660479335045, 557.8465921871488, 31143.065708028767, 40167.99580010501, 41789.164216715646, 41851.79074104633, 43777.343339849926, 43161.784191079, 43587.564124358745, 43597.7820110899, 43622.781886090524, 43660.12679746403, 9661.227101831853, 9883.159931405839, 9890.357126786772, 9894.85157722631, 9895.950520247388, 9826.557804898797, 9973.401595904234, 9973.850392244121, 9977.401355918668, 9979.900200998007, 9922.90077099227, 9954.751131221741, 9988.850167247498, 10005.149562287177, 10013.89986100137, 9889.50552472377, 9962.153216976565, 9973.301868869146, 9986.800263994752, 9996.90003099967, 9714.368566043184, 9806.509674516317, 9807.607695692164, 9819.705408837746, 9832.65585704504, 40838.183236335106, 41125.79437102795, 41131.1773764526, 41136.79431602853, 41232.0, 3641.18073433478, 3752.568454627698, 3940.2316548273097, 4142.337226043848, 4288.785560721952, 31648.841755791305, 31971.680283197104, 32373.0, 32766.836165819255, 32944.0, 3257.690519400653, 3288.128645908836, 3323.93352132956, 3437.535932649085, 3489.91275218119, 511640.0, 512784.0, 513368.0, 514067.0, 514828.9999999963, 57828.71085644545, 58313.0, 58491.122633160536, 58552.0, 58623.0, 34737.478937815955, 35483.82258088693, 35539.111522211635, 36376.72681456136, 36778.44832327517, 16556.58608534772, 16688.082155481323, 16714.749278760828, 16792.66414671724, 16828.24272907722, 12774.425150868234, 12865.871341286655, 12877.678058048436, 12889.677758056123, 12891.419886105148, 19176.904115479614, 19196.0, 19198.0, 19287.93916334587, 19297.61404771925, 6646.933530664728, 7657.846843063107, 7836.0, 7891.447598668153, 7901.446898717035, 859.6776208921655, 1016.0956748493846, 1060.8620879285797, 1071.164491696478, 1092.9672109836717, 26120.0, 30028.0, 30400.54399184014, 30747.0, 30821.69178308233, 991.6975322526555, 1022.7647641042602, 1076.0907033556632, 1123.5282378766747, 1128.3963079752457, 15166.848331516543, 15618.062916225063, 15736.662383697285, 15798.447054353042, 16198.271077801528, 633040.0, 636731.0, 639036.0, 639368.0, 639430.0, 58528.707356462946, 59081.704591476766, 59204.70397647984, 59249.70375148183, 59373.40626593765, 1349.1702602899147, 1376.2361889151416, 1386.7226554689103, 1389.0415613226842, 1396.4972609860442, 28905.0, 29436.55845162367, 29481.410371792874, 29518.852405737834, 30386.0, 50945.6317960255, 51452.0, 51538.48461515337, 51570.22644660258, 51674.224886625976, 588933.0, 589552.0, 590166.0, 590268.0, 592454.0754592399, 26870.865645671645, 27376.589351160146, 27881.860590696917, 27962.86018569894, 28830.855845720638, 47729.522704772506, 48105.03789924112, 48295.0, 48613.5138648609, 49296.75351623219, 34256.82871585626, 35731.35492385973, 41071.0, 42687.35968960406, 44017.04078327785, 39621.60378396179, 39664.24868394009, 40009.59990400175, 40819.183616328104, 41734.58265417307, 32205.0, 33539.4969075469, 34226.65773342235, 38909.221815564095, 39857.0, 33354.4996825043, 34619.65380346265, 34851.12872178216, 35248.82375588106, 35320.82339588286, 25401.0, 25692.614610781227, 25701.485970280115, 25915.0, 26289.21132366032], "value": 20126.838282164594, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.4387643029734, 372.1050872651274, 373.1696974232335, 377.92063666630014, 398.6073717388374, 15837.445689400873, 15874.523764287062, 19217.0, 20436.89781551092, 20689.068991895365, 16228.513144605651, 18214.180361883715, 20238.08928598213, 20853.165873365084, 21745.3476395708, 5860.912086318709, 6055.394460553954, 6065.0, 6066.181065556138, 6075.2405949256345, 4214.831406743727, 4432.601065904068, 5995.640261584297, 6054.455099041085, 6430.903536446934, 6561.606303621798, 6742.494312926526, 6768.0, 6771.661416929159, 6772.762953296636, 6579.736810527573, 6627.1716035495565, 6645.3354664533645, 6688.699008544627, 6724.991251312294, 5707.77168913243, 5750.05124154514, 5796.449337312949, 5806.912896306559, 5812.9709351452975, 24919.875400623063, 25463.490730185476, 25494.107706230192, 25751.742482575122, 26527.46945061107, 1929.5754933914516, 2097.5070858348427, 2315.166540045599, 2318.48993221491, 2443.4135807406274, 10215.591376345012, 10368.740781480448, 10410.0, 10415.583376664925, 10497.63258285964, 1800.8559315254747, 1846.778386593604, 1883.265526444674, 1909.7601142879757, 1960.235508151822, 92943.0, 98853.5171972421, 99028.0, 99745.0, 100151.49924250405, 9511.429314241168, 11341.829872551918, 11480.827787583194, 11536.538538458368, 11992.160548761678, 14496.85503144976, 14502.85497145036, 14515.491957781638, 14532.564023079325, 14532.782008269884, 7806.765797026098, 8391.748247552583, 8424.452410593292, 8578.528180950108, 8590.226879580743, 8487.405881588229, 8518.87221691675, 8523.445976011539, 8668.566571671392, 8738.86891696625, 4496.460424749051, 4504.594586487232, 4519.728816271035, 4521.61566266871, 4569.195167908724, 1854.2397616976975, 1900.1354383755634, 1904.828565429118, 1912.7609048868892, 1913.5598812273256, 768.8692922203239, 842.9409941304173, 884.6420744157687, 895.1585509620935, 960.3037797596686, 16045.919770401073, 20420.897895510723, 21359.359219223446, 21631.89184054101, 21682.241121560986, 882.4572887674034, 907.4237858959476, 926.305271046715, 959.203860795538, 963.3064193780467, 10807.67576972692, 11253.549858005588, 11255.887441125647, 11260.211785174959, 11269.774604507864, 562518.0, 564472.0, 575329.0, 595915.0, 609004.9549752222, 122778.38610807067, 123892.60201658639, 128388.35805821099, 129143.0, 131617.0, 927.0590350793949, 933.2533972821851, 949.8432758594893, 952.2239374909427, 952.514217748953, 18371.816281837546, 18722.438326850217, 18793.0, 18928.0, 18943.621127577648, 52106.739466302424, 52235.73882130565, 53142.2028669578, 53408.1988770161, 53571.0, 74097.88853167315, 74115.25884741083, 74291.62854185695, 77518.0, 77659.61170194113, 7994.2805147537, 8286.917130828615, 8311.335093192409, 8327.875081873655, 8362.665493380196, 12119.792938175377, 12487.188332758342, 12609.054320925783, 12735.363231838186, 12784.0, 8337.916620833714, 10944.452777361259, 11059.059979901798, 11125.554977800797, 11204.66386008421, 10926.743862162371, 11153.665390038312, 11199.104071674083, 11371.658850234506, 11562.30626162433, 10759.623413180814, 11016.669499915015, 11082.16883733707, 11230.0, 11266.155038371991, 11415.942920285346, 11867.643970680894, 11996.220245684352, 12176.39118044112, 12210.023198144305, 10957.0, 11404.885951140383, 11417.486213120263, 11543.711407214887, 11834.349110798988], "value": 9589.106556018316, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.7706151423502, 1350.372076984202, 1514.053716427233, 1650.009994003598, 1882.153031135989, 7772.883406748896, 8263.669453221864, 8296.460730052557, 8296.834063318738, 8303.792405189874, 8103.635336409861, 8543.06026337104, 8770.824583508325, 8793.34049946255, 8896.377253592234, 2386.0932845518723, 2417.4769894966234, 2421.9394515137174, 2424.636304554322, 2427.0413186791206, 2059.927902523412, 2356.45801465663, 2400.95198096038, 2405.278416475059, 2415.1546958564536, 2364.3852598324474, 2392.7846493815555, 2394.8443351182204, 2408.3738228060656, 2413.734489206186, 2357.3635118518077, 2374.6912901322803, 2380.2264264114233, 2387.1764241336623, 2400.387901085212, 2359.1742889988536, 2387.952240955171, 2388.4267775733874, 2389.319044072431, 2395.2933884504114, 8941.910580894173, 8951.955240223822, 9041.502717350542, 9054.77363065922, 9066.818663626756, 5244.318238628954, 5294.338207724035, 5414.025475414423, 5443.782248710087, 5455.263539422167, 3148.9527657085164, 3211.71094601487, 3280.885169019097, 3589.3718599245026, 3631.2374401375478, 1095.917806164533, 1106.3527836215824, 1127.8026345389605, 1132.1508868348737, 1142.2004596782203, 11957.641270761891, 12055.33695646739, 12059.397030148546, 12071.456784444634, 12107.334096624681, 4552.339910712994, 4559.475660299042, 4708.576228139415, 4758.595519380822, 4778.092162489116, 9402.670906518239, 9412.341136120544, 9415.62337506492, 9433.151016408556, 9434.858477122849, 5356.062689029404, 5359.276497672842, 5364.7049412282695, 5366.463353664602, 5371.167469042305, 4416.227160246944, 4439.778011099496, 4473.753943533072, 5038.294638750578, 5042.9747851261245, 3835.5972622874756, 3854.69162467002, 3908.687305015592, 3916.921661566753, 3929.0766669832565, 1186.5787645385935, 1186.792311345511, 1188.1385995153616, 1200.951961921531, 1203.2419575667273, 773.4315278270467, 866.5797088412115, 898.7303808857316, 914.2092090341806, 947.4457442396138, 5848.824535263949, 5892.202878122128, 6057.062909109114, 6062.545309101835, 6136.8772624547255, 920.3649481857497, 929.7907970706536, 934.6775362499926, 984.0897170117613, 1027.8766548014287, 4015.7188996769946, 4037.2329257440992, 4050.0887300357185, 4128.545859955402, 4137.007118291588, 10794.0, 11251.09991200702, 11253.212275140662, 11274.0, 11281.943590281997, 11198.160137989684, 11211.831822522669, 11215.66353009411, 11216.158788090763, 11222.989930906258, 7780.1052878919645, 8036.598170091472, 8153.0, 8179.713710020121, 8190.017197936167, 5641.351244606923, 6052.19597775765, 6076.8784624308155, 6131.754729810757, 6199.752009919553, 10105.646302379382, 10377.273590848568, 10396.948015259875, 10417.739556511147, 10419.218558607983, 10000.499975001367, 10144.746381340525, 10371.377717336984, 10512.26414151002, 10614.310069845435, 7672.846543068996, 7921.247481489249, 7933.9603301983125, 7936.722214722457, 7969.242921922408, 8482.363822713445, 8492.872606910778, 8536.530490823065, 8556.18716221958, 8569.571521424028, 7711.652975616223, 7729.07251129868, 7761.844763104819, 7828.178041305581, 7848.489848159851, 7525.887111693439, 7540.057492813398, 7556.282153195438, 7734.574598397142, 7744.186860379579, 7100.822479438056, 7173.1392232932385, 7253.093363329584, 7253.56478611285, 7269.527480713737, 5063.949360506348, 5090.720010399464, 5131.512506311894, 5176.352955880515, 5200.803815122467, 4763.523647635208, 4927.655064145475, 5134.306868572695, 5156.2523434101095, 5348.090824559912], "value": 4634.181712501776, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.31702695290093, 290.3090644266645, 295.45783487300804, 298.7699471407017, 302.3635247803374, 5103.438621751604, 5109.386873575171, 5110.821121260757, 5143.177091665334, 5288.973555132228, 4657.720536767788, 4765.261384485401, 5251.921221181676, 5369.62412631116, 5385.865353366168, 3720.9255814883686, 4022.0, 4061.8172182251724, 4072.5316588592245, 4143.585641435848, 3551.3607550640872, 3847.4228865670234, 3889.241597888413, 3985.501812273466, 4136.21411931732, 3318.867245310185, 3328.7170590499836, 3341.365140623274, 3988.165443895811, 4046.2514434829504, 3990.2418540477215, 4005.6595189408645, 4006.759594424344, 4007.6192761687594, 4021.8592349267783, 3495.528103706018, 3501.142220156078, 3527.0124365177535, 3527.4003419418505, 3530.1704099536587, 4273.786310684453, 4756.619470442356, 4828.493008234155, 4916.532929371704, 5157.871053223699, 3953.7034722395933, 4047.8380864765372, 4079.51045874494, 4502.8874278142985, 4539.409876716006, 4634.513376095529, 4664.976675116603, 5512.310961129859, 5528.75120619573, 5559.499645031926, 3471.9132021699406, 3762.3039737648623, 3762.7930463824478, 3838.213166300902, 3870.271458621665, 5029.64792464524, 5240.502152295526, 5627.831165065055, 5734.942650573483, 5775.729339545293, 3479.443289073736, 3597.6042635310096, 3814.6185381461632, 3839.520059992501, 3854.807259637007, 3267.3138640885204, 3296.456084746038, 3738.9065273368383, 3865.7100717446306, 3899.0699603695784, 3784.4890939723336, 4008.5390180129075, 4519.118771839459, 4536.79584418702, 4567.429071366079, 5477.561795056386, 5556.0, 5556.416266436046, 5557.472040156179, 5577.74500737339, 1157.4779165397476, 1231.7866901102407, 1247.6943148928572, 1270.39021269791, 1325.9337033148304, 707.4906067631297, 782.6478084861835, 801.8155824160359, 802.2258520527602, 824.5959479854828, 3837.869982159928, 3903.6681882039775, 4127.4015267786, 4175.726403446978, 4285.61429471349, 3797.6392242736897, 3986.4652108938267, 4248.895287225344, 4312.116016216668, 4411.404460397805, 3788.3931780963776, 3877.269730928263, 4037.3757057912594, 4161.729487583298, 4198.769067701244, 2174.6520556710852, 2215.5347377051, 2267.8866056697098, 2503.68703912011, 2511.033252197917, 4371.344298355209, 4808.158572249842, 4885.438174609894, 4900.411950565955, 4916.680415772963, 4379.693421460531, 4609.594073807454, 4746.477887432379, 4792.274781078809, 4854.417469903564, 3948.9210215795524, 4550.797649034399, 4814.927776083291, 4844.854654360375, 4890.682105663121, 4215.2811436623815, 4298.946758044299, 4642.2108241598335, 4701.388819453586, 4865.367502224688, 4212.7893605319, 4690.343351930801, 4734.928976065293, 5345.797195630952, 5527.0, 5442.108892141979, 5497.0, 5830.367497100819, 5876.442742673281, 5924.822255332347, 3725.5343082114737, 3772.8681395581766, 3819.9045023873514, 3822.7132965027176, 3828.674562662205, 4831.782569784438, 5424.0, 5625.774969001358, 5699.230603868425, 5703.458171473703, 5491.1171634892, 5496.31296087989, 5500.972495137498, 5529.336479622471, 5532.2254884317035, 4619.445666520039, 5258.842234732964, 5478.479544443271, 5500.862478438071, 5525.309336332958, 4221.303484924953, 5376.677399356051, 5418.945810541844, 5421.484958928895, 5432.130859062531, 5540.307461567304, 5540.418256083053, 5549.750261238173, 5554.944450555443, 5566.610337276352, 5152.922706159336, 5419.972900135632, 5433.972830135824, 5463.235147079492, 5473.72631368422], "value": 3804.0243008079574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.7349060375849, 716.6094478509212, 780.2821404308036, 786.5713186313459, 848.919352661497, 14801.629959251024, 15514.60816787988, 16160.434384796534, 16235.350585976561, 16236.75644865328, 12563.434645440955, 14801.407943682267, 14808.851911480882, 15013.099214047164, 15094.320755566, 836.9163083691643, 868.695956415256, 870.2341939093589, 870.2863651805523, 873.8995015573195, 678.413172605695, 679.1171477079802, 705.6048612776862, 864.5893200729666, 867.7049803066956, 914.9817003659923, 917.2203626917134, 917.6467060181844, 918.7060140754961, 919.3886065766258, 911.8039621481413, 912.0150237743221, 912.3659056955419, 913.2602591900582, 913.7715571107257, 525.5165247971875, 547.6440313796024, 652.3118110393533, 673.6227712481026, 680.9863802723968, 15215.847841521665, 15268.0, 15271.083734975937, 15304.234788260654, 15309.61725956849, 11586.884131158666, 13532.526361577298, 14240.359183836648, 14399.352029158714, 14415.927920360436, 11280.153988450898, 11618.128640351923, 11648.184627076109, 12991.025673074462, 13748.65628359299, 4396.494403143647, 4429.914670905617, 4721.5750582447745, 4863.635227357962, 4949.158643030693, 87117.56441217817, 88629.0, 89429.55285223533, 89893.0, 90073.0, 10763.461826908624, 11196.160287978435, 11201.327920324644, 11234.3259404436, 11699.415029248503, 21430.785692143192, 21472.24847130343, 21474.57050859005, 21502.032408541654, 21523.0, 7735.574543400167, 7799.298063174341, 7835.725749598737, 7853.175416581291, 7881.763547093597, 4444.911101777946, 4472.753998530047, 4486.327050942385, 4622.699524530894, 4660.836870709509, 3014.8191108533556, 3096.303331750383, 3116.6104236970377, 3131.029380891931, 3131.6711745266875, 614.8192292411584, 632.6457183976987, 637.1112298343785, 639.0861068671751, 639.3893831391104, 543.2476020711343, 574.3136951343105, 589.6933594530819, 623.8970569856014, 654.5549026661915, 8034.638441270157, 8745.731868878976, 8890.86663700045, 8964.462132272085, 9169.449833009907, 621.5400603553428, 626.232864740689, 628.5976974736082, 640.7885397818709, 648.1994736500418, 4335.869923902288, 5031.899362012739, 5051.343325367752, 5163.277141200235, 5169.121249387613, 143291.0, 144769.0, 149643.0, 155331.0, 158253.0, 122416.0, 131824.34087829498, 139389.0, 140327.0, 142279.28315125513, 120058.0, 122055.38972305259, 125694.0, 126046.36976815057, 126245.0, 10128.03783640553, 10145.9492702536, 10186.592536298465, 10206.0, 10209.030142136486, 49351.753241233564, 59005.40994589999, 59805.759243431174, 68633.65683171552, 69085.81856360893, 91857.11714954293, 94032.0, 96450.0, 103332.93334133124, 106476.0, 11652.708682283012, 13600.319984000562, 13642.386092625657, 13807.654808629864, 14327.713445731235, 12625.494980200689, 14984.700305993601, 15039.0, 15331.386744530093, 15749.212539372757, 12110.03136202924, 13955.441782328593, 14745.410183592536, 14807.555773326818, 14816.40734370613, 8249.711260105867, 13155.868441315464, 13844.307784610528, 13879.72240555163, 13991.300434978008, 13658.453661853415, 13669.658258543615, 13750.587482375544, 13982.300884955916, 14365.20991345486, 9769.364991275545, 10974.561017559208, 11311.7737645245, 11438.428078596204, 11930.284182949052, 11312.640887809888, 11345.526937912156, 11536.884631153582, 11694.356810375511, 11723.179377443497], "value": 7852.899749760673, "unit": "score"}], "name": "dromaeo_css", "value": 7313.445969636473, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 114.0, 108.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 106.0, 162.0, 107.0, 117.0, 131.0, 103.0, 140.0, 109.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 182.0, 182.0, 183.0, 183.0, 182.0, 194.0, 259.0], "value": 191.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 61.0, 88.0, 59.0, 71.0, 89.0, 64.0, 84.0, 58.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 102.0, 72.0, 79.0, 68.0, 96.0, 71.0, 68.0, 101.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 84.0, 86.0, 84.0, 87.0, 84.0, 89.0, 129.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 99.0, 121.0, 93.0, 92.0, 93.0, 92.0, 93.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 84.0, 84.0, 84.0, 85.0, 85.0, 84.0, 90.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 54.0, 54.0, 56.0, 55.0, 54.0, 52.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 77.0, 75.0, 71.0, 69.0, 67.0, 70.0, 68.0, 70.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 85.0, 84.0, 86.0, 85.0, 96.0, 105.0, 102.0, 75.0, 93.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 137.0, 138.0, 139.0, 145.0, 150.0, 146.0, 203.0, 150.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 166.0, 170.0, 167.0, 166.0, 168.0, 167.0, 166.0, 167.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 68.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1445.9999999999998, "alertThreshold": 2.0}]} 03:52:24 INFO - Return code: 0 03:52:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:52:24 INFO - # TBPL SUCCESS # 03:52:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:52:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:52:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:52:24 INFO - 2016-08-17 03:52:24.744 screenresolution[1687:10059] starting screenresolution argv=screenresolution get 03:52:24 INFO - 2016-08-17 03:52:24.747 screenresolution[1687:10059] Display 0: 1600x1200x32@60 03:52:24 INFO - 2016-08-17 03:52:24.753 screenresolution[1688:10062] starting screenresolution argv=screenresolution list 03:52:24 INFO - Available Modes on Display 0 03:52:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:52:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:52:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:52:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:52:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:52:24 INFO - Intel Iris: 03:52:24 INFO - Chipset Model: Intel Iris 03:52:24 INFO - Type: GPU 03:52:24 INFO - Bus: Built-In 03:52:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:52:24 INFO - Vendor: Intel (0x8086) 03:52:24 INFO - Device ID: 0x0a2e 03:52:24 INFO - Revision ID: 0x0009 03:52:24 INFO - Displays: 03:52:24 INFO - Display: 03:52:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:52:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:52:24 INFO - Display Serial Number: 1600x1200 60 03:52:24 INFO - Main Display: Yes 03:52:24 INFO - Mirror: Off 03:52:24 INFO - Online: Yes 03:52:24 INFO - Rotation: Supported 03:52:24 INFO - Return code: 0 03:52:24 INFO - Running post-action listener: _resource_record_post_action 03:52:24 INFO - [mozharness: 2016-08-17 10:52:24.987664Z] Finished run-tests step (success) 03:52:24 INFO - Running post-run listener: _resource_record_post_run 03:52:25 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36885504; Write bytes: 704584704; Read time: 376; Write time: 1910 03:52:25 INFO - TinderboxPrint: CPU usage
53.6% 03:52:25 INFO - TinderboxPrint: I/O read bytes / time
36,885,504 / 376 03:52:25 INFO - TinderboxPrint: I/O write bytes / time
704,584,704 / 1,910 03:52:25 INFO - TinderboxPrint: CPU idle
2,249.5 (46.4%) 03:52:25 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 03:52:25 INFO - TinderboxPrint: CPU user
1,631.1 (33.6%) 03:52:25 INFO - TinderboxPrint: Swap in / out
441,581,568 / 0 03:52:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402686464; Write bytes: 396641792; Read time: 29699; Write time: 712 03:52:25 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34579456; Write bytes: 295896576; Read time: 348; Write time: 1160 03:52:25 INFO - Running post-run listener: _upload_blobber_files 03:52:25 INFO - Blob upload gear active. 03:52:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:52:25 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:52:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:52:25 INFO - (blobuploader) - INFO - Open directory for files ... 03:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:52:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:26 INFO - (blobuploader) - INFO - Done attempting. 03:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:52:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:27 INFO - (blobuploader) - INFO - Done attempting. 03:52:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:52:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:52:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:52:30 INFO - (blobuploader) - INFO - Done attempting. 03:52:30 INFO - (blobuploader) - INFO - Iteration through files over. 03:52:30 INFO - Return code: 0 03:52:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:52:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:52:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/148dad5301d47bec8ed8ba0f40df4def835bd419065f445df7a77b545692f2bb5244372c71abafc33aed1adcab1aeddf0a8142120507b5cf2be1931684fe448e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6f834bc28c0e0020f87a08e6ddc9127e400ff92699275958881a7dd7d04c35a03d7620bd0563da748031b10f4c6f0bff4f3092150549d37483cbed6ed8e5427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ca153045c3afeac31885486839d50d75da3ff0be641403f8e64f984997e3becb9ab44cb238b5921a782a7d4efa931646b1de21d9b2328405b7d0f4e187ff7d8"} 03:52:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:52:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:52:30 INFO - Contents: 03:52:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/148dad5301d47bec8ed8ba0f40df4def835bd419065f445df7a77b545692f2bb5244372c71abafc33aed1adcab1aeddf0a8142120507b5cf2be1931684fe448e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6f834bc28c0e0020f87a08e6ddc9127e400ff92699275958881a7dd7d04c35a03d7620bd0563da748031b10f4c6f0bff4f3092150549d37483cbed6ed8e5427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ca153045c3afeac31885486839d50d75da3ff0be641403f8e64f984997e3becb9ab44cb238b5921a782a7d4efa931646b1de21d9b2328405b7d0f4e187ff7d8"} 03:52:30 INFO - Running post-run listener: copy_logs_to_upload_dir 03:52:30 INFO - Copying logs to upload dir... 03:52:30 INFO - mkdir: /builds/slave/test/build/upload/logs 03:52:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.341858 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-17 03:52:30.871882) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 03:52:30.876860) ========= 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.xWeV7Up1eu/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.MlOdPZl26c/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/148dad5301d47bec8ed8ba0f40df4def835bd419065f445df7a77b545692f2bb5244372c71abafc33aed1adcab1aeddf0a8142120507b5cf2be1931684fe448e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6f834bc28c0e0020f87a08e6ddc9127e400ff92699275958881a7dd7d04c35a03d7620bd0563da748031b10f4c6f0bff4f3092150549d37483cbed6ed8e5427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ca153045c3afeac31885486839d50d75da3ff0be641403f8e64f984997e3becb9ab44cb238b5921a782a7d4efa931646b1de21d9b2328405b7d0f4e187ff7d8"} build_url:https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011066 build_url: 'https://queue.taskcluster.net/v1/task/fxHkcpy_SkeFwP_5XBS_XQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/148dad5301d47bec8ed8ba0f40df4def835bd419065f445df7a77b545692f2bb5244372c71abafc33aed1adcab1aeddf0a8142120507b5cf2be1931684fe448e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6f834bc28c0e0020f87a08e6ddc9127e400ff92699275958881a7dd7d04c35a03d7620bd0563da748031b10f4c6f0bff4f3092150549d37483cbed6ed8e5427", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0ca153045c3afeac31885486839d50d75da3ff0be641403f8e64f984997e3becb9ab44cb238b5921a782a7d4efa931646b1de21d9b2328405b7d0f4e187ff7d8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 03:52:30.910761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:52:30.911099) ========= 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.xWeV7Up1eu/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.MlOdPZl26c/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 03:52:30.960508) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 03:52:30.960835) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 03:52:30.961182) ========= ========= Total master_lag: 0.23 =========