builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0118 starttime: 1471303931.41 results: success (0) buildid: 20160815124529 builduid: 25acad5010584d2885577971cc32991e revision: dbb9da97e0e1c021314baf7de44f82fd4a74058d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.406821) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.407308) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.407594) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.431318) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.431707) ========= 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.1rFMdVtAUU/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.SQ25LNszU8/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-15 16:32:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.51M=0.001s 2016-08-15 16:32:11 (8.51 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131794 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.585700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.586035) ========= 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.1rFMdVtAUU/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.SQ25LNszU8/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.020503 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:11.634853) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 16:32:11.635225) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dbb9da97e0e1c021314baf7de44f82fd4a74058d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dbb9da97e0e1c021314baf7de44f82fd4a74058d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1rFMdVtAUU/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.SQ25LNszU8/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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-15 16:32:11,712 truncating revision to first 12 chars 2016-08-15 16:32:11,712 Setting DEBUG logging. 2016-08-15 16:32:11,712 attempt 1/10 2016-08-15 16:32:11,712 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dbb9da97e0e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 16:32:13,022 unpacking tar archive at: fx-team-dbb9da97e0e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.556925 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 16:32:13.214024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:13.214351) ========= script_repo_revision: dbb9da97e0e1c021314baf7de44f82fd4a74058d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:13.214730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:13.215020) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 16:32:13.233450) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-15 16:32:13.233864) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1rFMdVtAUU/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.SQ25LNszU8/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:13 INFO - MultiFileLogger online at 20160815 16:32:13 in /builds/slave/test 16:32:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:32:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:32:13 INFO - {'append_to_log': False, 16:32:13 INFO - 'base_work_dir': '/builds/slave/test', 16:32:13 INFO - 'blob_upload_branch': 'Fx-Team', 16:32:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:32:13 INFO - 'branch': 'Fx-Team', 16:32:13 INFO - 'buildbot_json_path': 'buildprops.json', 16:32:13 INFO - 'config_files': ('talos/mac_config.py',), 16:32:13 INFO - 'default_actions': ('clobber', 16:32:13 INFO - 'read-buildbot-config', 16:32:13 INFO - 'download-and-extract', 16:32:13 INFO - 'populate-webroot', 16:32:13 INFO - 'create-virtualenv', 16:32:13 INFO - 'install', 16:32:13 INFO - 'run-tests'), 16:32:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:32:13 INFO - 'download_minidump_stackwalk': True, 16:32:13 INFO - 'download_symbols': 'ondemand', 16:32:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:32:13 INFO - 'tooltool.py': '/tools/tooltool.py', 16:32:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:32:13 INFO - '/tools/misc-python/virtualenv.py')}, 16:32:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:32:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:32:13 INFO - 'installer_path': 'installer.exe', 16:32:13 INFO - 'log_level': 'info', 16:32:13 INFO - 'log_name': 'talos', 16:32:13 INFO - 'log_to_console': True, 16:32:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:32:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:32:13 INFO - 'opt_config_files': (), 16:32:13 INFO - 'pip_index': False, 16:32:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:32:13 INFO - 'cmd': ('bash', 16:32:13 INFO - '-c', 16:32:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:32:13 INFO - 'enabled': True, 16:32:13 INFO - 'halt_on_failure': False, 16:32:13 INFO - 'name': 'check_screen_resolution'},), 16:32:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:32:13 INFO - 'cmd': ('bash', 16:32:13 INFO - '-c', 16:32:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:32:13 INFO - 'enabled': True, 16:32:13 INFO - 'halt_on_failure': False, 16:32:13 INFO - 'name': 'check_screen_resolution'},), 16:32:13 INFO - 'run_cmd_checks_enabled': True, 16:32:13 INFO - 'sps_profile': False, 16:32:13 INFO - 'sps_profile_interval': 0, 16:32:13 INFO - 'suite': 'dromaeojs', 16:32:13 INFO - 'system_bits': '32', 16:32:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:32:13 INFO - 'title': 't-yosemite-r7-0118', 16:32:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:32:13 INFO - 'use_talos_json': True, 16:32:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:32:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:32:13 INFO - 'work_dir': 'build'} 16:32:13 INFO - [mozharness: 2016-08-15 23:32:13.433406Z] Running clobber step. 16:32:13 INFO - Running pre-action listener: _resource_record_pre_action 16:32:13 INFO - Running main action method: clobber 16:32:13 INFO - rmtree: /builds/slave/test/build 16:32:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:32:13 INFO - Running post-action listener: _resource_record_post_action 16:32:13 INFO - [mozharness: 2016-08-15 23:32:13.901528Z] Finished clobber step (success) 16:32:13 INFO - [mozharness: 2016-08-15 23:32:13.901657Z] Running read-buildbot-config step. 16:32:13 INFO - Running pre-action listener: _resource_record_pre_action 16:32:13 INFO - Running main action method: read_buildbot_config 16:32:13 INFO - Using buildbot properties: 16:32:13 INFO - { 16:32:13 INFO - "project": "", 16:32:13 INFO - "product": "firefox", 16:32:13 INFO - "script_repo_revision": "production", 16:32:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:32:13 INFO - "repository": "", 16:32:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:32:13 INFO - "buildid": "20160815124529", 16:32:13 INFO - "pgo_build": "False", 16:32:13 INFO - "basedir": "/builds/slave/test", 16:32:13 INFO - "buildnumber": 126, 16:32:13 INFO - "slavename": "t-yosemite-r7-0118", 16:32:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:32:13 INFO - "platform": "yosemite_r7", 16:32:13 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 16:32:13 INFO - "branch": "fx-team", 16:32:13 INFO - "repo_path": "integration/fx-team", 16:32:13 INFO - "revision": "dbb9da97e0e1c021314baf7de44f82fd4a74058d", 16:32:13 INFO - "stage_platform": "macosx64", 16:32:13 INFO - "builduid": "25acad5010584d2885577971cc32991e", 16:32:13 INFO - "slavebuilddir": "test" 16:32:13 INFO - } 16:32:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:32:13 INFO - Running post-action listener: _resource_record_post_action 16:32:13 INFO - [mozharness: 2016-08-15 23:32:13.905063Z] Finished read-buildbot-config step (success) 16:32:13 INFO - [mozharness: 2016-08-15 23:32:13.905179Z] Running download-and-extract step. 16:32:13 INFO - Running pre-action listener: _resource_record_pre_action 16:32:13 INFO - Running main action method: download_and_extract 16:32:13 INFO - mkdir: /builds/slave/test/build/tests 16:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:13 INFO - trying https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:32:13 INFO - Downloading https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:15 INFO - Downloaded 1199 bytes. 16:32:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:32:15 INFO - Using the following test package requirements: 16:32:15 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:32:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:32:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:32:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'jsshell-mac.zip'], 16:32:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:32:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:32:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:32:15 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:32:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:32:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:32:15 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:32:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:15 INFO - trying https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:32:15 INFO - Downloading https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:15 INFO - Downloaded 16330448 bytes. 16:32:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:32:22 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 16:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:22 INFO - trying https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:32:22 INFO - Downloading https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:23 INFO - Downloaded 16330448 bytes. 16:32:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:32:30 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')]} 16:32:30 INFO - trying https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:32:30 INFO - Downloading https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:31 INFO - Downloaded 14413719 bytes. 16:32:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:31 INFO - trying https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:32:31 INFO - Downloading https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:32:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/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 16:32:35 INFO - Downloaded 111049912 bytes. 16:32:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:32:35 INFO - mkdir: /builds/slave/test/properties 16:32:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:32:35 INFO - Writing to file /builds/slave/test/properties/build_url 16:32:35 INFO - Contents: 16:32:35 INFO - build_url:https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:32:36 INFO - Running post-action listener: _resource_record_post_action 16:32:36 INFO - Running post-action listener: set_extra_try_arguments 16:32:36 INFO - [mozharness: 2016-08-15 23:32:36.595686Z] Finished download-and-extract step (success) 16:32:36 INFO - [mozharness: 2016-08-15 23:32:36.595805Z] Running populate-webroot step. 16:32:36 INFO - Running pre-action listener: _resource_record_pre_action 16:32:36 INFO - Running main action method: populate_webroot 16:32:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:32:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:32:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:32:36 INFO - '--tppagecycles', 16:32:36 INFO - '7'], 16:32:36 INFO - 'tests': ['tsvgm']}}, 16:32:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:32:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:32:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:32:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:32:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['damp', 'tps']}, 16:32:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'tests': ['damp', 'tps']}, 16:32:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['dromaeo_dom']}, 16:32:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:32:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['basic_compositor_video']}, 16:32:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:32:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['a11yr', 16:32:36 INFO - 'ts_paint', 16:32:36 INFO - 'tpaint', 16:32:36 INFO - 'sessionrestore', 16:32:36 INFO - 'sessionrestore_no_auto_restore', 16:32:36 INFO - 'tabpaint']}, 16:32:36 INFO - 'other-e10s': {'tests': ['a11yr', 16:32:36 INFO - 'ts_paint', 16:32:36 INFO - 'tpaint', 16:32:36 INFO - 'sessionrestore', 16:32:36 INFO - 'sessionrestore_no_auto_restore', 16:32:36 INFO - 'tabpaint']}, 16:32:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:32:36 INFO - 'ts_paint', 16:32:36 INFO - 'tpaint', 16:32:36 INFO - 'sessionrestore', 16:32:36 INFO - 'sessionrestore_no_auto_restore', 16:32:36 INFO - 'tabpaint']}, 16:32:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:32:36 INFO - 'ts_paint', 16:32:36 INFO - 'tpaint', 16:32:36 INFO - 'sessionrestore', 16:32:36 INFO - 'sessionrestore_no_auto_restore', 16:32:36 INFO - 'tabpaint']}, 16:32:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['a11yr', 16:32:36 INFO - 'ts_paint', 16:32:36 INFO - 'tpaint', 16:32:36 INFO - 'sessionrestore', 16:32:36 INFO - 'sessionrestore_no_auto_restore', 16:32:36 INFO - 'tabpaint']}, 16:32:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['a11yr', 16:32:36 INFO - 'ts_paint', 16:32:36 INFO - 'tpaint', 16:32:36 INFO - 'sessionrestore', 16:32:36 INFO - 'sessionrestore_no_auto_restore', 16:32:36 INFO - 'tabpaint']}, 16:32:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['tsvgx', 16:32:36 INFO - 'tsvgr_opacity', 16:32:36 INFO - 'tart', 16:32:36 INFO - 'tscrollx', 16:32:36 INFO - 'cart']}, 16:32:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:32:36 INFO - 'tsvgr_opacity', 16:32:36 INFO - 'tart', 16:32:36 INFO - 'tscrollx', 16:32:36 INFO - 'cart']}, 16:32:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'talos_options': ['--disable-e10s'], 16:32:36 INFO - 'tests': ['tp5o']}, 16:32:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'tests': ['tp5o']}, 16:32:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'talos_options': ['--disable-e10s', 16:32:36 INFO - '--xperf_path', 16:32:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:36 INFO - 'tests': ['tp5n']}, 16:32:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:36 INFO - 'talos_options': ['--xperf_path', 16:32:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:36 INFO - 'tests': ['tp5n']}}, 16:32:36 INFO - 'talos.zip': {'path': '', 16:32:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:32:36 INFO - Running post-action listener: _resource_record_post_action 16:32:36 INFO - [mozharness: 2016-08-15 23:32:36.614769Z] Finished populate-webroot step (success) 16:32:36 INFO - [mozharness: 2016-08-15 23:32:36.614900Z] Running create-virtualenv step. 16:32:36 INFO - Running pre-action listener: _resource_record_pre_action 16:32:36 INFO - Running main action method: create_virtualenv 16:32:36 INFO - Creating virtualenv /builds/slave/test/build/venv 16:32:36 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 16:32:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:32:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:32:36 INFO - Using real prefix '/tools/python27' 16:32:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:32:37 INFO - Installing distribute.............................................................................................................................................................................................done. 16:32:40 INFO - Installing pip.................done. 16:32:40 INFO - Return code: 0 16:32:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:32:40 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')]} 16:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:40 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')]} 16:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:40 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:40 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 16:32:40 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 16:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:40 INFO - 'HOME': '/Users/cltbld', 16:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:40 INFO - 'LOGNAME': 'cltbld', 16:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:40 INFO - 'MOZ_NO_REMOTE': '1', 16:32:40 INFO - 'NO_EM_RESTART': '1', 16:32:40 INFO - 'PAGER': '/bin/cat', 16:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:40 INFO - 'PWD': '/builds/slave/test', 16:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:40 INFO - 'SHELL': '/bin/bash', 16:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:40 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:40 INFO - 'USER': 'cltbld', 16:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:40 INFO - 'XPC_FLAGS': '0x0', 16:32:40 INFO - 'XPC_SERVICE_NAME': '0', 16:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:40 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 16:32:40 INFO - Cleaning up... 16:32:40 INFO - Return code: 0 16:32:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:32:40 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')]} 16:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:40 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')]} 16:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:40 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:40 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 16:32:40 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 16:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:40 INFO - 'HOME': '/Users/cltbld', 16:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:40 INFO - 'LOGNAME': 'cltbld', 16:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:40 INFO - 'MOZ_NO_REMOTE': '1', 16:32:40 INFO - 'NO_EM_RESTART': '1', 16:32:40 INFO - 'PAGER': '/bin/cat', 16:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:40 INFO - 'PWD': '/builds/slave/test', 16:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:40 INFO - 'SHELL': '/bin/bash', 16:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:40 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:40 INFO - 'USER': 'cltbld', 16:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:40 INFO - 'XPC_FLAGS': '0x0', 16:32:40 INFO - 'XPC_SERVICE_NAME': '0', 16:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:40 INFO - Downloading/unpacking psutil>=3.1.1 16:32:40 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 16:32:40 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 16:32:40 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 16:32:40 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 16:32:40 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 16:32:40 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 16:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:32:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:43 INFO - Installing collected packages: psutil 16:32:43 INFO - Running setup.py install for psutil 16:32:43 INFO - building 'psutil._psutil_osx' extension 16:32:43 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 16:32:44 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 16:32:44 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 16:32:44 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 16:32:44 INFO - building 'psutil._psutil_posix' extension 16:32:44 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 16:32:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:32:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:32:44 INFO - ^ 16:32:44 INFO - 1 warning generated. 16:32:44 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 16:32:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:44 INFO - Successfully installed psutil 16:32:44 INFO - Cleaning up... 16:32:44 INFO - Return code: 0 16:32:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:44 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:44 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 16:32:44 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 16:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:44 INFO - 'HOME': '/Users/cltbld', 16:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:44 INFO - 'LOGNAME': 'cltbld', 16:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:44 INFO - 'MOZ_NO_REMOTE': '1', 16:32:44 INFO - 'NO_EM_RESTART': '1', 16:32:44 INFO - 'PAGER': '/bin/cat', 16:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:44 INFO - 'PWD': '/builds/slave/test', 16:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:44 INFO - 'SHELL': '/bin/bash', 16:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:44 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:44 INFO - 'USER': 'cltbld', 16:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:44 INFO - 'XPC_FLAGS': '0x0', 16:32:44 INFO - 'XPC_SERVICE_NAME': '0', 16:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32:44 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 16:32:44 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 16:32:44 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 16:32:44 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 16:32:44 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 16:32:44 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 16:32:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:47 INFO - Installing collected packages: mozsystemmonitor 16:32:47 INFO - Running setup.py install for mozsystemmonitor 16:32:47 INFO - Successfully installed mozsystemmonitor 16:32:47 INFO - Cleaning up... 16:32:47 INFO - Return code: 0 16:32:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:47 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:47 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 16:32:47 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 16:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:47 INFO - 'HOME': '/Users/cltbld', 16:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:47 INFO - 'LOGNAME': 'cltbld', 16:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:47 INFO - 'MOZ_NO_REMOTE': '1', 16:32:47 INFO - 'NO_EM_RESTART': '1', 16:32:47 INFO - 'PAGER': '/bin/cat', 16:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:47 INFO - 'PWD': '/builds/slave/test', 16:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:47 INFO - 'SHELL': '/bin/bash', 16:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:47 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:47 INFO - 'USER': 'cltbld', 16:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:47 INFO - 'XPC_FLAGS': '0x0', 16:32:47 INFO - 'XPC_SERVICE_NAME': '0', 16:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:47 INFO - Downloading/unpacking jsonschema==2.5.1 16:32:47 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 16:32:47 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 16:32:47 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 16:32:47 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 16:32:47 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 16:32:47 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 16:32:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:50 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:50 INFO - warning: no files found matching '*.txt' 16:32:50 INFO - no previously-included directories found matching 'build' 16:32:50 INFO - no previously-included directories found matching 'dist' 16:32:50 INFO - no previously-included directories found matching '.git*' 16:32:50 INFO - Installing collected packages: jsonschema, functools32 16:32:50 INFO - Running setup.py install for functools32 16:32:50 INFO - warning: no files found matching '*.txt' 16:32:50 INFO - no previously-included directories found matching 'build' 16:32:50 INFO - no previously-included directories found matching 'dist' 16:32:50 INFO - no previously-included directories found matching '.git*' 16:32:50 INFO - Successfully installed jsonschema functools32 16:32:50 INFO - Cleaning up... 16:32:51 INFO - Return code: 0 16:32:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:32:51 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')]} 16:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:51 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')]} 16:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:51 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:51 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 16:32:51 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 16:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:51 INFO - 'HOME': '/Users/cltbld', 16:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:51 INFO - 'LOGNAME': 'cltbld', 16:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:51 INFO - 'MOZ_NO_REMOTE': '1', 16:32:51 INFO - 'NO_EM_RESTART': '1', 16:32:51 INFO - 'PAGER': '/bin/cat', 16:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:51 INFO - 'PWD': '/builds/slave/test', 16:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:51 INFO - 'SHELL': '/bin/bash', 16:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:51 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:51 INFO - 'USER': 'cltbld', 16:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:51 INFO - 'XPC_FLAGS': '0x0', 16:32:51 INFO - 'XPC_SERVICE_NAME': '0', 16:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:32:51 INFO - Cleaning up... 16:32:51 INFO - Return code: 0 16:32:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32:51 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')]} 16:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:51 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')]} 16:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:51 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:51 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 16:32:51 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 16:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:51 INFO - 'HOME': '/Users/cltbld', 16:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:51 INFO - 'LOGNAME': 'cltbld', 16:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:51 INFO - 'MOZ_NO_REMOTE': '1', 16:32:51 INFO - 'NO_EM_RESTART': '1', 16:32:51 INFO - 'PAGER': '/bin/cat', 16:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:51 INFO - 'PWD': '/builds/slave/test', 16:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:51 INFO - 'SHELL': '/bin/bash', 16:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:51 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:51 INFO - 'USER': 'cltbld', 16:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:51 INFO - 'XPC_FLAGS': '0x0', 16:32:51 INFO - 'XPC_SERVICE_NAME': '0', 16:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:51 INFO - Downloading/unpacking blobuploader==1.2.4 16:32:51 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 16:32:51 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 16:32:51 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 16:32:51 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 16:32:51 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 16:32:51 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 16:32:53 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 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 16:32:54 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 16:32:55 INFO - Downloading docopt-0.6.1.tar.gz 16:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:55 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:55 INFO - Running setup.py install for blobuploader 16:32:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:55 INFO - Running setup.py install for requests 16:32:55 INFO - Running setup.py install for docopt 16:32:56 INFO - Successfully installed blobuploader requests docopt 16:32:56 INFO - Cleaning up... 16:32:56 INFO - Return code: 0 16:32:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:56 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:32:56 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 16:32:56 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 16:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:56 INFO - 'HOME': '/Users/cltbld', 16:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:56 INFO - 'LOGNAME': 'cltbld', 16:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:56 INFO - 'MOZ_NO_REMOTE': '1', 16:32:56 INFO - 'NO_EM_RESTART': '1', 16:32:56 INFO - 'PAGER': '/bin/cat', 16:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:56 INFO - 'PWD': '/builds/slave/test', 16:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:56 INFO - 'SHELL': '/bin/bash', 16:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:32:56 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:56 INFO - 'USER': 'cltbld', 16:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:56 INFO - 'XPC_FLAGS': '0x0', 16:32:56 INFO - 'XPC_SERVICE_NAME': '0', 16:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-skd7Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-zzzpKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-TPPxwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-0J9ac4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:56 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-hjlj6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-C7ETxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ssSswb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-oFxel1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-1EJ7A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-HvDoRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-UfSrlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-KKjZBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-WNRdQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-R_V4vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:57 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-3xrFSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:58 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-DMOQPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:58 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-BwA7yO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:58 INFO - Running setup.py install for manifestparser 16:32:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:58 INFO - Running setup.py install for mozcrash 16:32:58 INFO - Running setup.py install for mozdebug 16:32:58 INFO - Running setup.py install for mozdevice 16:32:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:58 INFO - Running setup.py install for mozfile 16:32:58 INFO - Running setup.py install for mozhttpd 16:32:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Running setup.py install for mozinfo 16:32:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Running setup.py install for mozInstall 16:32:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Running setup.py install for mozleak 16:32:59 INFO - Running setup.py install for mozlog 16:32:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Running setup.py install for moznetwork 16:32:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:59 INFO - Running setup.py install for mozprocess 16:33:00 INFO - Running setup.py install for mozprofile 16:33:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:33:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:33:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:33:00 INFO - Running setup.py install for mozrunner 16:33:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:33:00 INFO - Running setup.py install for mozscreenshot 16:33:00 INFO - Running setup.py install for moztest 16:33:00 INFO - Running setup.py install for mozversion 16:33:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:33:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:33:00 INFO - Cleaning up... 16:33:00 INFO - Return code: 0 16:33:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:33:00 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')]} 16:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:33:00 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')]} 16:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:33:00 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:33:00 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 16:33:00 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 16:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:00 INFO - 'HOME': '/Users/cltbld', 16:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:00 INFO - 'LOGNAME': 'cltbld', 16:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:00 INFO - 'MOZ_NO_REMOTE': '1', 16:33:00 INFO - 'NO_EM_RESTART': '1', 16:33:00 INFO - 'PAGER': '/bin/cat', 16:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:00 INFO - 'PWD': '/builds/slave/test', 16:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:00 INFO - 'SHELL': '/bin/bash', 16:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:33:00 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:00 INFO - 'USER': 'cltbld', 16:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:00 INFO - 'XPC_FLAGS': '0x0', 16:33:00 INFO - 'XPC_SERVICE_NAME': '0', 16:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-GTAYyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-QkA8Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-CU1OwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-7gzR5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-kkfZlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-lVOvGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-8RcNeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-nJgwXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:33:01 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)) 16:33:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:33:01 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-ku2JxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-GtseLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-j2syP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-Fa8BR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-_D3epu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-q0xkdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-wptN4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-tXMTNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:33:02 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)) 16:33:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:33:02 INFO - Running setup.py (path:/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/pip-udiVnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:33:02 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)) 16:33:02 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)) 16:33:02 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)) 16:33:02 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)) 16:33:02 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)) 16:33:02 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)) 16:33:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:33:02 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 16:33:02 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 16:33:02 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 16:33:02 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 16:33:02 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 16:33:02 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 16:33:05 INFO - Downloading blessings-1.6.tar.gz 16:33:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:33:05 INFO - Installing collected packages: blessings 16:33:05 INFO - Running setup.py install for blessings 16:33:05 INFO - Successfully installed blessings 16:33:05 INFO - Cleaning up... 16:33:05 INFO - Return code: 0 16:33:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:33:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:33:06 INFO - Reading from file tmpfile_stdout 16:33:06 INFO - Current package versions: 16:33:06 INFO - blessings == 1.6 16:33:06 INFO - blobuploader == 1.2.4 16:33:06 INFO - docopt == 0.6.1 16:33:06 INFO - functools32 == 3.2.3-2 16:33:06 INFO - jsonschema == 2.5.1 16:33:06 INFO - manifestparser == 1.1 16:33:06 INFO - mozInstall == 1.12 16:33:06 INFO - mozcrash == 0.17 16:33:06 INFO - mozdebug == 0.1 16:33:06 INFO - mozdevice == 0.48 16:33:06 INFO - mozfile == 1.2 16:33:06 INFO - mozhttpd == 0.7 16:33:06 INFO - mozinfo == 0.9 16:33:06 INFO - mozleak == 0.1 16:33:06 INFO - mozlog == 3.3 16:33:06 INFO - moznetwork == 0.27 16:33:06 INFO - mozprocess == 0.23 16:33:06 INFO - mozprofile == 0.28 16:33:06 INFO - mozrunner == 6.12 16:33:06 INFO - mozscreenshot == 0.1 16:33:06 INFO - mozsystemmonitor == 0.3 16:33:06 INFO - moztest == 0.7 16:33:06 INFO - mozversion == 1.4 16:33:06 INFO - psutil == 3.1.1 16:33:06 INFO - requests == 1.2.3 16:33:06 INFO - wsgiref == 0.1.2 16:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:33:06 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 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:33:06 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 16:33:06 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 16:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:06 INFO - 'HOME': '/Users/cltbld', 16:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:06 INFO - 'LOGNAME': 'cltbld', 16:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:06 INFO - 'MOZ_NO_REMOTE': '1', 16:33:06 INFO - 'NO_EM_RESTART': '1', 16:33:06 INFO - 'PAGER': '/bin/cat', 16:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:06 INFO - 'PWD': '/builds/slave/test', 16:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:06 INFO - 'SHELL': '/bin/bash', 16:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:33:06 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:06 INFO - 'USER': 'cltbld', 16:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:06 INFO - 'XPC_FLAGS': '0x0', 16:33:06 INFO - 'XPC_SERVICE_NAME': '0', 16:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 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)) 16:33:06 INFO - Cleaning up... 16:33:06 INFO - Return code: 0 16:33:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:33:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4ab5633f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, '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.1rFMdVtAUU/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.SQ25LNszU8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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 16:33:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:06 INFO - 'HOME': '/Users/cltbld', 16:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:06 INFO - 'LOGNAME': 'cltbld', 16:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:06 INFO - 'MOZ_NO_REMOTE': '1', 16:33:06 INFO - 'NO_EM_RESTART': '1', 16:33:06 INFO - 'PAGER': '/bin/cat', 16:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:06 INFO - 'PWD': '/builds/slave/test', 16:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:06 INFO - 'SHELL': '/bin/bash', 16:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:33:06 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:06 INFO - 'USER': 'cltbld', 16:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:06 INFO - 'XPC_FLAGS': '0x0', 16:33:06 INFO - 'XPC_SERVICE_NAME': '0', 16:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:33:06 INFO - Cleaning up... 16:33:06 INFO - Return code: 0 16:33:06 INFO - Running post-action listener: _resource_record_post_action 16:33:06 INFO - Running post-action listener: _start_resource_monitoring 16:33:06 INFO - Starting resource monitoring. 16:33:06 INFO - [mozharness: 2016-08-15 23:33:06.605100Z] Finished create-virtualenv step (success) 16:33:06 INFO - [mozharness: 2016-08-15 23:33:06.606095Z] Running install step. 16:33:06 INFO - Running pre-action listener: _resource_record_pre_action 16:33:06 INFO - Running main action method: install 16:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:33:06 INFO - Reading from file tmpfile_stdout 16:33:06 INFO - Detecting whether we're running mozinstall >=1.0... 16:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:33:06 INFO - Reading from file tmpfile_stdout 16:33:06 INFO - Output received: 16:33:06 INFO - Usage: mozinstall [options] installer 16:33:06 INFO - Options: 16:33:06 INFO - -h, --help show this help message and exit 16:33:06 INFO - -d DEST, --destination=DEST 16:33:06 INFO - Directory to install application into. [default: 16:33:06 INFO - "/builds/slave/test"] 16:33:06 INFO - --app=APP Application being installed. [default: firefox] 16:33:06 INFO - mkdir: /builds/slave/test/build/application 16:33:06 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'] 16:33:06 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 16:33:41 INFO - Reading from file tmpfile_stdout 16:33:41 INFO - Output received: 16:33:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:33:41 INFO - Running post-action listener: _resource_record_post_action 16:33:41 INFO - [mozharness: 2016-08-15 23:33:41.208416Z] Finished install step (success) 16:33:41 INFO - [mozharness: 2016-08-15 23:33:41.208581Z] Running run-tests step. 16:33:41 INFO - Running pre-action listener: _resource_record_pre_action 16:33:41 INFO - Running main action method: run_tests 16:33:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:33:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:33:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:33:41 INFO - 2016-08-15 16:33:41.223 screenresolution[6164:100155] starting screenresolution argv=screenresolution get 16:33:41 INFO - 2016-08-15 16:33:41.226 screenresolution[6164:100155] Display 0: 1600x1200x32@60 16:33:41 INFO - 2016-08-15 16:33:41.232 screenresolution[6165:100160] starting screenresolution argv=screenresolution list 16:33:41 INFO - Available Modes on Display 0 16:33:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:33:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:33:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:33:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:33:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:33:41 INFO - Intel Iris: 16:33:41 INFO - Chipset Model: Intel Iris 16:33:41 INFO - Type: GPU 16:33:41 INFO - Bus: Built-In 16:33:41 INFO - VRAM (Dynamic, Max): 1536 MB 16:33:41 INFO - Vendor: Intel (0x8086) 16:33:41 INFO - Device ID: 0x0a2e 16:33:41 INFO - Revision ID: 0x0009 16:33:41 INFO - Displays: 16:33:41 INFO - Display: 16:33:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:33:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:33:41 INFO - Display Serial Number: 1600x1200 60 16:33:41 INFO - Main Display: Yes 16:33:41 INFO - Mirror: Off 16:33:41 INFO - Online: Yes 16:33:41 INFO - Rotation: Supported 16:33:41 INFO - Return code: 0 16:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:33:41 INFO - Python 2.7.3 16:33:41 INFO - Return code: 0 16:33:41 INFO - grabbing minidump binary from tooltool 16:33:41 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')]} 16:33:41 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 0x100dd8d30>, '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 0x100b3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100dd0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:33:41 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 16:33:41 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 16:33:41 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 16:33:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:33:41 INFO - Return code: 0 16:33:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:33:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:33:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:33:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:33:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 16:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0118 --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 16:33:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1rFMdVtAUU/Render', 16:33:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:41 INFO - 'HOME': '/Users/cltbld', 16:33:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:41 INFO - 'LOGNAME': 'cltbld', 16:33:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:33:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:41 INFO - 'MOZ_NO_REMOTE': '1', 16:33:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:33:41 INFO - 'NO_EM_RESTART': '1', 16:33:41 INFO - 'PAGER': '/bin/cat', 16:33:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:41 INFO - 'PWD': '/builds/slave/test', 16:33:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:33:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:41 INFO - 'SHELL': '/bin/bash', 16:33:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SQ25LNszU8/Listeners', 16:33:41 INFO - 'TMPDIR': '/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/', 16:33:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:41 INFO - 'USER': 'cltbld', 16:33:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:41 INFO - 'XPC_FLAGS': '0x0', 16:33:41 INFO - 'XPC_SERVICE_NAME': '0', 16:33:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0118', '--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 16:33:41 INFO - mozversion application_buildid: 20160815124529 16:33:41 INFO - mozversion application_changeset: dbb9da97e0e1c021314baf7de44f82fd4a74058d 16:33:41 INFO - mozversion application_display_name: Nightly 16:33:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:33:41 INFO - mozversion application_name: Firefox 16:33:41 INFO - mozversion application_remotingname: firefox 16:33:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:33:41 INFO - mozversion application_vendor: Mozilla 16:33:41 INFO - mozversion application_version: 51.0a1 16:33:41 INFO - mozversion platform_buildid: 20160815124529 16:33:41 INFO - mozversion platform_changeset: dbb9da97e0e1c021314baf7de44f82fd4a74058d 16:33:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:33:41 INFO - mozversion platform_version: 51.0a1 16:33:41 INFO - using testdate: 1471304021 16:33:41 INFO - actual date: 1471304021 16:33:41 INFO - starting webserver on 'localhost:56463' 16:33:41 INFO - SUITE-START | Running 2 tests 16:33:41 INFO - TEST-START | dromaeo_css 16:33:41 INFO - operating with platform_type : mac_ 16:33:41 INFO - Initialising browser for dromaeo_css test... 16:33:41 INFO - TEST-INFO | started process 6175 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpwbXGQ2/profile http://localhost:56463/getInfo.html) 16:33:44 INFO - PROCESS | 6175 | __metrics Screen width/height:1600/1200 16:33:44 INFO - PROCESS | 6175 | colorDepth:24 16:33:44 INFO - PROCESS | 6175 | Browser inner width/height: 1024/689 16:33:44 INFO - PROCESS | 6175 | __metrics 16:33:44 INFO - PROCESS | 6175 | 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" 16:33:44 INFO - PROCESS | 6175 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:33:45 INFO - TEST-INFO | 6175: exit 0 16:33:45 INFO - Browser initialized. 16:33:45 INFO - Running cycle 1/1 for dromaeo_css test... 16:33:45 INFO - TEST-INFO | started process 6178 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmpwbXGQ2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:57 INFO - PROCESS | 6178 | RSS: Main: 228470784 16:33:57 INFO - PROCESS | 6178 | 16:34:48 INFO - PROCESS | 6178 | 1471304088412 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] 16:37:00 INFO - PROCESS | 6178 | Cycle 1(1): loaded http://localhost:56463/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56463/tests/dromaeo/cssquery-ext.html) 16:37:01 INFO - PROCESS | 6178 | RSS: Main: 361947136 16:37:01 INFO - PROCESS | 6178 | 16:40:04 INFO - PROCESS | 6178 | Cycle 1(1): loaded http://localhost:56463/tests/dromaeo/cssquery-ext.html (next: http://localhost:56463/tests/dromaeo/cssquery-jquery.html) 16:40:05 INFO - PROCESS | 6178 | RSS: Main: 369332224 16:40:05 INFO - PROCESS | 6178 | 16:43:07 INFO - PROCESS | 6178 | Cycle 1(1): loaded http://localhost:56463/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56463/tests/dromaeo/cssquery-mootools.html) 16:43:08 INFO - PROCESS | 6178 | RSS: Main: 355692544 16:43:08 INFO - PROCESS | 6178 | 16:46:10 INFO - PROCESS | 6178 | Cycle 1(1): loaded http://localhost:56463/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56463/tests/dromaeo/cssquery-prototype.html) 16:46:11 INFO - PROCESS | 6178 | RSS: Main: 337580032 16:46:11 INFO - PROCESS | 6178 | 16:49:14 INFO - PROCESS | 6178 | Cycle 1(1): loaded http://localhost:56463/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56463/tests/dromaeo/cssquery-yui.html) 16:49:15 INFO - PROCESS | 6178 | RSS: Main: 329076736 16:49:15 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | Cycle 1(1): loaded http://localhost:56463/tests/dromaeo/cssquery-yui.html (next: http://localhost:56463/tests/dromaeo/cssquery-dojo.html) 16:52:18 INFO - PROCESS | 6178 | RSS: Main: 647180288 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | __start_tp_report 16:52:18 INFO - PROCESS | 6178 | _x_x_mozilla_page_load 16:52:18 INFO - PROCESS | 6178 | _x_x_mozilla_page_load_details 16:52:18 INFO - PROCESS | 6178 | |i|pagename|runs| 16:52:18 INFO - PROCESS | 6178 | |0;dojo.html;3013.081010291861;3350.346682396932;3374.5613070300856;3399.354122716684;3407.6762707542784;5656.604037717361;5745.971270143648;5773.30720313563;5801.6519008859495;5820.097884827848;5762.884742305151;5799.217105690731;5800.129980502927;5808.361080281165;5837.9124313135235;4831.106245344605;4880.170371036915;5276.419593844674;5373.731313434332;5405.891882162355;5172.767225474862;5331.466853314657;5337.2794672719265;5369.5167434930845;5410.188471729232;5210.218467229927;5374.785008599671;5381.704006279653;5392.73036348183;5407.756650950697;5376.1398176292;5402.37872644647;5420.891582168374;5427.674339539641;5462.644928079662;5179.766910489037;5211.947880521184;5220.582353411718;5316.308879845596;5320.973395133038;5729.369769325371;5767.279090113736;5826.038703613941;5855.502282306009;5908.202392676976;6244.56288059836;6329.1772069631115;6330;6332.968335158294;6340.809775706736;6132.692058233643;6287.9056814147825;6348.492120630339;6351.587146835441;6364.427201551883;5918.8816223675285;5938.287405511366;5949.8810023799715;5950.732217050243;5975.67133807636;6391.2330520337855;6401.839954001187;6402.359764023654;6405.967970160119;6414.550981431255;6101.694915254219;6112.2970858351855;6213.316535181127;6237.875242495124;6279.748810047547;6331.271903731038;6335.873282534416;6348.365163483707;6355.650439225795;6359.20509936258;6236.781712640036;6293;6293.842653933688;6306.274778400542;6316.778912738124;6159.6612186330185;6240.407161319758;6256.092866534327;6298.622082675054;6311.305756366796;5029.748512574357;5052.166392545262;5060.240963855451;5061.1902095665255;5077.263796749525;4534.569215924482;4559.589636932692;4603.861884143481;4613.331066995334;4664.253719404946;5753.741081651336;5803.158542011469;5812.854678632983;5843.328017277982;5846.298444186725;6217.440430361289;6259.96870015647;6288.9056664150075;6327.082573026885;6345.746170153235;5829.795957141479;5832.650040997469;5841.1238314253205;5870.354261031283;5889.646621202742;5450.754716037788;5483.451654834565;5485.588580856452;5491.093969495068;5517.44825517445;6282.277538083179;6305.337939516285;6338.461230795434;6412.711427985659;6439.871202576016;6316.842078948063;6330.715117819617;6337.588056776295;6354.936450635434;6364.490840732823;6461.967690161519;6473.2232132144445;6479.384458476437;6481.546291759531;6486;6125.204929265031;6127.250613123855;6301.212348456443;6318.62088274705;6331.715072821827;6304.810855674337;6319.652419116993;6336.303006669171;6357.1735674362035;6360.586561873463;6228.906566401417;6236.438720515175;6261.718222680081;6276.372362763687;6277.843053923689;5979.760809567742;6065.332813390612;6122.123717501779;6252.374762523711;6252.531060170596;6101.481374083252;6114.021756518936;6122.39815511198;6130.63216207029;6143.174139895698;6247;6252.062190671254;6260.092286618506;6267.1539342188225;6270.2475702915945;4795.712257264575;5898.8230353089475;6045.909311360245;6092.177556030057;6102.9694851525455 16:52:18 INFO - PROCESS | 6178 | |1;ext.html;451.5033463190491;470.90111076673895;485.61393692014843;492.9630277729169;495.99313393810564;25150.74849251507;40830;42276.943076423064;42377.15245695092;44017.339739903844;43204.351934721;43424.78287608558;43462.782686086524;43500.56499435005;43565;9657.971825916566;9865.112139907407;9882;9892.159166470823;9893.901060989407;9772.511374431286;9905.653302134428;9906.851397229048;9910.008999100104;9923.751906202331;9883.703488895344;9901.851472227921;9922.801543969117;9952.701418957442;9969.052939970692;9818.263630227726;9875.901240987641;9876.506174691236;9883.96218397065;9885.851712224323;9683.467409292487;9765.853512197322;9775.266854985834;9775.657851975147;9781.46201958892;41036;41172.176556469;41377.793111034254;41429.792851035854;41498;3393.694567488938;3429.331280400323;3493.2140268439653;3745.232227393378;4053.1691003344245;30976;31549.52675709866;31631;32250.87121950744;32297.86957456501;3208.3262514871903;3235.9514607280676;3246.3669584431045;3265.559149514833;3341.599008119041;336250;336992;337605;338330.30834845913;340341;59495;59725;59947.700261498416;59951.40048599545;60070;34391.484127738106;35116.82441587776;35480.29039419197;36293;62838.37161628417;13739.106958047694;16365.42721004783;16461.917690411472;16469.752953705705;16482.505524834272;12852.678683032813;12933.224006559449;12962.35188240603;12965.675858103623;12973.221606703628;15369.846301536842;19188.520286992938;19189.040547972825;19204.231830726894;19317.323893663655;7834.573465991696;7944.576904044322;7966.123726390092;8028.598570071473;8045.235702608243;744.0959234530072;775.7052320118431;809.6599428240153;863.7970077031893;875.5228400521731;23596.882015589814;25599.744002559735;27183;27640.861795691297;31192.844035779675;1048.3552615141632;1050.2700623066896;1052.1587222360868;1173.8180582009802;1215.2526196389333;13192.802107968197;15628.843711562738;15710.842891571167;15825.52523424299;16155.838441615433;583029;679145.2085479181;683521;683580;686378;58905.70547147237;59457.40542594605;59633.70183149143;59662.40337596568;59755.70122149361;1232.2544860359353;1276.5276847566645;1322.285965578577;1363.9590812275449;1387.2439520461373;29919.401611968075;31690.366192675556;31812.84093579517;31813.5227971576;32561.674383255864;51639.22541161811;52065.219021715464;52095;52141;52283.738581308375;542357;545214;545429;545461;546027;23916.92373843216;26358.81385337703;26911.596326055518;27048.1885543434;27368.589471158346;47797.283040755116;48185.5181448181;48625;49103;49205.75397122991;28634.85682571574;38398.616013839506;38989.610103898594;39810.60189398069;40947;38762.418563721;38922.07487090774;39128.87076950789;39956;40631.39052914149;33124.1718957028;33725.83137084397;36323.63676363308;38575.807120964215;38844.755957945315;29716.405671886874;33821.3235735279;34630;34822.47766283558;34823;24461;24928;25056.74943250617;25534.616980744933;25550.9779608821 16:52:18 INFO - PROCESS | 6178 | |2;jquery.html;288.27067519176484;346.35404969731457;372.79868870809764;373.76452834714144;401.6866843861789;15731.921340393297;17961.820381796195;20836.95815209237;20992.265270715547;21072.367828965154;15462.922685386557;19626.705599416022;20828.79171208291;21102.472438189052;21173.788262117374;5293.232481290211;5947.464728174475;6207.875842483148;6215.658138802364;6230.660408012276;6066.514678825705;6113.510919126482;6155.692215389235;6157.384261573851;6177.104319873616;5777.508911742486;6065.939340606582;6125.846853828668;6131.9386806131815;6134.815955521319;6112.144299798032;6153.969230153865;6157.476614487729;6159.969200154015;6165.383461653843;5813.645473583163;5841.707914604295;5907.645541267538;5912.970435147839;5970.34326224115;13090.410931508104;13297.002724795679;13311.86688133116;24166.03335866563;25284.115055973136;2203.922862699814;2348.3072493614254;2379.3337865397716;2575.3947822261753;2881.6253886994764;9530.428174309494;9795.608175673036;9976.700698979042;10152.543135558917;10460.320079194827;1851.490840018992;1863.2081365419642;1975.7332760077493;2010.4672261850621;2022.4842665120448;107775;123200.38399807943;124004;124545.37727311306;128929.99999999907;10771.353718776898;10832.67501974942;11148.777024459465;11219.1585631078;11406.771864562663;13732.931335343259;13838.100523465942;13856.792148117785;13880.23658698761;13899.374528146056;8359.247667709813;8487.957560212159;8490.235878770816;8501.14988501145;8609.182127697859;6847.965760171267;8491.320694344438;8675.696350627697;8682.782930426663;8687.21815036637;4323.978380108079;4388.345051244015;4393.450818647669;4456.888577785581;4466.1514312281215;1844.7878493973365;1859.2562974810185;1860.5069656541027;1887.292265400475;1906.5424298168339;713.3116542536477;934.7990182110879;963.4219468318953;968.8837339519303;986.2800155886243;20360.898195509224;21213.833239171992;21214.575708485743;21228.044737986827;21311;956.7464621875208;957.9616815327308;974.4202199691173;996.9351992120489;1009.0968583118068;8210.589470526449;8382.790430239293;10368.377897326185;10613.310134841211;11035.558577656804;515503;570698;573761.1311943413;598624;599834;87635;111164;116473;132461.67538324493;137525.31237343748;842.9157084291522;895.2077411490748;916.3688634231079;930.1907340613709;950.8573713943102;18614.53463663419;19002.619947601248;19272.421827345202;19274.421767347;19304.814705573423;53725.731371342896;53955.92088158293;54397.00816455133;55143.172852408046;55320.44679553153;77916.05209869798;79181.60409197917;83606.16393836227;85222;88689.11310886808;7024.402925751164;7102.9289707104335;7327.120745510573;7363.300486453778;7991.560464174526;10666;12980.286084265455;13239.867601323864;13255.933720331337;13313.670107731303;5426.457354264542;5459.972700136474;10822;10967.903209678969;11429.828552571884;10836.783264334827;10915.508802103766;10967.890321096687;11277.266977646756;11322.773544529227;8732;10993.670189894316;11329.433528323387;11494;11559.884401155881;7271.963640181977;7414.740484083031;7444.878209710207;7471.775846724389;7564.205758395509;9577.712668619952;10925.726856828644;11254.94372528132;11265.831012534654;11328.60349887783 16:52:18 INFO - PROCESS | 6178 | |3;mootools.html;1345.6232254968609;1508.6153030977102;1640.4012535424565;1810.5202121437812;1832.2029916986112;8497.150284971507;8978.326625503089;9014.053524379944;9041.954790226047;9047.95476022619;7365.742199023035;7519.887201691965;8490.787730306745;8735.956320218405;8768.035516093225;2365.5387199496104;2389.9880500597474;2399.628057651058;2401.3396316013063;2411.3730430088217;2369.5024044950583;2383.988080059606;2411.3248290478696;2413.239829453725;2413.8310318277727;2381.273711517986;2382.332946774906;2390.8924098415528;2416.4804567018086;2420.9273721788372;2353.752855950118;2374.2995816234165;2378.6907701998803;2379.940501487468;2388.988055059731;2335.6846825678485;2360.138549429463;2369.751176126499;2380.952380952371;2381.1070848431837;8846.867296990551;8986.505742184178;8986.595603197871;9000.954995225047;9011.594478248495;5240.135377662719;5402.405735369106;5405.810796622139;5406.621536492447;5409.540189083893;3525.7531972761917;3674.0080178351964;3695.224002959382;3755.511783468132;3760.4735337052834;928.9442633441947;1118.7874303882236;1128.0749785176158;1132.9943350283195;1141.8116010858198;11721.238119522204;11726.29642221461;11839.28964262147;11844.940775296154;11847.40762961857;4246.087091275391;4415.977920110443;4470.776461176928;4688.765561721901;4703.365045718852;8981.910180898238;9195.264378849675;9199.4480331179;9206.309526785519;9212.723618291462;5281.12861377876;5281.234221037928;5281.287026251407;5282.498162674539;5286.62993590445;4147.221043699611;4433.7561434121435;4554.43069616298;4591.196540605448;4954.40547134339;3569.411047177187;3698.14942563212;3698.9445158322646;3883.825227864753;3922.7842468663926;969.7866469376726;1154.1343992005995;1171.8183681529376;1179.1156632525606;1180.4451907603413;817.7896712864994;845.3828705045352;878.1218781218781;894.5393122541897;937.920276776468;5480.945190548123;5750.367459579443;5782.59521833476;5970.164177015221;6222.595531290452;892.5269607108243;899.9955000224957;903.3541018172039;921.3826736086704;940.3323640215524;3125.5936728225183;3888.300105980951;3901.941470877939;3957.3470377387985;4065.89835254121;10577.735556611146;10842.891571084347;10938.398388088572;10940.726481838017;10948.95984881435;10879.456027198608;10882.660228051007;10887.891121088845;10917.945410273056;10945.288556243819;8234.176582341837;8391.370647201484;8393.118722534167;8404.663813447394;8419.873701894476;5279.313689220377;5484.341878974548;5567.359753628384;5601.383847776659;5657.3776884543495;9934.056264654846;10239.948800256248;10298.897011029794;10300.484975751033;10324.845127322946;9331.533423328941;9763.414195148314;10175.79648407013;10201.285909986527;10331.173506119643;7145.1068616422945;7459.477836551389;7550;7667.961660191663;7771.261730135854;8277.089520152898;8377.70678026266;8408.663653453794;8438.620262088098;8498.107698691796;7593.5823529706395;7614.619269036415;7622.961885190539;7686.846263074819;7714.382849371925;7450.403967682464;7469.215732348025;7492.363149132167;7498.737544185928;7576.469647124869;7016.263292354433;7042.964785176247;7063.540869843444;7126.576963832322;7142.107236595426;4028.738132021409;4927.28554359623;4942;4960.330355402119;5069.92395114081;4920;4936.012797440598;5021.949780502295;5069.645124841226;5198.246254293031 16:52:18 INFO - PROCESS | 6178 | |4;prototype.html;274.3019016602746;303.4598414821618;309.54651435646775;309.7367237847831;318.0696462846174;4505.071703392792;4673.971726220226;5346.812861549846;5403.702796346204;5457.727113644317;4477.261251893442;5249.212618107285;5420.9186862197;5444.646098003637;5453.182022696597;2941.617589713345;3158.936821263579;3170.511148748233;4177.686673499485;4195.895102622429;3483.885156749841;3998.360262358023;4102.241085399196;4177.6866734995;4181.686373521983;4079.2453396121664;4093.1813637272517;4093.570175131613;4110.527289361732;4151.398047283142;3778.263238668461;3924.2543916655745;3985.6412922836794;4134.214499245134;4142.638695943314;2983.358577905739;3515.5078289039557;3560.6439356064448;3561.554805649294;3561.6972557332656;5333.733163405576;5367.651102678314;5369.24830523727;5371.4897084776885;5406.702631355274;3913.5695073541888;4524.705894116872;4622.514635963209;4732.290156476522;4745.833895813665;4230.344296634026;5699.2306038684665;5749.59752817303;5763.250777398953;5776.508996735282;3380.5436266104007;3912;3924.4113382992496;3933.5869733678123;3950.6370302245678;5074.974625126851;5586.776528938837;5808.2449281593545;5952.434518720715;5992.2809262888295;3429.9314013719586;3782.735208535376;3911.902202444962;3923.588023257574;3935.74417662851;3965;3979.46277252573;3988.561258261589;3989.7207195496617;4002.959970400317;4090.4682391288943;4678.274867395559;4684.531546845288;4685.250359942393;4690.296455531698;4642.837500687528;4940.925886111711;5464.497263252637;5671.858203544862;5706.2609947225565;1342.4630147940902;1353.75454581785;1361.8638136186303;1365.5015919189623;1372.540199033315;784.6233807772302;801.7892981597829;813.9418755617673;818.5579786915118;833.2375876073446;4563.06457176278;4732.006278681447;5259.921101183485;5354.705491197944;5394.029074766501;4662.370579971661;5420.620556561003;5825.76696932118;5921.585489015728;5951.791687290924;4687.20317546018;4940.184869496493;4953.95966846959;4958.454569997298;5323.760430780624;2775.4726601945968;2970.495015847311;3081.476149054666;3087.7838551301193;3154.6592698103213;5129.666571672829;6057.212562366953;6187.690615469299;6199.706108739795;6216.191895053705;4835.540623640749;5182.922256166161;5567.860803479945;5635.92917345703;6102.359252278536;5298.28473156119;6141.407276743066;6146.815595532141;6278.466330361879;6299.464545513682;5468.2344471773185;5584.86037849057;5717.771289148387;6044.395560443921;6061.545384096122;4859.052484765437;5459.2629994950175;5942.811437712388;5956.438731345563;5993.70031498432;5388.730563471732;6254.468370188585;6258.374162583706;6285.465735412541;6290.276618188784;3835.9232815344094;3878.631530004645;3911.6283953024417;3955.960440395674;3961.8613348532663;5317.627766056339;5993.550483713651;5998.4601385875485;6002.579819412599;6009.90985135232;5379.462053794589;5453.481919217668;5592.972035139798;5814.767409303749;5842.474177324061;4780.49804770508;5509.614326997072;5771.740271687868;5795.652260864362;5861.413858614104;4506.684532082722;5671;5717.514011309086;5718.857028574319;5722.2561067061015;5019.171836646912;5297.82789056485;5535.806246781343;5690.601657884074;5855.853603659943;4688.507706690748;5042.369703787027;5493.505584497414;5706.144078388275;5757.769689212385 16:52:18 INFO - PROCESS | 6178 | |5;yui.html;734.9853002939941;759.7758661194954;763.2291385700441;788.8027993001749;865.3682811547568;14817.259137043146;16101.83898161018;16129.516114516555;16143.83856161438;16195.02829830211;14440.638984025378;14514.346854391551;14594.124352538855;14602.342894569743;14602.926985365058;674.7806962737105;841.9663213471454;846.1453931529169;872.3152325424543;875.369733791669;874.8031692869117;875.6584931876573;876.3602570123782;876.526675595181;877.7673916412156;922.008840496469;925.6621333213362;928.0441145619989;928.22029495224;928.4429342394577;699.1854489519703;874.9431286966391;900.8333458310234;910.2899738204211;911.7583840282331;673.706937482192;673.9730410783562;674.689642764329;674.8987651852212;676.6447615002114;14461.927690361481;14484.42062317506;14501.564953051426;14501.782473262909;14506.492272770507;12564.497420103186;12698.74602507954;12701.237925724487;12771.297578633172;13103.541376051888;12298.446569904287;12348.012159027347;12598.370081495888;12705.491780328775;12775.808362874564;4553.157665831832;4565.178267911774;4589.173948689235;4722.409698787652;4748.548887855648;113312;115769.84230157675;115926;116115.99999999916;118185;10833.404162770965;10902.890971090346;11023.44882755859;11098.167637427225;11135.610253641245;21193.94030298479;21254.043568039477;21279.893600532207;21290.680639790415;21353.14587416517;7409.222031686703;7807.570583617842;8132.715354962548;8141.715039973573;8192.098869124391;3726.4782930389765;4364.912701745948;4528.7509186994375;4580.38164847748;4583.129205450954;3397.2016576104597;3619.60184379718;3650.178709790329;3650.8904732858055;3658.908527286839;567.9488846003796;683.0676127086491;683.5898460923406;683.9247682754893;684.4968947823346;606.5541826757329;634.5082561015158;678.9117414736053;684.0271228171167;697.9023332706388;8998.775030624287;9424;9528.571214295373;9552.474613896164;9647.951760241294;597.7190720361423;621.2700077409027;656.3534918105662;665.7070888808909;694.2224708326605;3746.4754934309217;4271.893202669959;4677.46209185948;4703.05938812232;4740.634149753713;82843;105098;105302;105610;155955;136739.94890076655;141947.16105677828;143199;144139.55860441472;152381;74433;121554;123099;124169;126536;9600.951995239979;9707.223422126353;9835;9916.107550320507;9950.55222515003;43708.56291437045;46434.767826160656;59706;61562.69218653878;63438.40916589118;85971.14028859632;93314.53342733243;95774;96139;98320;12769.425375857923;13319.200847949154;13365.933170334087;13378.13042152257;13589.98075144388;13627.659308517366;14052.929735351257;14998.025128366622;15244.771328430304;15760.975536590086;13834.584962451143;14070.929645351707;14212.360443779848;14265.215413152377;14587.635309117357;13951.720965580429;14245.64385890361;14616.707665846836;14657;14727.305382051827;13778.311084445939;13900.304984750519;13961.511347102803;14020.228887411291;14198;9610.711678649652;11001.339919604849;11553.191276610556;11682.357470339213;12153.513859445524;8750.387472876837;9515.571799268911;10529.86971719089;10862.131029517777;11800.82298765502 16:52:18 INFO - PROCESS | 6178 | __end_tp_report 16:52:18 INFO - PROCESS | 6178 | __start_cc_report 16:52:18 INFO - PROCESS | 6178 | _x_x_mozilla_cycle_collect,1271 16:52:18 INFO - PROCESS | 6178 | __end_cc_report 16:52:18 INFO - PROCESS | 6178 | __startTimestamp1471305138721__endTimestamp 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | ------- Summary: start ------- 16:52:18 INFO - PROCESS | 6178 | Number of tests: 6 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | [#0] dojo.html Cycles:165 Average:5837.12 Median:6101.69 stddev:657.38 (10.8%) stddev-sans-first:620.95 16:52:18 INFO - PROCESS | 6178 | Values: 3013.1 3350.3 3374.6 3399.4 3407.7 5656.6 5746.0 5773.3 5801.7 5820.1 5762.9 5799.2 5800.1 5808.4 5837.9 4831.1 4880.2 5276.4 5373.7 5405.9 5172.8 5331.5 5337.3 5369.5 5410.2 5210.2 5374.8 5381.7 5392.7 5407.8 5376.1 5402.4 5420.9 5427.7 5462.6 5179.8 5211.9 5220.6 5316.3 5321.0 5729.4 5767.3 5826.0 5855.5 5908.2 6244.6 6329.2 6330.0 6333.0 6340.8 6132.7 6287.9 6348.5 6351.6 6364.4 5918.9 5938.3 5949.9 5950.7 5975.7 6391.2 6401.8 6402.4 6406.0 6414.6 6101.7 6112.3 6213.3 6237.9 6279.7 6331.3 6335.9 6348.4 6355.7 6359.2 6236.8 6293.0 6293.8 6306.3 6316.8 6159.7 6240.4 6256.1 6298.6 6311.3 5029.7 5052.2 5060.2 5061.2 5077.3 4534.6 4559.6 4603.9 4613.3 4664.3 5753.7 5803.2 5812.9 5843.3 5846.3 6217.4 6260.0 6288.9 6327.1 6345.7 5829.8 5832.7 5841.1 5870.4 5889.6 5450.8 5483.5 5485.6 5491.1 5517.4 6282.3 6305.3 6338.5 6412.7 6439.9 6316.8 6330.7 6337.6 6354.9 6364.5 6462.0 6473.2 6479.4 6481.5 6486.0 6125.2 6127.3 6301.2 6318.6 6331.7 6304.8 6319.7 6336.3 6357.2 6360.6 6228.9 6236.4 6261.7 6276.4 6277.8 5979.8 6065.3 6122.1 6252.4 6252.5 6101.5 6114.0 6122.4 6130.6 6143.2 6247.0 6252.1 6260.1 6267.2 6270.2 4795.7 5898.8 6045.9 6092.2 6103.0 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | [#1] ext.html Cycles:180 Average:65252.55 Median:39883.30 stddev:142734.11 (357.9%) stddev-sans-first:143051.60 16:52:18 INFO - PROCESS | 6178 | Values: 451.5 470.9 485.6 493.0 496.0 25150.7 40830.0 42276.9 42377.2 44017.3 43204.4 43424.8 43462.8 43500.6 43565.0 9658.0 9865.1 9882.0 9892.2 9893.9 9772.5 9905.7 9906.9 9910.0 9923.8 9883.7 9901.9 9922.8 9952.7 9969.1 9818.3 9875.9 9876.5 9884.0 9885.9 9683.5 9765.9 9775.3 9775.7 9781.5 41036.0 41172.2 41377.8 41429.8 41498.0 3393.7 3429.3 3493.2 3745.2 4053.2 30976.0 31549.5 31631.0 32250.9 32297.9 3208.3 3236.0 3246.4 3265.6 3341.6 336250.0 336992.0 337605.0 338330.3 340341.0 59495.0 59725.0 59947.7 59951.4 60070.0 34391.5 35116.8 35480.3 36293.0 62838.4 13739.1 16365.4 16461.9 16469.8 16482.5 12852.7 12933.2 12962.4 12965.7 12973.2 15369.8 19188.5 19189.0 19204.2 19317.3 7834.6 7944.6 7966.1 8028.6 8045.2 744.1 775.7 809.7 863.8 875.5 23596.9 25599.7 27183.0 27640.9 31192.8 1048.4 1050.3 1052.2 1173.8 1215.3 13192.8 15628.8 15710.8 15825.5 16155.8 583029.0 679145.2 683521.0 683580.0 686378.0 58905.7 59457.4 59633.7 59662.4 59755.7 1232.3 1276.5 1322.3 1364.0 1387.2 29919.4 31690.4 31812.8 31813.5 32561.7 51639.2 52065.2 52095.0 52141.0 52283.7 542357.0 545214.0 545429.0 545461.0 546027.0 23916.9 26358.8 26911.6 27048.2 27368.6 47797.3 48185.5 48625.0 49103.0 49205.8 28634.9 38398.6 38989.6 39810.6 40947.0 38762.4 38922.1 39128.9 39956.0 40631.4 33124.2 33725.8 36323.6 38575.8 38844.8 29716.4 33821.3 34630.0 34822.5 34823.0 24461.0 24928.0 25056.7 25534.6 25551.0 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | [#2] jquery.html Cycles:180 Average:33869.83 Median:2362.83 stddev:95821.91 (4055.4%) stddev-sans-first:96057.54 16:52:18 INFO - PROCESS | 6178 | Values: 288.3 346.4 372.8 373.8 401.7 15731.9 17961.8 20837.0 20992.3 21072.4 15462.9 19626.7 20828.8 21102.5 21173.8 5293.2 5947.5 6207.9 6215.7 6230.7 6066.5 6113.5 6155.7 6157.4 6177.1 5777.5 6065.9 6125.8 6131.9 6134.8 6112.1 6154.0 6157.5 6160.0 6165.4 5813.6 5841.7 5907.6 5913.0 5970.3 13090.4 13297.0 13311.9 24166.0 25284.1 2203.9 2348.3 2379.3 2575.4 2881.6 9530.4 9795.6 9976.7 10152.5 10460.3 1851.5 1863.2 1975.7 2010.5 2022.5 107775.0 123200.4 124004.0 124545.4 128930.0 10771.4 10832.7 11148.8 11219.2 11406.8 13732.9 13838.1 13856.8 13880.2 13899.4 8359.2 8488.0 8490.2 8501.1 8609.2 6848.0 8491.3 8675.7 8682.8 8687.2 4324.0 4388.3 4393.5 4456.9 4466.2 1844.8 1859.3 1860.5 1887.3 1906.5 713.3 934.8 963.4 968.9 986.3 20360.9 21213.8 21214.6 21228.0 21311.0 956.7 958.0 974.4 996.9 1009.1 8210.6 8382.8 10368.4 10613.3 11035.6 515503.0 570698.0 573761.1 598624.0 599834.0 87635.0 111164.0 116473.0 132461.7 137525.3 842.9 895.2 916.4 930.2 950.9 18614.5 19002.6 19272.4 19274.4 19304.8 53725.7 53955.9 54397.0 55143.2 55320.4 77916.1 79181.6 83606.2 85222.0 88689.1 7024.4 7102.9 7327.1 7363.3 7991.6 10666.0 12980.3 13239.9 13255.9 13313.7 5426.5 5460.0 10822.0 10967.9 11429.8 10836.8 10915.5 10967.9 11277.3 11322.8 8732.0 10993.7 11329.4 11494.0 11559.9 7272.0 7414.7 7444.9 7471.8 7564.2 9577.7 10925.7 11254.9 11265.8 11328.6 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | [#3] mootools.html Cycles:180 Average:5697.70 Median:4991.14 stddev:3223.58 (64.6%) stddev-sans-first:3216.03 16:52:18 INFO - PROCESS | 6178 | Values: 1345.6 1508.6 1640.4 1810.5 1832.2 8497.2 8978.3 9014.1 9042.0 9048.0 7365.7 7519.9 8490.8 8736.0 8768.0 2365.5 2390.0 2399.6 2401.3 2411.4 2369.5 2384.0 2411.3 2413.2 2413.8 2381.3 2382.3 2390.9 2416.5 2420.9 2353.8 2374.3 2378.7 2379.9 2389.0 2335.7 2360.1 2369.8 2381.0 2381.1 8846.9 8986.5 8986.6 9001.0 9011.6 5240.1 5402.4 5405.8 5406.6 5409.5 3525.8 3674.0 3695.2 3755.5 3760.5 928.9 1118.8 1128.1 1133.0 1141.8 11721.2 11726.3 11839.3 11844.9 11847.4 4246.1 4416.0 4470.8 4688.8 4703.4 8981.9 9195.3 9199.4 9206.3 9212.7 5281.1 5281.2 5281.3 5282.5 5286.6 4147.2 4433.8 4554.4 4591.2 4954.4 3569.4 3698.1 3698.9 3883.8 3922.8 969.8 1154.1 1171.8 1179.1 1180.4 817.8 845.4 878.1 894.5 937.9 5480.9 5750.4 5782.6 5970.2 6222.6 892.5 900.0 903.4 921.4 940.3 3125.6 3888.3 3901.9 3957.3 4065.9 10577.7 10842.9 10938.4 10940.7 10949.0 10879.5 10882.7 10887.9 10917.9 10945.3 8234.2 8391.4 8393.1 8404.7 8419.9 5279.3 5484.3 5567.4 5601.4 5657.4 9934.1 10239.9 10298.9 10300.5 10324.8 9331.5 9763.4 10175.8 10201.3 10331.2 7145.1 7459.5 7550.0 7668.0 7771.3 8277.1 8377.7 8408.7 8438.6 8498.1 7593.6 7614.6 7623.0 7686.8 7714.4 7450.4 7469.2 7492.4 7498.7 7576.5 7016.3 7043.0 7063.5 7126.6 7142.1 4028.7 4927.3 4942.0 4960.3 5069.9 4920.0 4936.0 5021.9 5069.6 5198.2 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | [#4] prototype.html Cycles:180 Average:4585.02 Median:5102.32 stddev:1446.19 (28.3%) stddev-sans-first:1413.59 16:52:18 INFO - PROCESS | 6178 | Values: 274.3 303.5 309.5 309.7 318.1 4505.1 4674.0 5346.8 5403.7 5457.7 4477.3 5249.2 5420.9 5444.6 5453.2 2941.6 3158.9 3170.5 4177.7 4195.9 3483.9 3998.4 4102.2 4177.7 4181.7 4079.2 4093.2 4093.6 4110.5 4151.4 3778.3 3924.3 3985.6 4134.2 4142.6 2983.4 3515.5 3560.6 3561.6 3561.7 5333.7 5367.7 5369.2 5371.5 5406.7 3913.6 4524.7 4622.5 4732.3 4745.8 4230.3 5699.2 5749.6 5763.3 5776.5 3380.5 3912.0 3924.4 3933.6 3950.6 5075.0 5586.8 5808.2 5952.4 5992.3 3429.9 3782.7 3911.9 3923.6 3935.7 3965.0 3979.5 3988.6 3989.7 4003.0 4090.5 4678.3 4684.5 4685.3 4690.3 4642.8 4940.9 5464.5 5671.9 5706.3 1342.5 1353.8 1361.9 1365.5 1372.5 784.6 801.8 813.9 818.6 833.2 4563.1 4732.0 5259.9 5354.7 5394.0 4662.4 5420.6 5825.8 5921.6 5951.8 4687.2 4940.2 4954.0 4958.5 5323.8 2775.5 2970.5 3081.5 3087.8 3154.7 5129.7 6057.2 6187.7 6199.7 6216.2 4835.5 5182.9 5567.9 5635.9 6102.4 5298.3 6141.4 6146.8 6278.5 6299.5 5468.2 5584.9 5717.8 6044.4 6061.5 4859.1 5459.3 5942.8 5956.4 5993.7 5388.7 6254.5 6258.4 6285.5 6290.3 3835.9 3878.6 3911.6 3956.0 3961.9 5317.6 5993.6 5998.5 6002.6 6009.9 5379.5 5453.5 5593.0 5814.8 5842.5 4780.5 5509.6 5771.7 5795.7 5861.4 4506.7 5671.0 5717.5 5718.9 5722.3 5019.2 5297.8 5535.8 5690.6 5855.9 4688.5 5042.4 5493.5 5706.1 5757.8 16:52:18 INFO - PROCESS | 6178 | 16:52:18 INFO - PROCESS | 6178 | [#5] yui.html Cycles:180 Average:24418.21 Median:3736.48 stddev:38822.99 (1039.0%) stddev-sans-first:38891.17 16:52:18 INFO - PROCESS | 6178 | Values: 735.0 759.8 763.2 788.8 865.4 14817.3 16101.8 16129.5 16143.8 16195.0 14440.6 14514.3 14594.1 14602.3 14602.9 674.8 842.0 846.1 872.3 875.4 874.8 875.7 876.4 876.5 877.8 922.0 925.7 928.0 928.2 928.4 699.2 874.9 900.8 910.3 911.8 673.7 674.0 674.7 674.9 676.6 14461.9 14484.4 14501.6 14501.8 14506.5 12564.5 12698.7 12701.2 12771.3 13103.5 12298.4 12348.0 12598.4 12705.5 12775.8 4553.2 4565.2 4589.2 4722.4 4748.5 113312.0 115769.8 115926.0 116116.0 118185.0 10833.4 10902.9 11023.4 11098.2 11135.6 21193.9 21254.0 21279.9 21290.7 21353.1 7409.2 7807.6 8132.7 8141.7 8192.1 3726.5 4364.9 4528.8 4580.4 4583.1 3397.2 3619.6 3650.2 3650.9 3658.9 567.9 683.1 683.6 683.9 684.5 606.6 634.5 678.9 684.0 697.9 8998.8 9424.0 9528.6 9552.5 9648.0 597.7 621.3 656.4 665.7 694.2 3746.5 4271.9 4677.5 4703.1 4740.6 82843.0 105098.0 105302.0 105610.0 155955.0 136739.9 141947.2 143199.0 144139.6 152381.0 74433.0 121554.0 123099.0 124169.0 126536.0 9601.0 9707.2 9835.0 9916.1 9950.6 43708.6 46434.8 59706.0 61562.7 63438.4 85971.1 93314.5 95774.0 96139.0 98320.0 12769.4 13319.2 13365.9 13378.1 13590.0 13627.7 14052.9 14998.0 15244.8 15761.0 13834.6 14070.9 14212.4 14265.2 14587.6 13951.7 14245.6 14616.7 14657.0 14727.3 13778.3 13900.3 13961.5 14020.2 14198.0 9610.7 11001.3 11553.2 11682.4 12153.5 8750.4 9515.6 10529.9 10862.1 11800.8 16:52:18 INFO - PROCESS | 6178 | -------- Summary: end -------- 16:52:18 INFO - PROCESS | 6178 | 16:52:19 INFO - TEST-INFO | 6178: exit 0 16:52:19 INFO - TEST-OK | dromaeo_css | took 1117703ms 16:52:19 INFO - TEST-START | kraken 16:52:19 INFO - operating with platform_type : mac_ 16:52:19 INFO - Initialising browser for kraken test... 16:52:19 INFO - TEST-INFO | started process 6181 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmprWA2xk/profile http://localhost:56463/getInfo.html) 16:52:21 INFO - PROCESS | 6181 | __metrics Screen width/height:1600/1200 16:52:21 INFO - PROCESS | 6181 | colorDepth:24 16:52:21 INFO - PROCESS | 6181 | Browser inner width/height: 1024/689 16:52:21 INFO - PROCESS | 6181 | __metrics 16:52:21 INFO - PROCESS | 6181 | 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" 16:52:21 INFO - PROCESS | 6181 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:52:22 INFO - TEST-INFO | 6181: exit 0 16:52:22 INFO - Browser initialized. 16:52:22 INFO - Running cycle 1/1 for kraken test... 16:52:22 INFO - TEST-INFO | started process 6182 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/tmprWA2xk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:34 INFO - PROCESS | 6182 | RSS: Main: 228687872 16:52:34 INFO - PROCESS | 6182 | 16:52:37 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56463/tests/kraken/driver.html?testName=audio-beat-detection) 16:52:38 INFO - PROCESS | 6182 | RSS: Main: 286420992 16:52:38 INFO - PROCESS | 6182 | 16:52:40 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56463/tests/kraken/driver.html?testName=audio-dft) 16:52:41 INFO - PROCESS | 6182 | RSS: Main: 294580224 16:52:41 INFO - PROCESS | 6182 | 16:52:44 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56463/tests/kraken/driver.html?testName=audio-fft) 16:52:45 INFO - PROCESS | 6182 | RSS: Main: 385069056 16:52:45 INFO - PROCESS | 6182 | 16:52:47 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56463/tests/kraken/driver.html?testName=audio-oscillator) 16:52:48 INFO - PROCESS | 6182 | RSS: Main: 289898496 16:52:48 INFO - PROCESS | 6182 | 16:52:50 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56463/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:52:50 INFO - PROCESS | 6182 | RSS: Main: 294354944 16:52:50 INFO - PROCESS | 6182 | 16:52:53 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56463/tests/kraken/driver.html?testName=imaging-darkroom) 16:52:54 INFO - PROCESS | 6182 | RSS: Main: 332136448 16:52:54 INFO - PROCESS | 6182 | 16:52:57 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56463/tests/kraken/driver.html?testName=imaging-desaturate) 16:52:58 INFO - PROCESS | 6182 | RSS: Main: 332509184 16:52:58 INFO - PROCESS | 6182 | 16:53:00 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56463/tests/kraken/driver.html?testName=json-parse-financial) 16:53:01 INFO - PROCESS | 6182 | RSS: Main: 332218368 16:53:01 INFO - PROCESS | 6182 | 16:53:02 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56463/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:53:03 INFO - PROCESS | 6182 | RSS: Main: 301146112 16:53:03 INFO - PROCESS | 6182 | 16:53:05 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:53:06 INFO - PROCESS | 6182 | RSS: Main: 308137984 16:53:06 INFO - PROCESS | 6182 | 16:53:08 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:53:09 INFO - PROCESS | 6182 | RSS: Main: 306536448 16:53:09 INFO - PROCESS | 6182 | 16:53:12 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:53:12 INFO - PROCESS | 6182 | RSS: Main: 313544704 16:53:12 INFO - PROCESS | 6182 | 16:53:15 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:53:16 INFO - PROCESS | 6182 | RSS: Main: 314953728 16:53:16 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | Cycle 1(1): loaded http://localhost:56463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56463/tests/kraken/driver.html?testName=ai-astar) 16:53:18 INFO - PROCESS | 6182 | RSS: Main: 358678528 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | __start_tp_report 16:53:18 INFO - PROCESS | 6182 | _x_x_mozilla_page_load 16:53:18 INFO - PROCESS | 6182 | _x_x_mozilla_page_load_details 16:53:18 INFO - PROCESS | 6182 | |i|pagename|runs| 16:53:18 INFO - PROCESS | 6182 | |0;ai-astar;104;114;113;112;109;114;110;113;112;112 16:53:18 INFO - PROCESS | 6182 | |1;audio-beat-detection;143;128;108;167;110;108;130;107;142;107 16:53:18 INFO - PROCESS | 6182 | |2;audio-dft;183;183;184;182;182;197;202;184;183;184 16:53:18 INFO - PROCESS | 6182 | |3;audio-fft;84;58;61;87;58;69;62;72;110;79 16:53:18 INFO - PROCESS | 6182 | |4;audio-oscillator;86;72;107;71;83;68;96;75;69;101 16:53:18 INFO - PROCESS | 6182 | |5;imaging-gaussian-blur;89;87;90;88;88;87;125;87;90;86 16:53:18 INFO - PROCESS | 6182 | |6;imaging-darkroom;142;100;95;96;95;94;94;95;95;95 16:53:18 INFO - PROCESS | 6182 | |7;imaging-desaturate;85;84;84;80;84;87;84;87;87;85 16:53:18 INFO - PROCESS | 6182 | |8;json-parse-financial;55;53;54;54;53;56;53;53;54;55 16:53:18 INFO - PROCESS | 6182 | |9;json-stringify-tinderbox;65;66;83;77;73;69;82;108;112;81 16:53:18 INFO - PROCESS | 6182 | |10;stanford-crypto-aes;89;84;86;85;83;88;88;83;85;86 16:53:18 INFO - PROCESS | 6182 | |11;stanford-crypto-ccm;139;134;141;138;141;156;156;143;154;139 16:53:18 INFO - PROCESS | 6182 | |12;stanford-crypto-pbkdf2;169;171;235;212;168;167;169;172;169;169 16:53:18 INFO - PROCESS | 6182 | |13;stanford-crypto-sha256-iterative;68;66;69;69;65;67;67;68;67;66 16:53:18 INFO - PROCESS | 6182 | __end_tp_report 16:53:18 INFO - PROCESS | 6182 | __start_cc_report 16:53:18 INFO - PROCESS | 6182 | _x_x_mozilla_cycle_collect,2082 16:53:18 INFO - PROCESS | 6182 | __end_cc_report 16:53:18 INFO - PROCESS | 6182 | __startTimestamp1471305198337__endTimestamp 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | ------- Summary: start ------- 16:53:18 INFO - PROCESS | 6182 | Number of tests: 14 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.50 stddev:3.02 (2.7%) stddev-sans-first:1.69 16:53:18 INFO - PROCESS | 6182 | Values: 104.0 114.0 113.0 112.0 109.0 114.0 110.0 113.0 112.0 112.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:129.00 stddev:20.71 (16.1%) stddev-sans-first:20.92 16:53:18 INFO - PROCESS | 6182 | Values: 143.0 128.0 108.0 167.0 110.0 108.0 130.0 107.0 142.0 107.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#2] audio-dft Cycles:10 Average:186.40 Median:184.00 stddev:7.04 (3.8%) stddev-sans-first:7.36 16:53:18 INFO - PROCESS | 6182 | Values: 183.0 183.0 184.0 182.0 182.0 197.0 202.0 184.0 183.0 184.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#3] audio-fft Cycles:10 Average:74.00 Median:70.50 stddev:16.48 (23.4%) stddev-sans-first:17.08 16:53:18 INFO - PROCESS | 6182 | Values: 84.0 58.0 61.0 87.0 58.0 69.0 62.0 72.0 110.0 79.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:73.50 stddev:14.25 (19.4%) stddev-sans-first:15.07 16:53:18 INFO - PROCESS | 6182 | Values: 86.0 72.0 107.0 71.0 83.0 68.0 96.0 75.0 69.0 101.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:88.00 stddev:11.78 (13.4%) stddev-sans-first:12.45 16:53:18 INFO - PROCESS | 6182 | Values: 89.0 87.0 90.0 88.0 88.0 87.0 125.0 87.0 90.0 86.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:95.00 stddev:14.82 (15.6%) stddev-sans-first:1.81 16:53:18 INFO - PROCESS | 6182 | Values: 142.0 100.0 95.0 96.0 95.0 94.0 94.0 95.0 95.0 95.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:2.11 (2.5%) stddev-sans-first:2.24 16:53:18 INFO - PROCESS | 6182 | Values: 85.0 84.0 84.0 80.0 84.0 87.0 84.0 87.0 87.0 85.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.05 (2.0%) stddev-sans-first:1.05 16:53:18 INFO - PROCESS | 6182 | Values: 55.0 53.0 54.0 54.0 53.0 56.0 53.0 53.0 54.0 55.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:75.00 stddev:16.32 (21.8%) stddev-sans-first:16.16 16:53:18 INFO - PROCESS | 6182 | Values: 65.0 66.0 83.0 77.0 73.0 69.0 82.0 108.0 112.0 81.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.00 stddev:2.11 (2.5%) stddev-sans-first:1.87 16:53:18 INFO - PROCESS | 6182 | Values: 89.0 84.0 86.0 85.0 83.0 88.0 88.0 83.0 85.0 86.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:142.00 stddev:8.12 (5.7%) stddev-sans-first:8.40 16:53:18 INFO - PROCESS | 6182 | Values: 139.0 134.0 141.0 138.0 141.0 156.0 156.0 143.0 154.0 139.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.10 Median:170.00 stddev:23.55 (13.9%) stddev-sans-first:24.63 16:53:18 INFO - PROCESS | 6182 | Values: 169.0 171.0 235.0 212.0 168.0 167.0 169.0 172.0 169.0 169.0 16:53:18 INFO - PROCESS | 6182 | 16:53:18 INFO - PROCESS | 6182 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.32 (2.0%) stddev-sans-first:1.36 16:53:18 INFO - PROCESS | 6182 | Values: 68.0 66.0 69.0 69.0 65.0 67.0 67.0 68.0 67.0 66.0 16:53:18 INFO - PROCESS | 6182 | -------- Summary: end -------- 16:53:18 INFO - PROCESS | 6182 | 16:53:19 INFO - TEST-INFO | 6182: exit 0 16:53:19 INFO - TEST-OK | kraken | took 59732ms 16:53:19 INFO - SUITE-END | took 1177s 16:53:19 INFO - Completed test suite (00:19:37) 16:53:19 INFO - javascript benchmark 16:53:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3013.081010291861, 3350.346682396932, 3374.5613070300856, 3399.354122716684, 3407.6762707542784, 5656.604037717361, 5745.971270143648, 5773.30720313563, 5801.6519008859495, 5820.097884827848, 5762.884742305151, 5799.217105690731, 5800.129980502927, 5808.361080281165, 5837.9124313135235, 4831.106245344605, 4880.170371036915, 5276.419593844674, 5373.731313434332, 5405.891882162355, 5172.767225474862, 5331.466853314657, 5337.2794672719265, 5369.5167434930845, 5410.188471729232, 5210.218467229927, 5374.785008599671, 5381.704006279653, 5392.73036348183, 5407.756650950697, 5376.1398176292, 5402.37872644647, 5420.891582168374, 5427.674339539641, 5462.644928079662, 5179.766910489037, 5211.947880521184, 5220.582353411718, 5316.308879845596, 5320.973395133038, 5729.369769325371, 5767.279090113736, 5826.038703613941, 5855.502282306009, 5908.202392676976, 6244.56288059836, 6329.1772069631115, 6330.0, 6332.968335158294, 6340.809775706736, 6132.692058233643, 6287.9056814147825, 6348.492120630339, 6351.587146835441, 6364.427201551883, 5918.8816223675285, 5938.287405511366, 5949.8810023799715, 5950.732217050243, 5975.67133807636, 6391.2330520337855, 6401.839954001187, 6402.359764023654, 6405.967970160119, 6414.550981431255, 6101.694915254219, 6112.2970858351855, 6213.316535181127, 6237.875242495124, 6279.748810047547, 6331.271903731038, 6335.873282534416, 6348.365163483707, 6355.650439225795, 6359.20509936258, 6236.781712640036, 6293.0, 6293.842653933688, 6306.274778400542, 6316.778912738124, 6159.6612186330185, 6240.407161319758, 6256.092866534327, 6298.622082675054, 6311.305756366796, 5029.748512574357, 5052.166392545262, 5060.240963855451, 5061.1902095665255, 5077.263796749525, 4534.569215924482, 4559.589636932692, 4603.861884143481, 4613.331066995334, 4664.253719404946, 5753.741081651336, 5803.158542011469, 5812.854678632983, 5843.328017277982, 5846.298444186725, 6217.440430361289, 6259.96870015647, 6288.9056664150075, 6327.082573026885, 6345.746170153235, 5829.795957141479, 5832.650040997469, 5841.1238314253205, 5870.354261031283, 5889.646621202742, 5450.754716037788, 5483.451654834565, 5485.588580856452, 5491.093969495068, 5517.44825517445, 6282.277538083179, 6305.337939516285, 6338.461230795434, 6412.711427985659, 6439.871202576016, 6316.842078948063, 6330.715117819617, 6337.588056776295, 6354.936450635434, 6364.490840732823, 6461.967690161519, 6473.2232132144445, 6479.384458476437, 6481.546291759531, 6486.0, 6125.204929265031, 6127.250613123855, 6301.212348456443, 6318.62088274705, 6331.715072821827, 6304.810855674337, 6319.652419116993, 6336.303006669171, 6357.1735674362035, 6360.586561873463, 6228.906566401417, 6236.438720515175, 6261.718222680081, 6276.372362763687, 6277.843053923689, 5979.760809567742, 6065.332813390612, 6122.123717501779, 6252.374762523711, 6252.531060170596, 6101.481374083252, 6114.021756518936, 6122.39815511198, 6130.63216207029, 6143.174139895698, 6247.0, 6252.062190671254, 6260.092286618506, 6267.1539342188225, 6270.2475702915945, 4795.712257264575, 5898.8230353089475, 6045.909311360245, 6092.177556030057, 6102.9694851525455], "value": 5793.72440862047, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.5033463190491, 470.90111076673895, 485.61393692014843, 492.9630277729169, 495.99313393810564, 25150.74849251507, 40830.0, 42276.943076423064, 42377.15245695092, 44017.339739903844, 43204.351934721, 43424.78287608558, 43462.782686086524, 43500.56499435005, 43565.0, 9657.971825916566, 9865.112139907407, 9882.0, 9892.159166470823, 9893.901060989407, 9772.511374431286, 9905.653302134428, 9906.851397229048, 9910.008999100104, 9923.751906202331, 9883.703488895344, 9901.851472227921, 9922.801543969117, 9952.701418957442, 9969.052939970692, 9818.263630227726, 9875.901240987641, 9876.506174691236, 9883.96218397065, 9885.851712224323, 9683.467409292487, 9765.853512197322, 9775.266854985834, 9775.657851975147, 9781.46201958892, 41036.0, 41172.176556469, 41377.793111034254, 41429.792851035854, 41498.0, 3393.694567488938, 3429.331280400323, 3493.2140268439653, 3745.232227393378, 4053.1691003344245, 30976.0, 31549.52675709866, 31631.0, 32250.87121950744, 32297.86957456501, 3208.3262514871903, 3235.9514607280676, 3246.3669584431045, 3265.559149514833, 3341.599008119041, 336250.0, 336992.0, 337605.0, 338330.30834845913, 340341.0, 59495.0, 59725.0, 59947.700261498416, 59951.40048599545, 60070.0, 34391.484127738106, 35116.82441587776, 35480.29039419197, 36293.0, 62838.37161628417, 13739.106958047694, 16365.42721004783, 16461.917690411472, 16469.752953705705, 16482.505524834272, 12852.678683032813, 12933.224006559449, 12962.35188240603, 12965.675858103623, 12973.221606703628, 15369.846301536842, 19188.520286992938, 19189.040547972825, 19204.231830726894, 19317.323893663655, 7834.573465991696, 7944.576904044322, 7966.123726390092, 8028.598570071473, 8045.235702608243, 744.0959234530072, 775.7052320118431, 809.6599428240153, 863.7970077031893, 875.5228400521731, 23596.882015589814, 25599.744002559735, 27183.0, 27640.861795691297, 31192.844035779675, 1048.3552615141632, 1050.2700623066896, 1052.1587222360868, 1173.8180582009802, 1215.2526196389333, 13192.802107968197, 15628.843711562738, 15710.842891571167, 15825.52523424299, 16155.838441615433, 583029.0, 679145.2085479181, 683521.0, 683580.0, 686378.0, 58905.70547147237, 59457.40542594605, 59633.70183149143, 59662.40337596568, 59755.70122149361, 1232.2544860359353, 1276.5276847566645, 1322.285965578577, 1363.9590812275449, 1387.2439520461373, 29919.401611968075, 31690.366192675556, 31812.84093579517, 31813.5227971576, 32561.674383255864, 51639.22541161811, 52065.219021715464, 52095.0, 52141.0, 52283.738581308375, 542357.0, 545214.0, 545429.0, 545461.0, 546027.0, 23916.92373843216, 26358.81385337703, 26911.596326055518, 27048.1885543434, 27368.589471158346, 47797.283040755116, 48185.5181448181, 48625.0, 49103.0, 49205.75397122991, 28634.85682571574, 38398.616013839506, 38989.610103898594, 39810.60189398069, 40947.0, 38762.418563721, 38922.07487090774, 39128.87076950789, 39956.0, 40631.39052914149, 33124.1718957028, 33725.83137084397, 36323.63676363308, 38575.807120964215, 38844.755957945315, 29716.405671886874, 33821.3235735279, 34630.0, 34822.47766283558, 34823.0, 24461.0, 24928.0, 25056.74943250617, 25534.616980744933, 25550.9779608821], "value": 19528.580654039768, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.27067519176484, 346.35404969731457, 372.79868870809764, 373.76452834714144, 401.6866843861789, 15731.921340393297, 17961.820381796195, 20836.95815209237, 20992.265270715547, 21072.367828965154, 15462.922685386557, 19626.705599416022, 20828.79171208291, 21102.472438189052, 21173.788262117374, 5293.232481290211, 5947.464728174475, 6207.875842483148, 6215.658138802364, 6230.660408012276, 6066.514678825705, 6113.510919126482, 6155.692215389235, 6157.384261573851, 6177.104319873616, 5777.508911742486, 6065.939340606582, 6125.846853828668, 6131.9386806131815, 6134.815955521319, 6112.144299798032, 6153.969230153865, 6157.476614487729, 6159.969200154015, 6165.383461653843, 5813.645473583163, 5841.707914604295, 5907.645541267538, 5912.970435147839, 5970.34326224115, 13090.410931508104, 13297.002724795679, 13311.86688133116, 24166.03335866563, 25284.115055973136, 2203.922862699814, 2348.3072493614254, 2379.3337865397716, 2575.3947822261753, 2881.6253886994764, 9530.428174309494, 9795.608175673036, 9976.700698979042, 10152.543135558917, 10460.320079194827, 1851.490840018992, 1863.2081365419642, 1975.7332760077493, 2010.4672261850621, 2022.4842665120448, 107775.0, 123200.38399807943, 124004.0, 124545.37727311306, 128929.99999999907, 10771.353718776898, 10832.67501974942, 11148.777024459465, 11219.1585631078, 11406.771864562663, 13732.931335343259, 13838.100523465942, 13856.792148117785, 13880.23658698761, 13899.374528146056, 8359.247667709813, 8487.957560212159, 8490.235878770816, 8501.14988501145, 8609.182127697859, 6847.965760171267, 8491.320694344438, 8675.696350627697, 8682.782930426663, 8687.21815036637, 4323.978380108079, 4388.345051244015, 4393.450818647669, 4456.888577785581, 4466.1514312281215, 1844.7878493973365, 1859.2562974810185, 1860.5069656541027, 1887.292265400475, 1906.5424298168339, 713.3116542536477, 934.7990182110879, 963.4219468318953, 968.8837339519303, 986.2800155886243, 20360.898195509224, 21213.833239171992, 21214.575708485743, 21228.044737986827, 21311.0, 956.7464621875208, 957.9616815327308, 974.4202199691173, 996.9351992120489, 1009.0968583118068, 8210.589470526449, 8382.790430239293, 10368.377897326185, 10613.310134841211, 11035.558577656804, 515503.0, 570698.0, 573761.1311943413, 598624.0, 599834.0, 87635.0, 111164.0, 116473.0, 132461.67538324493, 137525.31237343748, 842.9157084291522, 895.2077411490748, 916.3688634231079, 930.1907340613709, 950.8573713943102, 18614.53463663419, 19002.619947601248, 19272.421827345202, 19274.421767347, 19304.814705573423, 53725.731371342896, 53955.92088158293, 54397.00816455133, 55143.172852408046, 55320.44679553153, 77916.05209869798, 79181.60409197917, 83606.16393836227, 85222.0, 88689.11310886808, 7024.402925751164, 7102.9289707104335, 7327.120745510573, 7363.300486453778, 7991.560464174526, 10666.0, 12980.286084265455, 13239.867601323864, 13255.933720331337, 13313.670107731303, 5426.457354264542, 5459.972700136474, 10822.0, 10967.903209678969, 11429.828552571884, 10836.783264334827, 10915.508802103766, 10967.890321096687, 11277.266977646756, 11322.773544529227, 8732.0, 10993.670189894316, 11329.433528323387, 11494.0, 11559.884401155881, 7271.963640181977, 7414.740484083031, 7444.878209710207, 7471.775846724389, 7564.205758395509, 9577.712668619952, 10925.726856828644, 11254.94372528132, 11265.831012534654, 11328.60349887783], "value": 9314.443420122841, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.6232254968609, 1508.6153030977102, 1640.4012535424565, 1810.5202121437812, 1832.2029916986112, 8497.150284971507, 8978.326625503089, 9014.053524379944, 9041.954790226047, 9047.95476022619, 7365.742199023035, 7519.887201691965, 8490.787730306745, 8735.956320218405, 8768.035516093225, 2365.5387199496104, 2389.9880500597474, 2399.628057651058, 2401.3396316013063, 2411.3730430088217, 2369.5024044950583, 2383.988080059606, 2411.3248290478696, 2413.239829453725, 2413.8310318277727, 2381.273711517986, 2382.332946774906, 2390.8924098415528, 2416.4804567018086, 2420.9273721788372, 2353.752855950118, 2374.2995816234165, 2378.6907701998803, 2379.940501487468, 2388.988055059731, 2335.6846825678485, 2360.138549429463, 2369.751176126499, 2380.952380952371, 2381.1070848431837, 8846.867296990551, 8986.505742184178, 8986.595603197871, 9000.954995225047, 9011.594478248495, 5240.135377662719, 5402.405735369106, 5405.810796622139, 5406.621536492447, 5409.540189083893, 3525.7531972761917, 3674.0080178351964, 3695.224002959382, 3755.511783468132, 3760.4735337052834, 928.9442633441947, 1118.7874303882236, 1128.0749785176158, 1132.9943350283195, 1141.8116010858198, 11721.238119522204, 11726.29642221461, 11839.28964262147, 11844.940775296154, 11847.40762961857, 4246.087091275391, 4415.977920110443, 4470.776461176928, 4688.765561721901, 4703.365045718852, 8981.910180898238, 9195.264378849675, 9199.4480331179, 9206.309526785519, 9212.723618291462, 5281.12861377876, 5281.234221037928, 5281.287026251407, 5282.498162674539, 5286.62993590445, 4147.221043699611, 4433.7561434121435, 4554.43069616298, 4591.196540605448, 4954.40547134339, 3569.411047177187, 3698.14942563212, 3698.9445158322646, 3883.825227864753, 3922.7842468663926, 969.7866469376726, 1154.1343992005995, 1171.8183681529376, 1179.1156632525606, 1180.4451907603413, 817.7896712864994, 845.3828705045352, 878.1218781218781, 894.5393122541897, 937.920276776468, 5480.945190548123, 5750.367459579443, 5782.59521833476, 5970.164177015221, 6222.595531290452, 892.5269607108243, 899.9955000224957, 903.3541018172039, 921.3826736086704, 940.3323640215524, 3125.5936728225183, 3888.300105980951, 3901.941470877939, 3957.3470377387985, 4065.89835254121, 10577.735556611146, 10842.891571084347, 10938.398388088572, 10940.726481838017, 10948.95984881435, 10879.456027198608, 10882.660228051007, 10887.891121088845, 10917.945410273056, 10945.288556243819, 8234.176582341837, 8391.370647201484, 8393.118722534167, 8404.663813447394, 8419.873701894476, 5279.313689220377, 5484.341878974548, 5567.359753628384, 5601.383847776659, 5657.3776884543495, 9934.056264654846, 10239.948800256248, 10298.897011029794, 10300.484975751033, 10324.845127322946, 9331.533423328941, 9763.414195148314, 10175.79648407013, 10201.285909986527, 10331.173506119643, 7145.1068616422945, 7459.477836551389, 7550.0, 7667.961660191663, 7771.261730135854, 8277.089520152898, 8377.70678026266, 8408.663653453794, 8438.620262088098, 8498.107698691796, 7593.5823529706395, 7614.619269036415, 7622.961885190539, 7686.846263074819, 7714.382849371925, 7450.403967682464, 7469.215732348025, 7492.363149132167, 7498.737544185928, 7576.469647124869, 7016.263292354433, 7042.964785176247, 7063.540869843444, 7126.576963832322, 7142.107236595426, 4028.738132021409, 4927.28554359623, 4942.0, 4960.330355402119, 5069.92395114081, 4920.0, 4936.012797440598, 5021.949780502295, 5069.645124841226, 5198.246254293031], "value": 4561.274439255623, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.3019016602746, 303.4598414821618, 309.54651435646775, 309.7367237847831, 318.0696462846174, 4505.071703392792, 4673.971726220226, 5346.812861549846, 5403.702796346204, 5457.727113644317, 4477.261251893442, 5249.212618107285, 5420.9186862197, 5444.646098003637, 5453.182022696597, 2941.617589713345, 3158.936821263579, 3170.511148748233, 4177.686673499485, 4195.895102622429, 3483.885156749841, 3998.360262358023, 4102.241085399196, 4177.6866734995, 4181.686373521983, 4079.2453396121664, 4093.1813637272517, 4093.570175131613, 4110.527289361732, 4151.398047283142, 3778.263238668461, 3924.2543916655745, 3985.6412922836794, 4134.214499245134, 4142.638695943314, 2983.358577905739, 3515.5078289039557, 3560.6439356064448, 3561.554805649294, 3561.6972557332656, 5333.733163405576, 5367.651102678314, 5369.24830523727, 5371.4897084776885, 5406.702631355274, 3913.5695073541888, 4524.705894116872, 4622.514635963209, 4732.290156476522, 4745.833895813665, 4230.344296634026, 5699.2306038684665, 5749.59752817303, 5763.250777398953, 5776.508996735282, 3380.5436266104007, 3912.0, 3924.4113382992496, 3933.5869733678123, 3950.6370302245678, 5074.974625126851, 5586.776528938837, 5808.2449281593545, 5952.434518720715, 5992.2809262888295, 3429.9314013719586, 3782.735208535376, 3911.902202444962, 3923.588023257574, 3935.74417662851, 3965.0, 3979.46277252573, 3988.561258261589, 3989.7207195496617, 4002.959970400317, 4090.4682391288943, 4678.274867395559, 4684.531546845288, 4685.250359942393, 4690.296455531698, 4642.837500687528, 4940.925886111711, 5464.497263252637, 5671.858203544862, 5706.2609947225565, 1342.4630147940902, 1353.75454581785, 1361.8638136186303, 1365.5015919189623, 1372.540199033315, 784.6233807772302, 801.7892981597829, 813.9418755617673, 818.5579786915118, 833.2375876073446, 4563.06457176278, 4732.006278681447, 5259.921101183485, 5354.705491197944, 5394.029074766501, 4662.370579971661, 5420.620556561003, 5825.76696932118, 5921.585489015728, 5951.791687290924, 4687.20317546018, 4940.184869496493, 4953.95966846959, 4958.454569997298, 5323.760430780624, 2775.4726601945968, 2970.495015847311, 3081.476149054666, 3087.7838551301193, 3154.6592698103213, 5129.666571672829, 6057.212562366953, 6187.690615469299, 6199.706108739795, 6216.191895053705, 4835.540623640749, 5182.922256166161, 5567.860803479945, 5635.92917345703, 6102.359252278536, 5298.28473156119, 6141.407276743066, 6146.815595532141, 6278.466330361879, 6299.464545513682, 5468.2344471773185, 5584.86037849057, 5717.771289148387, 6044.395560443921, 6061.545384096122, 4859.052484765437, 5459.2629994950175, 5942.811437712388, 5956.438731345563, 5993.70031498432, 5388.730563471732, 6254.468370188585, 6258.374162583706, 6285.465735412541, 6290.276618188784, 3835.9232815344094, 3878.631530004645, 3911.6283953024417, 3955.960440395674, 3961.8613348532663, 5317.627766056339, 5993.550483713651, 5998.4601385875485, 6002.579819412599, 6009.90985135232, 5379.462053794589, 5453.481919217668, 5592.972035139798, 5814.767409303749, 5842.474177324061, 4780.49804770508, 5509.614326997072, 5771.740271687868, 5795.652260864362, 5861.413858614104, 4506.684532082722, 5671.0, 5717.514011309086, 5718.857028574319, 5722.2561067061015, 5019.171836646912, 5297.82789056485, 5535.806246781343, 5690.601657884074, 5855.853603659943, 4688.507706690748, 5042.369703787027, 5493.505584497414, 5706.144078388275, 5757.769689212385], "value": 4120.720585959651, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.9853002939941, 759.7758661194954, 763.2291385700441, 788.8027993001749, 865.3682811547568, 14817.259137043146, 16101.83898161018, 16129.516114516555, 16143.83856161438, 16195.02829830211, 14440.638984025378, 14514.346854391551, 14594.124352538855, 14602.342894569743, 14602.926985365058, 674.7806962737105, 841.9663213471454, 846.1453931529169, 872.3152325424543, 875.369733791669, 874.8031692869117, 875.6584931876573, 876.3602570123782, 876.526675595181, 877.7673916412156, 922.008840496469, 925.6621333213362, 928.0441145619989, 928.22029495224, 928.4429342394577, 699.1854489519703, 874.9431286966391, 900.8333458310234, 910.2899738204211, 911.7583840282331, 673.706937482192, 673.9730410783562, 674.689642764329, 674.8987651852212, 676.6447615002114, 14461.927690361481, 14484.42062317506, 14501.564953051426, 14501.782473262909, 14506.492272770507, 12564.497420103186, 12698.74602507954, 12701.237925724487, 12771.297578633172, 13103.541376051888, 12298.446569904287, 12348.012159027347, 12598.370081495888, 12705.491780328775, 12775.808362874564, 4553.157665831832, 4565.178267911774, 4589.173948689235, 4722.409698787652, 4748.548887855648, 113312.0, 115769.84230157675, 115926.0, 116115.99999999916, 118185.0, 10833.404162770965, 10902.890971090346, 11023.44882755859, 11098.167637427225, 11135.610253641245, 21193.94030298479, 21254.043568039477, 21279.893600532207, 21290.680639790415, 21353.14587416517, 7409.222031686703, 7807.570583617842, 8132.715354962548, 8141.715039973573, 8192.098869124391, 3726.4782930389765, 4364.912701745948, 4528.7509186994375, 4580.38164847748, 4583.129205450954, 3397.2016576104597, 3619.60184379718, 3650.178709790329, 3650.8904732858055, 3658.908527286839, 567.9488846003796, 683.0676127086491, 683.5898460923406, 683.9247682754893, 684.4968947823346, 606.5541826757329, 634.5082561015158, 678.9117414736053, 684.0271228171167, 697.9023332706388, 8998.775030624287, 9424.0, 9528.571214295373, 9552.474613896164, 9647.951760241294, 597.7190720361423, 621.2700077409027, 656.3534918105662, 665.7070888808909, 694.2224708326605, 3746.4754934309217, 4271.893202669959, 4677.46209185948, 4703.05938812232, 4740.634149753713, 82843.0, 105098.0, 105302.0, 105610.0, 155955.0, 136739.94890076655, 141947.16105677828, 143199.0, 144139.55860441472, 152381.0, 74433.0, 121554.0, 123099.0, 124169.0, 126536.0, 9600.951995239979, 9707.223422126353, 9835.0, 9916.107550320507, 9950.55222515003, 43708.56291437045, 46434.767826160656, 59706.0, 61562.69218653878, 63438.40916589118, 85971.14028859632, 93314.53342733243, 95774.0, 96139.0, 98320.0, 12769.425375857923, 13319.200847949154, 13365.933170334087, 13378.13042152257, 13589.98075144388, 13627.659308517366, 14052.929735351257, 14998.025128366622, 15244.771328430304, 15760.975536590086, 13834.584962451143, 14070.929645351707, 14212.360443779848, 14265.215413152377, 14587.635309117357, 13951.720965580429, 14245.64385890361, 14616.707665846836, 14657.0, 14727.305382051827, 13778.311084445939, 13900.304984750519, 13961.511347102803, 14020.228887411291, 14198.0, 9610.711678649652, 11001.339919604849, 11553.191276610556, 11682.357470339213, 12153.513859445524, 8750.387472876837, 9515.571799268911, 10529.86971719089, 10862.131029517777, 11800.82298765502], "value": 7869.355824734987, "unit": "score"}], "name": "dromaeo_css", "value": 7336.203980080908, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 113.0, 112.0, 109.0, 114.0, 110.0, 113.0, 112.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 128.0, 108.0, 167.0, 110.0, 108.0, 130.0, 107.0, 142.0, 107.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 184.0, 182.0, 182.0, 197.0, 202.0, 184.0, 183.0, 184.0], "value": 186.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 61.0, 87.0, 58.0, 69.0, 62.0, 72.0, 110.0, 79.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 107.0, 71.0, 83.0, 68.0, 96.0, 75.0, 69.0, 101.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 88.0, 88.0, 87.0, 125.0, 87.0, 90.0, 86.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 100.0, 95.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 80.0, 84.0, 87.0, 84.0, 87.0, 87.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 54.0, 53.0, 56.0, 53.0, 53.0, 54.0, 55.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 83.0, 77.0, 73.0, 69.0, 82.0, 108.0, 112.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 86.0, 85.0, 83.0, 88.0, 88.0, 83.0, 85.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 134.0, 141.0, 138.0, 141.0, 156.0, 156.0, 143.0, 154.0, 139.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 235.0, 212.0, 168.0, 167.0, 169.0, 172.0, 169.0, 169.0], "value": 180.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 69.0, 65.0, 67.0, 67.0, 68.0, 67.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1468.7, "alertThreshold": 2.0}]} 16:53:19 INFO - Return code: 0 16:53:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:53:19 INFO - # TBPL SUCCESS # 16:53:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:53:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:53:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:53:19 INFO - 2016-08-15 16:53:19.682 screenresolution[6186:102216] starting screenresolution argv=screenresolution get 16:53:19 INFO - 2016-08-15 16:53:19.685 screenresolution[6186:102216] Display 0: 1600x1200x32@60 16:53:19 INFO - 2016-08-15 16:53:19.691 screenresolution[6187:102219] starting screenresolution argv=screenresolution list 16:53:19 INFO - Available Modes on Display 0 16:53:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:53:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:53:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:53:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:53:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:53:19 INFO - Intel Iris: 16:53:19 INFO - Chipset Model: Intel Iris 16:53:19 INFO - Type: GPU 16:53:19 INFO - Bus: Built-In 16:53:19 INFO - VRAM (Dynamic, Max): 1536 MB 16:53:19 INFO - Vendor: Intel (0x8086) 16:53:19 INFO - Device ID: 0x0a2e 16:53:19 INFO - Revision ID: 0x0009 16:53:19 INFO - Displays: 16:53:19 INFO - Display: 16:53:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:53:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:53:19 INFO - Display Serial Number: 1600x1200 60 16:53:19 INFO - Main Display: Yes 16:53:19 INFO - Mirror: Off 16:53:19 INFO - Online: Yes 16:53:19 INFO - Rotation: Supported 16:53:19 INFO - Return code: 0 16:53:19 INFO - Running post-action listener: _resource_record_post_action 16:53:19 INFO - [mozharness: 2016-08-15 23:53:19.927134Z] Finished run-tests step (success) 16:53:19 INFO - Running post-run listener: _resource_record_post_run 16:53:19 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 28523520; Write bytes: 703760384; Read time: 284; Write time: 1918 16:53:19 INFO - TinderboxPrint: CPU usage
53.3% 16:53:19 INFO - TinderboxPrint: I/O read bytes / time
28,523,520 / 284 16:53:19 INFO - TinderboxPrint: I/O write bytes / time
703,760,384 / 1,918 16:53:19 INFO - TinderboxPrint: CPU idle
2,265.7 (46.7%) 16:53:19 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 16:53:19 INFO - TinderboxPrint: CPU user
1,613.9 (33.3%) 16:53:19 INFO - TinderboxPrint: Swap in / out
427,884,544 / 0 16:53:19 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393912832; Write bytes: 389251584; Read time: 29278; Write time: 729 16:53:20 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 28515328; Write bytes: 294524416; Read time: 283; Write time: 1139 16:53:20 INFO - Running post-run listener: _upload_blobber_files 16:53:20 INFO - Blob upload gear active. 16:53:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:53:20 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 16:53:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:53:20 INFO - (blobuploader) - INFO - Open directory for files ... 16:53:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:53:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:53:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:21 INFO - (blobuploader) - INFO - Done attempting. 16:53:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:53:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:53:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:22 INFO - (blobuploader) - INFO - Done attempting. 16:53:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:53:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:53:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:25 INFO - (blobuploader) - INFO - Done attempting. 16:53:25 INFO - (blobuploader) - INFO - Iteration through files over. 16:53:25 INFO - Return code: 0 16:53:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:53:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:53:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8fe85db210c63188ba5edea9e22d7e5c6f1a2c1ef58995727d995b5c7e0d78bbad9c18ed04959b782e86fc61b49efb9eb34e690c97e2ec5e3790079d59bb8e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/799026993138ec7458e261b4aa4ab6dfdcbd0f0a93e9c466b22d7137f03e4b7055b9e665e1a8eb5ecf6053bfef96bca788f0ebf8a208d69ff48ec3d07d8549dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56d518f84bb4b28f29db251c6a1ef15caaa859bb1a7fb38d7d82bbe28866ddf5f6c5e667bac1f33d3126b5b673bb7ef6ef319006cd98844843d667b19448d02c"} 16:53:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:53:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:53:25 INFO - Contents: 16:53:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8fe85db210c63188ba5edea9e22d7e5c6f1a2c1ef58995727d995b5c7e0d78bbad9c18ed04959b782e86fc61b49efb9eb34e690c97e2ec5e3790079d59bb8e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/799026993138ec7458e261b4aa4ab6dfdcbd0f0a93e9c466b22d7137f03e4b7055b9e665e1a8eb5ecf6053bfef96bca788f0ebf8a208d69ff48ec3d07d8549dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56d518f84bb4b28f29db251c6a1ef15caaa859bb1a7fb38d7d82bbe28866ddf5f6c5e667bac1f33d3126b5b673bb7ef6ef319006cd98844843d667b19448d02c"} 16:53:25 INFO - Running post-run listener: copy_logs_to_upload_dir 16:53:25 INFO - Copying logs to upload dir... 16:53:25 INFO - mkdir: /builds/slave/test/build/upload/logs 16:53:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.073142 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-15 16:53:25.344659) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 16:53:25.348442) ========= 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.1rFMdVtAUU/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.SQ25LNszU8/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8fe85db210c63188ba5edea9e22d7e5c6f1a2c1ef58995727d995b5c7e0d78bbad9c18ed04959b782e86fc61b49efb9eb34e690c97e2ec5e3790079d59bb8e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/799026993138ec7458e261b4aa4ab6dfdcbd0f0a93e9c466b22d7137f03e4b7055b9e665e1a8eb5ecf6053bfef96bca788f0ebf8a208d69ff48ec3d07d8549dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56d518f84bb4b28f29db251c6a1ef15caaa859bb1a7fb38d7d82bbe28866ddf5f6c5e667bac1f33d3126b5b673bb7ef6ef319006cd98844843d667b19448d02c"} build_url:https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/B3WGrhNcSWGnfsERT9KFAw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b8fe85db210c63188ba5edea9e22d7e5c6f1a2c1ef58995727d995b5c7e0d78bbad9c18ed04959b782e86fc61b49efb9eb34e690c97e2ec5e3790079d59bb8e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/799026993138ec7458e261b4aa4ab6dfdcbd0f0a93e9c466b22d7137f03e4b7055b9e665e1a8eb5ecf6053bfef96bca788f0ebf8a208d69ff48ec3d07d8549dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56d518f84bb4b28f29db251c6a1ef15caaa859bb1a7fb38d7d82bbe28866ddf5f6c5e667bac1f33d3126b5b673bb7ef6ef319006cd98844843d667b19448d02c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 16:53:25.382032) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:53:25.382393) ========= 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.1rFMdVtAUU/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.SQ25LNszU8/Listeners TMPDIR=/var/folders/r2/7_qh7wy12sb77w8gdmz8z9zr00000w/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.005233 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 16:53:25.430859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 16:53:25.431194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 16:53:25.431532) ========= ========= Total master_lag: 0.18 =========