builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1472681316.3 results: success (0) buildid: 20160831125821 builduid: ce4f2544975f4132893902791ded70cb revision: 7eac2acb63a5b28940bb8801e02ccab5c83c078c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.303775) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.304282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.304625) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.451084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.451747) ========= 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.G1wE9OQkvF/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.WCTkURcqPj/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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-31 15:08:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.18M=0.001s 2016-08-31 15:08:36 (8.18 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.250110 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.727650) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.728282) ========= 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.G1wE9OQkvF/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.WCTkURcqPj/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.022086 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:36.778859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 15:08:36.779219) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7eac2acb63a5b28940bb8801e02ccab5c83c078c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 7eac2acb63a5b28940bb8801e02ccab5c83c078c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G1wE9OQkvF/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.WCTkURcqPj/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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-31 15:08:36,856 truncating revision to first 12 chars 2016-08-31 15:08:36,856 Setting DEBUG logging. 2016-08-31 15:08:36,856 attempt 1/10 2016-08-31 15:08:36,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7eac2acb63a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 15:08:38,170 unpacking tar archive at: autoland-7eac2acb63a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571954 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 15:08:38.377775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:38.378444) ========= script_repo_revision: 7eac2acb63a5b28940bb8801e02ccab5c83c078c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:38.379291) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:38.379952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 15:08:38.418578) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-31 15:08:38.419315) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G1wE9OQkvF/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.WCTkURcqPj/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:08:38 INFO - MultiFileLogger online at 20160831 15:08:38 in /builds/slave/test 15:08:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:08:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:08:38 INFO - {'append_to_log': False, 15:08:38 INFO - 'base_work_dir': '/builds/slave/test', 15:08:38 INFO - 'blob_upload_branch': 'Autoland', 15:08:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:08:38 INFO - 'branch': 'Autoland', 15:08:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:08:38 INFO - 'config_files': ('talos/mac_config.py',), 15:08:38 INFO - 'default_actions': ('clobber', 15:08:38 INFO - 'read-buildbot-config', 15:08:38 INFO - 'download-and-extract', 15:08:38 INFO - 'populate-webroot', 15:08:38 INFO - 'create-virtualenv', 15:08:38 INFO - 'install', 15:08:38 INFO - 'run-tests'), 15:08:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:08:38 INFO - 'download_minidump_stackwalk': True, 15:08:38 INFO - 'download_symbols': 'ondemand', 15:08:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:08:38 INFO - 'tooltool.py': '/tools/tooltool.py', 15:08:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:08:38 INFO - '/tools/misc-python/virtualenv.py')}, 15:08:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:08:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:08:38 INFO - 'installer_path': 'installer.exe', 15:08:38 INFO - 'log_level': 'info', 15:08:38 INFO - 'log_name': 'talos', 15:08:38 INFO - 'log_to_console': True, 15:08:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:08:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:08:38 INFO - 'opt_config_files': (), 15:08:38 INFO - 'pip_index': False, 15:08:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:08:38 INFO - 'cmd': ('bash', 15:08:38 INFO - '-c', 15:08:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:08:38 INFO - 'enabled': True, 15:08:38 INFO - 'halt_on_failure': False, 15:08:38 INFO - 'name': 'check_screen_resolution'},), 15:08:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:08:38 INFO - 'cmd': ('bash', 15:08:38 INFO - '-c', 15:08:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:08:38 INFO - 'enabled': True, 15:08:38 INFO - 'halt_on_failure': False, 15:08:38 INFO - 'name': 'check_screen_resolution'},), 15:08:38 INFO - 'run_cmd_checks_enabled': True, 15:08:38 INFO - 'sps_profile': False, 15:08:38 INFO - 'sps_profile_interval': 0, 15:08:38 INFO - 'suite': 'dromaeojs', 15:08:38 INFO - 'system_bits': '32', 15:08:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:08:38 INFO - 'title': 't-yosemite-r7-0180', 15:08:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:08:38 INFO - 'use_talos_json': True, 15:08:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:08:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:08:38 INFO - 'work_dir': 'build'} 15:08:38 INFO - [mozharness: 2016-08-31 22:08:38.629636Z] Running clobber step. 15:08:38 INFO - Running pre-action listener: _resource_record_pre_action 15:08:38 INFO - Running main action method: clobber 15:08:38 INFO - rmtree: /builds/slave/test/build 15:08:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:08:40 INFO - Running post-action listener: _resource_record_post_action 15:08:40 INFO - [mozharness: 2016-08-31 22:08:40.196404Z] Finished clobber step (success) 15:08:40 INFO - [mozharness: 2016-08-31 22:08:40.196658Z] Running read-buildbot-config step. 15:08:40 INFO - Running pre-action listener: _resource_record_pre_action 15:08:40 INFO - Running main action method: read_buildbot_config 15:08:40 INFO - Using buildbot properties: 15:08:40 INFO - { 15:08:40 INFO - "project": "", 15:08:40 INFO - "product": "firefox", 15:08:40 INFO - "script_repo_revision": "production", 15:08:40 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:08:40 INFO - "repository": "", 15:08:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:08:40 INFO - "buildid": "20160831125821", 15:08:40 INFO - "pgo_build": "False", 15:08:40 INFO - "basedir": "/builds/slave/test", 15:08:40 INFO - "buildnumber": 165, 15:08:40 INFO - "slavename": "t-yosemite-r7-0180", 15:08:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:08:40 INFO - "platform": "yosemite_r7", 15:08:40 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:08:40 INFO - "branch": "autoland", 15:08:40 INFO - "repo_path": "integration/autoland", 15:08:40 INFO - "revision": "7eac2acb63a5b28940bb8801e02ccab5c83c078c", 15:08:40 INFO - "stage_platform": "macosx64", 15:08:40 INFO - "builduid": "ce4f2544975f4132893902791ded70cb", 15:08:40 INFO - "slavebuilddir": "test" 15:08:40 INFO - } 15:08:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:08:40 INFO - Running post-action listener: _resource_record_post_action 15:08:40 INFO - [mozharness: 2016-08-31 22:08:40.202543Z] Finished read-buildbot-config step (success) 15:08:40 INFO - [mozharness: 2016-08-31 22:08:40.202749Z] Running download-and-extract step. 15:08:40 INFO - Running pre-action listener: _resource_record_pre_action 15:08:40 INFO - Running main action method: download_and_extract 15:08:40 INFO - mkdir: /builds/slave/test/build/tests 15:08: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')]} 15:08:40 INFO - trying https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:08:40 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:41 INFO - Downloaded 1199 bytes. 15:08:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:08:41 INFO - Using the following test package requirements: 15:08:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:08:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:08:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:08:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'jsshell-mac.zip'], 15:08:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:08:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:08:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:08:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:08:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:08:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:08:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:08:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:08: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')]} 15:08:41 INFO - trying https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:08:41 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:42 INFO - Downloaded 16396673 bytes. 15:08:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:08:49 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 15:08:49 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')]} 15:08:49 INFO - trying https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:08:49 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:50 INFO - Downloaded 16396673 bytes. 15:08:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:57 INFO - trying https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:08:57 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:08:58 INFO - Downloaded 14413998 bytes. 15:08:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:08:59 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')]} 15:08:59 INFO - trying https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/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 15:09:02 INFO - Downloaded 111575991 bytes. 15:09:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:09:02 INFO - mkdir: /builds/slave/test/properties 15:09:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:09:02 INFO - Writing to file /builds/slave/test/properties/build_url 15:09:02 INFO - Contents: 15:09:02 INFO - build_url:https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:09:02 INFO - Running post-action listener: _resource_record_post_action 15:09:02 INFO - Running post-action listener: set_extra_try_arguments 15:09:02 INFO - [mozharness: 2016-08-31 22:09:02.722712Z] Finished download-and-extract step (success) 15:09:02 INFO - [mozharness: 2016-08-31 22:09:02.722838Z] Running populate-webroot step. 15:09:02 INFO - Running pre-action listener: _resource_record_pre_action 15:09:02 INFO - Running main action method: populate_webroot 15:09:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:09:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:09:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:09:02 INFO - '--tppagecycles', 15:09:02 INFO - '7'], 15:09:02 INFO - 'tests': ['tsvgm']}}, 15:09:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:09:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:09:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:09:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:09:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['damp', 'tps']}, 15:09:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'tests': ['damp', 'tps']}, 15:09:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['dromaeo_dom']}, 15:09:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:09:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['basic_compositor_video']}, 15:09:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:09:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['a11yr', 15:09:02 INFO - 'ts_paint', 15:09:02 INFO - 'tpaint', 15:09:02 INFO - 'sessionrestore', 15:09:02 INFO - 'sessionrestore_no_auto_restore', 15:09:02 INFO - 'tabpaint']}, 15:09:02 INFO - 'other-e10s': {'tests': ['a11yr', 15:09:02 INFO - 'ts_paint', 15:09:02 INFO - 'tpaint', 15:09:02 INFO - 'sessionrestore', 15:09:02 INFO - 'sessionrestore_no_auto_restore', 15:09:02 INFO - 'tabpaint']}, 15:09:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:09:02 INFO - 'ts_paint', 15:09:02 INFO - 'tpaint', 15:09:02 INFO - 'sessionrestore', 15:09:02 INFO - 'sessionrestore_no_auto_restore', 15:09:02 INFO - 'tabpaint']}, 15:09:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:09:02 INFO - 'ts_paint', 15:09:02 INFO - 'tpaint', 15:09:02 INFO - 'sessionrestore', 15:09:02 INFO - 'sessionrestore_no_auto_restore', 15:09:02 INFO - 'tabpaint']}, 15:09:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['a11yr', 15:09:02 INFO - 'ts_paint', 15:09:02 INFO - 'tpaint', 15:09:02 INFO - 'sessionrestore', 15:09:02 INFO - 'sessionrestore_no_auto_restore', 15:09:02 INFO - 'tabpaint']}, 15:09:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['a11yr', 15:09:02 INFO - 'ts_paint', 15:09:02 INFO - 'tpaint', 15:09:02 INFO - 'sessionrestore', 15:09:02 INFO - 'sessionrestore_no_auto_restore', 15:09:02 INFO - 'tabpaint']}, 15:09:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['tsvgx', 15:09:02 INFO - 'tsvgr_opacity', 15:09:02 INFO - 'tart', 15:09:02 INFO - 'tscrollx', 15:09:02 INFO - 'cart']}, 15:09:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:09:02 INFO - 'tsvgr_opacity', 15:09:02 INFO - 'tart', 15:09:02 INFO - 'tscrollx', 15:09:02 INFO - 'cart']}, 15:09:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'talos_options': ['--disable-e10s'], 15:09:02 INFO - 'tests': ['tp5o']}, 15:09:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'tests': ['tp5o']}, 15:09:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'talos_options': ['--disable-e10s', 15:09:02 INFO - '--xperf_path', 15:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:02 INFO - 'tests': ['tp5n']}, 15:09:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:09:02 INFO - 'talos_options': ['--xperf_path', 15:09:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:02 INFO - 'tests': ['tp5n']}}, 15:09:02 INFO - 'talos.zip': {'path': '', 15:09:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:09:02 INFO - Running post-action listener: _resource_record_post_action 15:09:02 INFO - [mozharness: 2016-08-31 22:09:02.741696Z] Finished populate-webroot step (success) 15:09:02 INFO - [mozharness: 2016-08-31 22:09:02.741807Z] Running create-virtualenv step. 15:09:02 INFO - Running pre-action listener: _resource_record_pre_action 15:09:02 INFO - Running main action method: create_virtualenv 15:09:02 INFO - Creating virtualenv /builds/slave/test/build/venv 15:09:02 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 15:09:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:09:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:09:02 INFO - Using real prefix '/tools/python27' 15:09:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:09:03 INFO - Installing distribute.............................................................................................................................................................................................done. 15:09:06 INFO - Installing pip.................done. 15:09:06 INFO - Return code: 0 15:09:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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')]} 15:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09: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', 'pip>=1.5'] in /builds/slave/test/build 15:09: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 pip>=1.5 15:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:06 INFO - 'HOME': '/Users/cltbld', 15:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:06 INFO - 'LOGNAME': 'cltbld', 15:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:06 INFO - 'MOZ_NO_REMOTE': '1', 15:09:06 INFO - 'NO_EM_RESTART': '1', 15:09:06 INFO - 'PAGER': '/bin/cat', 15:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:06 INFO - 'PWD': '/builds/slave/test', 15:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:06 INFO - 'SHELL': '/bin/bash', 15:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:06 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:06 INFO - 'USER': 'cltbld', 15:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:06 INFO - 'XPC_FLAGS': '0x0', 15:09:06 INFO - 'XPC_SERVICE_NAME': '0', 15:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:06 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 15:09:06 INFO - Cleaning up... 15:09:06 INFO - Return code: 0 15:09:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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')]} 15:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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', '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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:09: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 psutil>=3.1.1 15:09:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:06 INFO - 'HOME': '/Users/cltbld', 15:09:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:06 INFO - 'LOGNAME': 'cltbld', 15:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:06 INFO - 'MOZ_NO_REMOTE': '1', 15:09:06 INFO - 'NO_EM_RESTART': '1', 15:09:06 INFO - 'PAGER': '/bin/cat', 15:09:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:06 INFO - 'PWD': '/builds/slave/test', 15:09:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:06 INFO - 'SHELL': '/bin/bash', 15:09:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:06 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:06 INFO - 'USER': 'cltbld', 15:09:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:06 INFO - 'XPC_FLAGS': '0x0', 15:09:06 INFO - 'XPC_SERVICE_NAME': '0', 15:09:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:06 INFO - Downloading/unpacking psutil>=3.1.1 15:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:09:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:09 INFO - Installing collected packages: psutil 15:09:09 INFO - Running setup.py install for psutil 15:09:09 INFO - building 'psutil._psutil_osx' extension 15:09:09 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 15:09:10 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 15:09:10 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 15:09:10 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 15:09:10 INFO - building 'psutil._psutil_posix' extension 15:09:10 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 15:09:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:09:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:09:10 INFO - ^ 15:09:10 INFO - 1 warning generated. 15:09:10 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 15:09:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:10 INFO - Successfully installed psutil 15:09:10 INFO - Cleaning up... 15:09:10 INFO - Return code: 0 15:09:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:09:10 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')]} 15:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:10 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')]} 15:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:10 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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:10 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 15:09:10 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 15:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:10 INFO - 'HOME': '/Users/cltbld', 15:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:10 INFO - 'LOGNAME': 'cltbld', 15:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:10 INFO - 'MOZ_NO_REMOTE': '1', 15:09:10 INFO - 'NO_EM_RESTART': '1', 15:09:10 INFO - 'PAGER': '/bin/cat', 15:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:10 INFO - 'PWD': '/builds/slave/test', 15:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:10 INFO - 'SHELL': '/bin/bash', 15:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:10 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:10 INFO - 'USER': 'cltbld', 15:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:10 INFO - 'XPC_FLAGS': '0x0', 15:09:10 INFO - 'XPC_SERVICE_NAME': '0', 15:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:09:10 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 15:09:10 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 15:09:10 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 15:09:10 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 15:09:10 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 15:09:10 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 15:09:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:09:13 INFO - Installing collected packages: mozsystemmonitor 15:09:13 INFO - Running setup.py install for mozsystemmonitor 15:09:13 INFO - Successfully installed mozsystemmonitor 15:09:13 INFO - Cleaning up... 15:09:13 INFO - Return code: 0 15:09:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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')]} 15:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:13 INFO - 'HOME': '/Users/cltbld', 15:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:13 INFO - 'LOGNAME': 'cltbld', 15:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:13 INFO - 'MOZ_NO_REMOTE': '1', 15:09:13 INFO - 'NO_EM_RESTART': '1', 15:09:13 INFO - 'PAGER': '/bin/cat', 15:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:13 INFO - 'PWD': '/builds/slave/test', 15:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:13 INFO - 'SHELL': '/bin/bash', 15:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:13 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:13 INFO - 'USER': 'cltbld', 15:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:13 INFO - 'XPC_FLAGS': '0x0', 15:09:13 INFO - 'XPC_SERVICE_NAME': '0', 15:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:14 INFO - Downloading/unpacking jsonschema==2.5.1 15:09:14 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 15:09:14 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 15:09:14 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 15:09:14 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 15:09:14 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 15:09:14 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 15:09:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:09:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:16 INFO - Downloading functools32-3.2.3-2.tar.gz 15:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:09:16 INFO - warning: no files found matching '*.txt' 15:09:16 INFO - no previously-included directories found matching 'build' 15:09:16 INFO - no previously-included directories found matching 'dist' 15:09:16 INFO - no previously-included directories found matching '.git*' 15:09:16 INFO - Installing collected packages: jsonschema, functools32 15:09:16 INFO - Running setup.py install for functools32 15:09:17 INFO - warning: no files found matching '*.txt' 15:09:17 INFO - no previously-included directories found matching 'build' 15:09:17 INFO - no previously-included directories found matching 'dist' 15:09:17 INFO - no previously-included directories found matching '.git*' 15:09:17 INFO - Successfully installed jsonschema functools32 15:09:17 INFO - Cleaning up... 15:09:17 INFO - Return code: 0 15:09:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:17 INFO - 'HOME': '/Users/cltbld', 15:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:17 INFO - 'LOGNAME': 'cltbld', 15:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:17 INFO - 'MOZ_NO_REMOTE': '1', 15:09:17 INFO - 'NO_EM_RESTART': '1', 15:09:17 INFO - 'PAGER': '/bin/cat', 15:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:17 INFO - 'PWD': '/builds/slave/test', 15:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:17 INFO - 'SHELL': '/bin/bash', 15:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:17 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:17 INFO - 'USER': 'cltbld', 15:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:17 INFO - 'XPC_FLAGS': '0x0', 15:09:17 INFO - 'XPC_SERVICE_NAME': '0', 15:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:09:17 INFO - Cleaning up... 15:09:17 INFO - Return code: 0 15:09:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:17 INFO - 'HOME': '/Users/cltbld', 15:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:17 INFO - 'LOGNAME': 'cltbld', 15:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:17 INFO - 'MOZ_NO_REMOTE': '1', 15:09:17 INFO - 'NO_EM_RESTART': '1', 15:09:17 INFO - 'PAGER': '/bin/cat', 15:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:17 INFO - 'PWD': '/builds/slave/test', 15:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:17 INFO - 'SHELL': '/bin/bash', 15:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:17 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:17 INFO - 'USER': 'cltbld', 15:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:17 INFO - 'XPC_FLAGS': '0x0', 15:09:17 INFO - 'XPC_SERVICE_NAME': '0', 15:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:09:17 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 15:09:17 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 15:09:17 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 15:09:17 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 15:09:17 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 15:09:17 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 15:09:20 INFO - Downloading blobuploader-1.2.4.tar.gz 15:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:09:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:09:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 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 15:09:20 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 15:09:21 INFO - Downloading docopt-0.6.1.tar.gz 15:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:09:21 INFO - Installing collected packages: blobuploader, requests, docopt 15:09:21 INFO - Running setup.py install for blobuploader 15:09:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:09:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:09:21 INFO - Running setup.py install for requests 15:09:22 INFO - Running setup.py install for docopt 15:09:22 INFO - Successfully installed blobuploader requests docopt 15:09:22 INFO - Cleaning up... 15:09:22 INFO - Return code: 0 15:09:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09: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')]} 15:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:22 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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:22 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 15:09:22 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 15:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:22 INFO - 'HOME': '/Users/cltbld', 15:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:22 INFO - 'LOGNAME': 'cltbld', 15:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:22 INFO - 'MOZ_NO_REMOTE': '1', 15:09:22 INFO - 'NO_EM_RESTART': '1', 15:09:22 INFO - 'PAGER': '/bin/cat', 15:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:22 INFO - 'PWD': '/builds/slave/test', 15:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:22 INFO - 'SHELL': '/bin/bash', 15:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:22 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:22 INFO - 'USER': 'cltbld', 15:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:22 INFO - 'XPC_FLAGS': '0x0', 15:09:22 INFO - 'XPC_SERVICE_NAME': '0', 15:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:22 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-LwsPxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:22 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-RNN8_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:22 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-epKACZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:22 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-xMDkia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:22 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-FtzMfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-NCgxty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-y3RCa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-cVj0VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ge22HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ANuzle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-cGY77i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-wgMcc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-xeNlES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:23 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-vEGZ8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:24 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-74aYne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:24 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-H8GAzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:24 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-YXj45b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:09:24 INFO - Running setup.py install for manifestparser 15:09:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:09:24 INFO - Running setup.py install for mozcrash 15:09:24 INFO - Running setup.py install for mozdebug 15:09:24 INFO - Running setup.py install for mozdevice 15:09:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:09:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:09:24 INFO - Running setup.py install for mozfile 15:09:25 INFO - Running setup.py install for mozhttpd 15:09:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Running setup.py install for mozinfo 15:09:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Running setup.py install for mozInstall 15:09:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Running setup.py install for mozleak 15:09:25 INFO - Running setup.py install for mozlog 15:09:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Running setup.py install for moznetwork 15:09:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:09:25 INFO - Running setup.py install for mozprocess 15:09:26 INFO - Running setup.py install for mozprofile 15:09:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Running setup.py install for mozrunner 15:09:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Running setup.py install for mozscreenshot 15:09:26 INFO - Running setup.py install for moztest 15:09:26 INFO - Running setup.py install for mozversion 15:09:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:09:26 INFO - Cleaning up... 15:09:26 INFO - Return code: 0 15:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:26 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')]} 15:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:26 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')]} 15:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:26 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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:26 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 15:09:26 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 15:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:26 INFO - 'HOME': '/Users/cltbld', 15:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:26 INFO - 'LOGNAME': 'cltbld', 15:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:26 INFO - 'MOZ_NO_REMOTE': '1', 15:09:26 INFO - 'NO_EM_RESTART': '1', 15:09:26 INFO - 'PAGER': '/bin/cat', 15:09:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:26 INFO - 'PWD': '/builds/slave/test', 15:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:26 INFO - 'SHELL': '/bin/bash', 15:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:26 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:26 INFO - 'USER': 'cltbld', 15:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:26 INFO - 'XPC_FLAGS': '0x0', 15:09:26 INFO - 'XPC_SERVICE_NAME': '0', 15:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-2279Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9psxua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-smXuYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-uHi_Gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-kzv5EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-7osak0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-mqScpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:27 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)) 15:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:27 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-5u92Ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-4OeUBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-szkW6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-rKS1uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-LjJ8na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VdajwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-pLxIxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-_m0qtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-QjTNMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:28 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)) 15:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-y8mtS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:28 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)) 15:09:28 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)) 15:09:28 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)) 15:09:28 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)) 15:09:28 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)) 15:09:28 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)) 15:09:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:31 INFO - Downloading blessings-1.6.tar.gz 15:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:09:31 INFO - Installing collected packages: blessings 15:09:31 INFO - Running setup.py install for blessings 15:09:31 INFO - Successfully installed blessings 15:09:31 INFO - Cleaning up... 15:09:31 INFO - Return code: 0 15:09:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:32 INFO - Reading from file tmpfile_stdout 15:09:32 INFO - Current package versions: 15:09:32 INFO - blessings == 1.6 15:09:32 INFO - blobuploader == 1.2.4 15:09:32 INFO - docopt == 0.6.1 15:09:32 INFO - functools32 == 3.2.3-2 15:09:32 INFO - jsonschema == 2.5.1 15:09:32 INFO - manifestparser == 1.1 15:09:32 INFO - mozInstall == 1.12 15:09:32 INFO - mozcrash == 0.17 15:09:32 INFO - mozdebug == 0.1 15:09:32 INFO - mozdevice == 0.48 15:09:32 INFO - mozfile == 1.2 15:09:32 INFO - mozhttpd == 0.7 15:09:32 INFO - mozinfo == 0.9 15:09:32 INFO - mozleak == 0.1 15:09:32 INFO - mozlog == 3.3 15:09:32 INFO - moznetwork == 0.27 15:09:32 INFO - mozprocess == 0.23 15:09:32 INFO - mozprofile == 0.28 15:09:32 INFO - mozrunner == 6.12 15:09:32 INFO - mozscreenshot == 0.1 15:09:32 INFO - mozsystemmonitor == 0.3 15:09:32 INFO - moztest == 0.7 15:09:32 INFO - mozversion == 1.4 15:09:32 INFO - psutil == 3.1.1 15:09:32 INFO - requests == 1.2.3 15:09:32 INFO - wsgiref == 0.1.2 15:09:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:32 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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:32 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 15:09:32 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 15:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:32 INFO - 'HOME': '/Users/cltbld', 15:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:32 INFO - 'LOGNAME': 'cltbld', 15:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:32 INFO - 'MOZ_NO_REMOTE': '1', 15:09:32 INFO - 'NO_EM_RESTART': '1', 15:09:32 INFO - 'PAGER': '/bin/cat', 15:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:32 INFO - 'PWD': '/builds/slave/test', 15:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:32 INFO - 'SHELL': '/bin/bash', 15:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:32 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:32 INFO - 'USER': 'cltbld', 15:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:32 INFO - 'XPC_FLAGS': '0x0', 15:09:32 INFO - 'XPC_SERVICE_NAME': '0', 15:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 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)) 15:09:32 INFO - Cleaning up... 15:09:32 INFO - Return code: 0 15:09:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:33 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')]} 15:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:09:33 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 0x1084d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa9bc867b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, '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.G1wE9OQkvF/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.WCTkURcqPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:09:33 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 15:09:33 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 15:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:33 INFO - 'HOME': '/Users/cltbld', 15:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:33 INFO - 'LOGNAME': 'cltbld', 15:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:33 INFO - 'MOZ_NO_REMOTE': '1', 15:09:33 INFO - 'NO_EM_RESTART': '1', 15:09:33 INFO - 'PAGER': '/bin/cat', 15:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:33 INFO - 'PWD': '/builds/slave/test', 15:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:33 INFO - 'SHELL': '/bin/bash', 15:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:09:33 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:33 INFO - 'USER': 'cltbld', 15:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:33 INFO - 'XPC_FLAGS': '0x0', 15:09:33 INFO - 'XPC_SERVICE_NAME': '0', 15:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:09:33 INFO - Cleaning up... 15:09:33 INFO - Return code: 0 15:09:33 INFO - Running post-action listener: _resource_record_post_action 15:09:33 INFO - Running post-action listener: _start_resource_monitoring 15:09:33 INFO - Starting resource monitoring. 15:09:33 INFO - [mozharness: 2016-08-31 22:09:33.314217Z] Finished create-virtualenv step (success) 15:09:33 INFO - [mozharness: 2016-08-31 22:09:33.315215Z] Running install step. 15:09:33 INFO - Running pre-action listener: _resource_record_pre_action 15:09:33 INFO - Running main action method: install 15:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:33 INFO - Reading from file tmpfile_stdout 15:09:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:09:33 INFO - Reading from file tmpfile_stdout 15:09:33 INFO - Output received: 15:09:33 INFO - Usage: mozinstall [options] installer 15:09:33 INFO - Options: 15:09:33 INFO - -h, --help show this help message and exit 15:09:33 INFO - -d DEST, --destination=DEST 15:09:33 INFO - Directory to install application into. [default: 15:09:33 INFO - "/builds/slave/test"] 15:09:33 INFO - --app=APP Application being installed. [default: firefox] 15:09:33 INFO - mkdir: /builds/slave/test/build/application 15:09:33 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'] 15:09:33 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 15:10:07 INFO - Reading from file tmpfile_stdout 15:10:07 INFO - Output received: 15:10:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:10:07 INFO - Running post-action listener: _resource_record_post_action 15:10:07 INFO - [mozharness: 2016-08-31 22:10:07.909091Z] Finished install step (success) 15:10:07 INFO - [mozharness: 2016-08-31 22:10:07.909267Z] Running run-tests step. 15:10:07 INFO - Running pre-action listener: _resource_record_pre_action 15:10:07 INFO - Running main action method: run_tests 15:10:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:10:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:10:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:10:07 INFO - 2016-08-31 15:10:07.923 screenresolution[1940:11979] starting screenresolution argv=screenresolution get 15:10:07 INFO - 2016-08-31 15:10:07.926 screenresolution[1940:11979] Display 0: 1600x1200x32@60 15:10:07 INFO - 2016-08-31 15:10:07.932 screenresolution[1941:11981] starting screenresolution argv=screenresolution list 15:10:07 INFO - Available Modes on Display 0 15:10:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:10:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:10:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:10:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:10:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:10:08 INFO - Intel Iris: 15:10:08 INFO - Chipset Model: Intel Iris 15:10:08 INFO - Type: GPU 15:10:08 INFO - Bus: Built-In 15:10:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:10:08 INFO - Vendor: Intel (0x8086) 15:10:08 INFO - Device ID: 0x0a2e 15:10:08 INFO - Revision ID: 0x0009 15:10:08 INFO - Displays: 15:10:08 INFO - Display: 15:10:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:10:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:10:08 INFO - Display Serial Number: 1600x1200 60 15:10:08 INFO - Main Display: Yes 15:10:08 INFO - Mirror: Off 15:10:08 INFO - Online: Yes 15:10:08 INFO - Rotation: Supported 15:10:08 INFO - Return code: 0 15:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:10:08 INFO - Python 2.7.3 15:10:08 INFO - Return code: 0 15:10:08 INFO - grabbing minidump binary from tooltool 15:10:08 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')]} 15:10:08 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 0x10843cd30>, '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 0x1081a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108434b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:10:08 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 15:10:08 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 15:10:08 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 15:10:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:10:08 INFO - Return code: 0 15:10:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:10:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:10:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:10:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:10:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 15:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 15:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G1wE9OQkvF/Render', 15:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:08 INFO - 'HOME': '/Users/cltbld', 15:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:08 INFO - 'LOGNAME': 'cltbld', 15:10:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:10:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:08 INFO - 'MOZ_NO_REMOTE': '1', 15:10:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:10:08 INFO - 'NO_EM_RESTART': '1', 15:10:08 INFO - 'PAGER': '/bin/cat', 15:10:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:08 INFO - 'PWD': '/builds/slave/test', 15:10:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:08 INFO - 'SHELL': '/bin/bash', 15:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WCTkURcqPj/Listeners', 15:10:08 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:08 INFO - 'USER': 'cltbld', 15:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:08 INFO - 'XPC_FLAGS': '0x0', 15:10:08 INFO - 'XPC_SERVICE_NAME': '0', 15:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 15:10:08 INFO - mozversion application_buildid: 20160831125821 15:10:08 INFO - mozversion application_changeset: 7eac2acb63a5b28940bb8801e02ccab5c83c078c 15:10:08 INFO - mozversion application_display_name: Nightly 15:10:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:10:08 INFO - mozversion application_name: Firefox 15:10:08 INFO - mozversion application_remotingname: firefox 15:10:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:10:08 INFO - mozversion application_vendor: Mozilla 15:10:08 INFO - mozversion application_version: 51.0a1 15:10:08 INFO - mozversion platform_buildid: 20160831125821 15:10:08 INFO - mozversion platform_changeset: 7eac2acb63a5b28940bb8801e02ccab5c83c078c 15:10:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:10:08 INFO - mozversion platform_version: 51.0a1 15:10:08 INFO - using testdate: 1472681408 15:10:08 INFO - actual date: 1472681408 15:10:08 INFO - starting webserver on 'localhost:50515' 15:10:08 INFO - SUITE-START | Running 2 tests 15:10:08 INFO - TEST-START | dromaeo_css 15:10:08 INFO - operating with platform_type : mac_ 15:10:08 INFO - Initialising browser for dromaeo_css test... 15:10:08 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpZ5rUKs/profile http://localhost:50515/getInfo.html) 15:10:10 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 15:10:10 INFO - PROCESS | 1951 | colorDepth:24 15:10:10 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 15:10:10 INFO - PROCESS | 1951 | __metrics 15:10:10 INFO - PROCESS | 1951 | 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" 15:10:10 INFO - PROCESS | 1951 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:10:11 INFO - TEST-INFO | 1951: exit 0 15:10:11 INFO - Browser initialized. 15:10:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:10:11 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpZ5rUKs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:10:23 INFO - PROCESS | 1953 | RSS: Main: 234377216 15:10:23 INFO - PROCESS | 1953 | 15:11:14 INFO - PROCESS | 1953 | 1472681474465 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] 15:13:27 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50515/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50515/tests/dromaeo/cssquery-ext.html) 15:13:28 INFO - PROCESS | 1953 | RSS: Main: 369434624 15:13:28 INFO - PROCESS | 1953 | 15:16:31 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50515/tests/dromaeo/cssquery-ext.html (next: http://localhost:50515/tests/dromaeo/cssquery-jquery.html) 15:16:32 INFO - PROCESS | 1953 | RSS: Main: 371744768 15:16:32 INFO - PROCESS | 1953 | 15:19:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50515/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50515/tests/dromaeo/cssquery-mootools.html) 15:19:35 INFO - PROCESS | 1953 | RSS: Main: 333873152 15:19:35 INFO - PROCESS | 1953 | 15:22:37 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50515/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50515/tests/dromaeo/cssquery-prototype.html) 15:22:38 INFO - PROCESS | 1953 | RSS: Main: 333049856 15:22:38 INFO - PROCESS | 1953 | 15:25:41 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50515/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50515/tests/dromaeo/cssquery-yui.html) 15:25:42 INFO - PROCESS | 1953 | RSS: Main: 337018880 15:25:42 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50515/tests/dromaeo/cssquery-yui.html (next: http://localhost:50515/tests/dromaeo/cssquery-dojo.html) 15:28:45 INFO - PROCESS | 1953 | RSS: Main: 485576704 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | __start_tp_report 15:28:45 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 15:28:45 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 15:28:45 INFO - PROCESS | 1953 | |i|pagename|runs| 15:28:45 INFO - PROCESS | 1953 | |0;dojo.html;3298.0270820108067;3304.785188962716;3338.782979106358;3359.865605375785;3384.509246159307;5628.831135065944;5693;5699.857503562413;5755.39568345324;5757.654540727559;5761.1358296255585;5793.942060579393;5812.4768770810515;5898.056310990242;5931.169636250917;5113.795448182068;5185.429602743695;5232.642339557437;5276.630635855492;5362.383325917531;5334.466553344654;5354.303940487731;5359.919601205985;5367.490088441592;5372.274742909716;5168.224766285088;5205.29728486657;5230.320058392384;5242.68543887368;5300.734963251842;5195.766190521435;5214.165733482625;5232.895342093175;5238.79044838206;5254.65844720092;5167.741612919376;5189.065968125736;5211.2443695663915;5211.348581427322;5226.268322434862;5669.489745922846;5703.800366987177;5718.999175144333;5795.420457954213;5814.156947242668;6139.201903752528;6197.659128747917;6211.937880621181;6227.283862355842;6231.065340198961;4795.904081918342;6108.175396321484;6132.080177995968;6169.074638804215;6171.413715697002;5846.269216347957;5861.208736820475;5868.4424979626865;5870.090136028923;5875.9316045927835;6099.505621122853;6193.938060619426;6194.566380353423;6200.658963757037;6207.224096987877;6092.482139018233;6129.203203583595;6142.877142457215;6172.629642221481;6189.442950134419;6165;6166.660833654192;6172.845678857974;6183.690815459299;6191.442770150708;6179.62922224668;6188.721507532172;6193.628382297076;6196.008638617799;6202.4417802398;6104.216637118914;6122.265328160649;6146.678464130234;6173.722182501709;6187.969060154761;5009.724465154379;5010.924836127461;5012;5014.52362025607;5025.120603894304;4479.686421950498;4489.6408287336935;4489.708168969007;4509.526499717548;4558.840440584564;5608.523275521627;5669.40471250521;5777;5813.157092221604;5870.295564532198;5780.479756821906;5886.999210134288;6144.2488890665945;6175.69121543921;6188.597741146811;5637.126245431965;5729.169270455844;5744.684042377626;5756.481916627524;5760.222369979999;5381.327334083239;5432.320959880015;5441.891162176814;5485.149801780651;5486.945130548724;6174.567780255339;6179.969100154471;6218.253809542884;6229.719662615103;6312.842178945563;6156.538259630635;6167.629942203482;6175.59858609189;6178.876422471615;6203.658798766111;6250.1874756281395;6277.780277690259;6301.873962520815;6324.051392291193;6328.019157030668;6073.392660733891;6095.63426194412;6105.520399749632;6144.218176728698;6164.291106522807;6166.075088736546;6166.075088736726;6177.166082578974;6179.567430280017;6197.938020619736;6103.786367477117;6115.694215289129;6127.448529632355;6127.969360153171;6146.385361463817;6021.638701677914;6088.299845517645;6098.91636565712;6110.175126358063;6113.449789518964;4683.391159149289;6062;6062.969685151546;6098.420650038239;6109.602875813058;6182.505399568114;6201.3178550358525;6202.937970620236;6205.503559715302;6205.596636218631;5862.970685146547;5969.313528944227;5972.731227094878;6046.879062418815;6056.242969628796 15:28:45 INFO - PROCESS | 1953 | |1;ext.html;458.15241802665076;494.21173228700235;509.8903735696824;517.017666433776;521.0594876248371;29903.252418689517;40330;41845.7907710461;41847;41878.743637690844;42606.147877042444;43201.56798432015;43341;43429;43459.78270108645;8894.332925030616;9777.071178238073;9958.402495850272;9959.850602240973;9967.302288839785;8888.73333799987;11806.055515558735;12130.757384852297;12152.696182595417;12291.93854030733;12144.453499592539;12211.389430528483;12226.449809758538;12228.960538354251;12260.69348266296;12074.154809163361;12154.574589889357;12173.20874143178;12174.634760957097;12180.81728774069;11944.581939632159;12016;12027.819582706266;12030.939845300718;12062.035037197003;38654.420183697264;38968.41547376791;39009.60990390117;39018.41472377917;39043.41434878479;3188.4260833050043;3317.2370354818286;3775.7734535927657;4007.3788562772816;4029.5567487576345;29355.825766969294;29731.702682973326;30242.092737217918;30694.53958190629;30713;3266.5590145330316;3287.3260981498734;3309.3546758382126;3336.349411864687;3342.28140949697;446752;446838;447162;448069;459036.70481647714;60210;60652;60760.83532899753;60768.69615651982;60781.69609151926;63697.363026369145;63728.68135659292;63778;63796.36203637904;63817;16495.34018639241;16705.498835034967;16714.331426742796;16724.83275167257;16745.413910513074;11546.538138474534;11749;11763.235389699814;12774.872251277555;12857.742845143044;14295.428182872776;14621;14643.437398455228;15936.601584960237;16073.919630402008;4708.204386275852;5359.678419294777;6222.191115155001;6236.501079913596;6236.688165591793;863.6372723456163;903.5888670654837;941.5857022909897;945.9006804285589;1017.9185665146771;27970.720292797218;28109.859450703025;29583.556246656317;30262.09213723592;30331.545026824617;1010.1211945607369;1094.3871431998107;1114.57089020726;1117.2626066795876;1211.1098342718094;14795.630109247355;15188.164650944304;15328.463503777537;15502;15920.363185472683;575525;581668;584566;585039;586453;57220.71389643025;57380;57469.71265143647;57512.71243643755;57532;1216.531635320408;1334.3128288931402;1371.060823336018;1383.8270216222973;1397.0639671420172;26627;29110.41779164447;30577.54133688041;30755.538666919565;30762;55187.44812551823;55771.44228557825;55842;55931.72034139803;56016.15975760285;537759;540581;541417;542838.2858085685;543748;24559;25285.494290113726;25358.87320563459;25845.612315814902;25873.870630647478;44714.55285447104;44993.1001379964;45088.54911450844;45510.86222844323;46240;31798.52302215515;36895;37182.25635487221;37721.434178486794;38134.61865381311;36430.453543196345;36462.74803444403;37828.24343513169;38048;38066.42900356552;32325.676743232267;37268.440973385965;37660;38112.428313575874;38132.428013579265;32856.67143328632;33302.66697332996;33801;34384.968450946515;34550.654493455746;23020.884895575415;24610.570097381926;25437.10970116037;25488.617670734584;25828 15:28:45 INFO - PROCESS | 1953 | |2;jquery.html;348.48424331988673;348.7976973355454;376.1330134041036;382.26986455869286;403.43720509888703;15070.321835517401;16558.83441165588;20100.09549570269;20802.375928722126;20902.99999999998;20042.29851955182;20638.89680551599;20647.690284645705;20655.586888262227;20883.477913052182;5265.394479634833;5316.73416329184;6094.055421409688;6189.381061893797;6198.814035578917;6076.908846367308;6189.319174890758;6192.845178870519;6199.318075011745;6206.100115483275;5900.262467191601;6080.604760690563;6137.601055931373;6138.324784273748;6160.876782464348;5835.941640583582;6065.666388348639;6108.96945515274;6120.173776540155;6128.816135515964;5201.401838788512;5268.631195816294;5275.947240527622;5279.63042587019;5280.445553216934;25708.10021649252;26059.21822345333;26093.347666308306;26229.344266393302;26539.734602654113;2491.314888405685;3120.6723294054023;3193.409219294415;3216.2281052547455;3249.8700051997894;14902.925485372612;15639;15891.967022143524;15994.360225590963;16262.43081492154;1675.8858645051712;1776.4670598820303;1780.5993651428455;1917.4085509027473;1935.4967708395777;107019;107168;107585.46207268913;110118;110974.51556776116;10571.101456376295;10583.364998099985;10709.892901071045;11023.503942322615;11068.944655276833;11719.531218751325;13834.446622135001;13842.238676872868;13849.584512464642;13887.861121388656;7415.814604634819;8474;8531.82936341282;8605.870911936327;8649.654013839501;8673.392862499692;8681.2186903179;8681.479111253346;8835.337349698799;8871.822563548692;5525.475079867406;6682.732690692318;6683.097781799492;6698.229703584053;6717.361850624183;1630.7390817469147;1809.6109336492718;1860.6836837737617;1868.7292641003976;1903.1055403960117;743.5352904434728;842.7556008757392;910.9498977556166;956.7512446763892;1008.2708155513329;19147.659663823677;19265.724826779482;20386.28647997313;21532.78467215339;21557.137714491557;885.1679421343916;991.3679181772405;1004.5830980143305;1018.3737001743874;1066.914646828252;10093.293469457214;11019.944900275448;11574.594889179007;11590.826137607943;11668.299902005738;546728;550592;555206;561306;561381;79117.60441197873;109303.45348273207;109553.45223273992;109793.90206097996;112472;960.9375390599589;973.4694591447678;997.4514017290461;997.9750506237402;1003.418017549834;18999.620007600046;19985.60028799445;20052;20101.899490503038;20172.899135504227;44089.795510225005;44913.05956344734;46955;48007;49288;67731;67821.3217867815;68568.3143168562;69699.95450068152;76444.00451923076;7608;10971.670849874517;11010.834837477605;11414.315141091562;11583.710407239887;11757.647270581896;12580.433880475584;13150.539731109366;13167.144135631153;13266;10805.783884322427;10975;11004.39475828837;11051.944740276247;11185;10415.53130109162;10861.782764344827;10901.509432075418;10983.890161098287;11328.773424531628;10692.89307106919;11056.668299951014;11269;11368.488418021043;11456.312621242752;9514.23886089125;11554.59558915434;11572.94213528927;11760;11837;10240.436775977392;10399.896001039893;10441.269111162146;10492.790144197226;10940.671779846618 15:28:45 INFO - PROCESS | 1953 | |3;mootools.html;1527.0684882221847;1557.8987365821222;1776.3782676063372;1908.9809101908995;1941.592265624219;8711.91288087119;9252.53737313135;9259.76850578735;9261.444313341195;9263.675771348004;7944.721934732285;8180.877286840702;8592.355573331994;8820.426672266298;8973.91026089739;2380.5476959377747;2400.759924007603;2405.603075492542;2409.012304954969;2416.8549887006834;2372.7271363793125;2389.474315650554;2418.9758102419014;2423.285130886393;2424.806015518754;2378.3816207786012;2386.1529157149134;2396.3769419950763;2407.7953373963146;2412.49337639096;2366.6095094309417;2377.120465427794;2384.6899903012672;2394.126143957452;2397.2568503763887;2118.4915620251027;2363.6927199463958;2367.597508423572;2371.9407014824596;2376.916807911732;8941.239994600466;8976.775580610472;9041.276697864221;9062.682806101755;9069.274458043341;5261.631685781997;5420.756065977041;5422.864428389283;5434.130539113723;5438.184272359178;3499.5100685903994;3507.57909050913;3544.220271540257;3573.624769399202;3574.982125089358;1042.3016578892161;1042.4475028235047;1043.6190790361538;1055.250771951915;1063.7127975446663;11687.181897267195;11831.704207394798;11837.289762614271;11866.52533898643;11874.584389546411;4548.931766023512;4718.4337879454;4816.012717392926;4835.685680430761;4842.249451335048;8736.475811451333;10052.798944021079;10060.496975151213;10097.677534167182;10118.494075296207;5415.675059496364;5419.83740487786;5430.809921652803;5437.809676661298;5443.401225865152;4447.977760111179;4593.311003349524;4810.927836082462;4954.9009019819405;4959.380077490314;3920.5295364555864;3963.286608410456;4019.577944315863;4059.2693315202955;4089.386592011222;1162.8895254950767;1308.483149156078;1310.8361454818148;1315.9736805264033;1336.1715736243398;808.5552945879742;884.2307192742355;899.4513346858411;916.2088117729955;945.385499425379;5786.594938354275;5906.3207731110615;6095.725692343855;6190.442860142519;6245.6252624842655;437.72860826287905;439.63949561360016;440.2295982031445;454.56134829889317;463.0507459707586;3842.3852183650483;3911.6870650347905;4017.779022153751;4023.6781057515327;4030;10618.362898225976;11094;11113.722156946142;11127.27672701288;11157.609483668033;10707.678769636768;10931.234813563133;11114.833277500844;11156.442177891073;11170.832437513443;8003.919960800434;8293.295069919124;8325.417220794607;8411.537365444838;8420.410571260076;5961.672108033927;6034.487058624016;6275.717592708248;6303.46420554258;6306.9684651576445;9917.702468925943;10124;10459.163266938778;10501.002404771534;10535.367877927349;10216.131628811338;10349.844752328872;10511.684649460529;10607.204459665401;10612.416317102634;6676.298988606127;7064.788056358318;7276.1996180419055;7277.417806575567;7908.84182316339;7389.630518474162;8081.636326365446;8156.551389673625;8167.14245004266;8564.48613083217;7596.392288616787;7685.269899359552;7697.961510192413;7827.6868925244635;7860;6292.2134733158355;6420.935790642221;7434.7769566913075;7476.5514069156025;7624.8475030500185;6994.860102798018;7142.500024998408;7151;7159.749408770668;7160.10498687664;4619.884502887455;4950.579200767975;5021.3472248608905;5065.898682026265;5217.765200565907;4945.109880221451;5073.518015788494;5218.217267409767;5269.314989051399;5360.16917377807 15:28:45 INFO - PROCESS | 1953 | |4;prototype.html;296.1751522011199;298.3064375327364;303.9149038269284;309.4615369257493;309.88224474699626;4732.160703577852;4739.478657347693;5493.45065493451;5496.587755918302;5513.448655134494;4836.927446088312;5259.948010397926;5415.377231618365;5429.072679198886;5438.362394238801;3715.1826598148364;3888.2199227552364;4076.1847630473876;4082.6937979651498;4082.7754473503946;3439.0569328329416;3888.475055867464;4086.7139300248996;4122.793860306988;4128.009277773328;3388.6272510023878;3560.3413368526903;3831.63599458051;3886.14504808943;4101;3901.7463864848696;4051.392291156321;4103.528094269167;4111.5815043809835;4147.191297696951;3335.5997280326274;3438.0889064397957;3475.2528206435736;3539.256756081226;3573.4818451324413;4657.812257874254;5071.442141364447;5167.777855522124;5179.249008893727;5188.9740551297;4468.642508599304;4549.454065512126;4670.6497012724185;4672.486026537078;4716.669833111683;5445.215575175254;5578.30271216098;5645.435456454363;5655.462731040545;5674.631148975303;3234.9353012939846;3545.503629491873;3901.9609803902167;3916.5691773904846;3924.13668992824;4765.523447655241;5319.361676598833;5667.5749318800845;5996.400359964013;6022.759089636409;3973.602639736061;3981.9203615927518;3984.5019372578427;3987.1626958338493;3990.940135897964;4059.736117152435;4062.1672557125717;4065.1503565878047;4066.9186616267507;4081.3266560709967;3972.8609498667406;4535.863924082283;4704.059188162381;4717.386739723814;4744.857654270378;5224.268602395668;5642.294713160855;5700.515456186187;5704.201411802351;5734.913976290359;1254.2411840836346;1384.3424373422545;1387.9167249965035;1389.8262717160355;1390.3395886953615;780.1730166024033;780.4029917113413;795.8408318336355;849.762066621347;851.8381507513552;3762.7177961652988;3825.6907774775177;3962.782046987386;4101.651359634405;4502.774861256924;4309.030468144642;4596.218642830727;4741.596964258008;4910.975445122752;4937.185364414903;4029.49631296088;4144.052295421147;4424.867253982386;4462.776861156929;4495.741192466115;2252.549490101986;2402.183257692393;2410.4696966667307;2453.576925383273;2598.753118453744;4993.400791904995;5048.7728052237735;5062.544371006627;5066.340171410039;5067.442581316052;4583.839565615188;4716.792581044922;4788.371953535745;5007.499250075036;5075.593952483792;4056.959430405658;4981.651284410056;4986.626003049786;5078.949210507922;5199.844004679866;3980.920381592294;4787.569118779326;4803.447603525499;4899.260762429429;4904.411470623548;4598.540145985375;4764.499727528698;4799.688020278671;4845.709257444564;4883.853484395474;4358.934615980699;4370.950971766817;4504.977475112603;5028.296038554532;5077.949220507748;3435.5362026126154;3450.8447119880166;3454.6890779829937;3455.844486998041;3503.969066757186;4353.734698975768;4622;4928.771654566265;4940.728259945738;4979.502049794992;4078.184363127327;4193.477478917724;5011.5489605935645;5748.281464816898;5769.423057694197;5671.517920976598;5694.601377903507;5705.3153621564015;5709.286339207599;5793.391693872083;4720.45714742809;4877.024595080927;4907.828226012215;5230.450802665818;5602.803901863578;4769.594584460359;4817.373741413594;5206.453322401245;5723.713814309352;5757.481826635623;5160.322895059196;5197.298364720715;5607.691576963306;5612.410696876933;5685.687287199244 15:28:45 INFO - PROCESS | 1953 | |5;yui.html;699.2797418658781;720.0531301338735;767.838753861689;790.671871173463;816.8447994880972;15863.444779432722;16401.589960250985;16425.589360266;16434;16446.095464749433;14410.325367031419;14423.92788036061;14449.277536123205;14493.5651930442;14760.926195369035;672.0826072411148;692.2696555134331;834.028356964136;836.9246767790897;862.9568521573896;833.5248908122372;847.0216899481109;847.7117779954814;849.3163003781953;850.974470765878;910.544727636182;910.7723069232692;911.2300106410073;911.8768966189546;912.1289168843738;706.9611171385571;736.2894806511688;741.9814504637427;907.8774365460645;909.1771946388496;542.9809956651537;653.2716021635889;679.8912174052128;682.621145264378;686.1423220973783;14793.630159245997;14818.33317500715;14836.18400987956;14857.405703771836;14883.18142502162;13568.93215533916;13656.453741850313;13922.791158132635;13979.860201397958;14064.495688076147;12540.686482837911;12803.679908002281;12847.100702950796;13120.55324152499;13343.266120363376;4488.84289049885;4542.182407166709;4556.658250631182;4735.976320118378;4744.07490539345;106848;109167.90832091657;110337.44831275873;111951.44024279909;113911;10937.507812148473;11004.504797283982;11026.669199924016;11059.944700276446;11265.436728163722;21624.351269461942;21692.783072169394;21696.023678934485;21720.674189887166;22118;7345.19202887682;7617.542947423172;7908.881366779503;7998.280154786097;8084.434089613788;4318.5681431856565;4329.415528903621;4344.500382456058;4346.2828633275785;4459.888502787391;3399.8810041648426;3527.647235276503;3529.6999755020597;3532.947005794915;3535.275268569937;653.6078352988264;665.171861033017;672.5124284893413;673.0409166937193;676.3000294694967;555.2448669809056;572.5820151289582;605.5639939243738;610.9755609775648;620.9472194863487;7038.979347994512;8734.781630459289;8956.149165829236;9061.456312621263;9127.634894604274;550.3780727777586;581.4563383236688;595.6842873277084;612.7824622259034;640.759715106835;3775.3959366501226;4174.068376916613;4351.369051487517;4405.427294451765;4408;109641.03589640972;140320.2983985094;154219;154748;155697;80389;104321.91356172835;108851.91148088576;132241;139203;103566;103989.48005260076;106113.99999999846;106802;107076.4646176764;9685.6610018649;9906.455144967096;9938.155256803253;9948.154406875206;9964.451955142536;43511.347329789445;55457.44542554523;60098.69950650219;62980.68509657423;63449;91057;92201.5389923046;93630;96494;97329.51335243278;12732.535758387903;12850.42173102194;13024.023198260358;13174.275414852274;13544.036114763501;13210.735785284432;13891.166530008231;14863.554093377215;14948.850511494746;15265.3893844245;13735.656608584466;13815.102018368774;13818.447262109403;13837.584872453843;14459.204743739196;13413.530526431528;13639.659008524866;13718.65703357424;13790.931045344709;14239.644008899446;13470.259135747629;13478;13581.932090339486;13706.93146534261;13770.173789572658;10862.820289350086;11074.557017719524;11328.376939268419;11335.489902954221;11549.655144918788;9784.9021509784;11013.00882920541;11135.245003287928;11490.54037838477;11527.250728702607 15:28:45 INFO - PROCESS | 1953 | __end_tp_report 15:28:45 INFO - PROCESS | 1953 | __start_cc_report 15:28:45 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,1417 15:28:45 INFO - PROCESS | 1953 | __end_cc_report 15:28:45 INFO - PROCESS | 1953 | __startTimestamp1472682525681__endTimestamp 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | ------- Summary: start ------- 15:28:45 INFO - PROCESS | 1953 | Number of tests: 6 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | [#0] dojo.html Cycles:165 Average:5736.76 Median:6021.64 stddev:621.78 (10.3%) stddev-sans-first:593.52 15:28:45 INFO - PROCESS | 1953 | Values: 3298.0 3304.8 3338.8 3359.9 3384.5 5628.8 5693.0 5699.9 5755.4 5757.7 5761.1 5793.9 5812.5 5898.1 5931.2 5113.8 5185.4 5232.6 5276.6 5362.4 5334.5 5354.3 5359.9 5367.5 5372.3 5168.2 5205.3 5230.3 5242.7 5300.7 5195.8 5214.2 5232.9 5238.8 5254.7 5167.7 5189.1 5211.2 5211.3 5226.3 5669.5 5703.8 5719.0 5795.4 5814.2 6139.2 6197.7 6211.9 6227.3 6231.1 4795.9 6108.2 6132.1 6169.1 6171.4 5846.3 5861.2 5868.4 5870.1 5875.9 6099.5 6193.9 6194.6 6200.7 6207.2 6092.5 6129.2 6142.9 6172.6 6189.4 6165.0 6166.7 6172.8 6183.7 6191.4 6179.6 6188.7 6193.6 6196.0 6202.4 6104.2 6122.3 6146.7 6173.7 6188.0 5009.7 5010.9 5012.0 5014.5 5025.1 4479.7 4489.6 4489.7 4509.5 4558.8 5608.5 5669.4 5777.0 5813.2 5870.3 5780.5 5887.0 6144.2 6175.7 6188.6 5637.1 5729.2 5744.7 5756.5 5760.2 5381.3 5432.3 5441.9 5485.1 5486.9 6174.6 6180.0 6218.3 6229.7 6312.8 6156.5 6167.6 6175.6 6178.9 6203.7 6250.2 6277.8 6301.9 6324.1 6328.0 6073.4 6095.6 6105.5 6144.2 6164.3 6166.1 6166.1 6177.2 6179.6 6197.9 6103.8 6115.7 6127.4 6128.0 6146.4 6021.6 6088.3 6098.9 6110.2 6113.4 4683.4 6062.0 6063.0 6098.4 6109.6 6182.5 6201.3 6202.9 6205.5 6205.6 5863.0 5969.3 5972.7 6046.9 6056.2 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | [#1] ext.html Cycles:180 Average:66514.75 Median:36446.60 stddev:140551.83 (385.6%) stddev-sans-first:140858.61 15:28:45 INFO - PROCESS | 1953 | Values: 458.2 494.2 509.9 517.0 521.1 29903.3 40330.0 41845.8 41847.0 41878.7 42606.1 43201.6 43341.0 43429.0 43459.8 8894.3 9777.1 9958.4 9959.9 9967.3 8888.7 11806.1 12130.8 12152.7 12291.9 12144.5 12211.4 12226.4 12229.0 12260.7 12074.2 12154.6 12173.2 12174.6 12180.8 11944.6 12016.0 12027.8 12030.9 12062.0 38654.4 38968.4 39009.6 39018.4 39043.4 3188.4 3317.2 3775.8 4007.4 4029.6 29355.8 29731.7 30242.1 30694.5 30713.0 3266.6 3287.3 3309.4 3336.3 3342.3 446752.0 446838.0 447162.0 448069.0 459036.7 60210.0 60652.0 60760.8 60768.7 60781.7 63697.4 63728.7 63778.0 63796.4 63817.0 16495.3 16705.5 16714.3 16724.8 16745.4 11546.5 11749.0 11763.2 12774.9 12857.7 14295.4 14621.0 14643.4 15936.6 16073.9 4708.2 5359.7 6222.2 6236.5 6236.7 863.6 903.6 941.6 945.9 1017.9 27970.7 28109.9 29583.6 30262.1 30331.5 1010.1 1094.4 1114.6 1117.3 1211.1 14795.6 15188.2 15328.5 15502.0 15920.4 575525.0 581668.0 584566.0 585039.0 586453.0 57220.7 57380.0 57469.7 57512.7 57532.0 1216.5 1334.3 1371.1 1383.8 1397.1 26627.0 29110.4 30577.5 30755.5 30762.0 55187.4 55771.4 55842.0 55931.7 56016.2 537759.0 540581.0 541417.0 542838.3 543748.0 24559.0 25285.5 25358.9 25845.6 25873.9 44714.6 44993.1 45088.5 45510.9 46240.0 31798.5 36895.0 37182.3 37721.4 38134.6 36430.5 36462.7 37828.2 38048.0 38066.4 32325.7 37268.4 37660.0 38112.4 38132.4 32856.7 33302.7 33801.0 34385.0 34550.7 23020.9 24610.6 25437.1 25488.6 25828.0 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | [#2] jquery.html Cycles:180 Average:32836.72 Median:20651.64 stddev:92053.40 (445.7%) stddev-sans-first:92279.32 15:28:45 INFO - PROCESS | 1953 | Values: 348.5 348.8 376.1 382.3 403.4 15070.3 16558.8 20100.1 20802.4 20903.0 20042.3 20638.9 20647.7 20655.6 20883.5 5265.4 5316.7 6094.1 6189.4 6198.8 6076.9 6189.3 6192.8 6199.3 6206.1 5900.3 6080.6 6137.6 6138.3 6160.9 5835.9 6065.7 6109.0 6120.2 6128.8 5201.4 5268.6 5275.9 5279.6 5280.4 25708.1 26059.2 26093.3 26229.3 26539.7 2491.3 3120.7 3193.4 3216.2 3249.9 14902.9 15639.0 15892.0 15994.4 16262.4 1675.9 1776.5 1780.6 1917.4 1935.5 107019.0 107168.0 107585.5 110118.0 110974.5 10571.1 10583.4 10709.9 11023.5 11068.9 11719.5 13834.4 13842.2 13849.6 13887.9 7415.8 8474.0 8531.8 8605.9 8649.7 8673.4 8681.2 8681.5 8835.3 8871.8 5525.5 6682.7 6683.1 6698.2 6717.4 1630.7 1809.6 1860.7 1868.7 1903.1 743.5 842.8 910.9 956.8 1008.3 19147.7 19265.7 20386.3 21532.8 21557.1 885.2 991.4 1004.6 1018.4 1066.9 10093.3 11019.9 11574.6 11590.8 11668.3 546728.0 550592.0 555206.0 561306.0 561381.0 79117.6 109303.5 109553.5 109793.9 112472.0 960.9 973.5 997.5 998.0 1003.4 18999.6 19985.6 20052.0 20101.9 20172.9 44089.8 44913.1 46955.0 48007.0 49288.0 67731.0 67821.3 68568.3 69700.0 76444.0 7608.0 10971.7 11010.8 11414.3 11583.7 11757.6 12580.4 13150.5 13167.1 13266.0 10805.8 10975.0 11004.4 11051.9 11185.0 10415.5 10861.8 10901.5 10983.9 11328.8 10692.9 11056.7 11269.0 11368.5 11456.3 9514.2 11554.6 11572.9 11760.0 11837.0 10240.4 10399.9 10441.3 10492.8 10940.7 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | [#3] mootools.html Cycles:180 Average:5782.16 Median:2541.47 stddev:3282.70 (129.2%) stddev-sans-first:3276.33 15:28:45 INFO - PROCESS | 1953 | Values: 1527.1 1557.9 1776.4 1909.0 1941.6 8711.9 9252.5 9259.8 9261.4 9263.7 7944.7 8180.9 8592.4 8820.4 8973.9 2380.5 2400.8 2405.6 2409.0 2416.9 2372.7 2389.5 2419.0 2423.3 2424.8 2378.4 2386.2 2396.4 2407.8 2412.5 2366.6 2377.1 2384.7 2394.1 2397.3 2118.5 2363.7 2367.6 2371.9 2376.9 8941.2 8976.8 9041.3 9062.7 9069.3 5261.6 5420.8 5422.9 5434.1 5438.2 3499.5 3507.6 3544.2 3573.6 3575.0 1042.3 1042.4 1043.6 1055.3 1063.7 11687.2 11831.7 11837.3 11866.5 11874.6 4548.9 4718.4 4816.0 4835.7 4842.2 8736.5 10052.8 10060.5 10097.7 10118.5 5415.7 5419.8 5430.8 5437.8 5443.4 4448.0 4593.3 4810.9 4954.9 4959.4 3920.5 3963.3 4019.6 4059.3 4089.4 1162.9 1308.5 1310.8 1316.0 1336.2 808.6 884.2 899.5 916.2 945.4 5786.6 5906.3 6095.7 6190.4 6245.6 437.7 439.6 440.2 454.6 463.1 3842.4 3911.7 4017.8 4023.7 4030.0 10618.4 11094.0 11113.7 11127.3 11157.6 10707.7 10931.2 11114.8 11156.4 11170.8 8003.9 8293.3 8325.4 8411.5 8420.4 5961.7 6034.5 6275.7 6303.5 6307.0 9917.7 10124.0 10459.2 10501.0 10535.4 10216.1 10349.8 10511.7 10607.2 10612.4 6676.3 7064.8 7276.2 7277.4 7908.8 7389.6 8081.6 8156.6 8167.1 8564.5 7596.4 7685.3 7698.0 7827.7 7860.0 6292.2 6420.9 7434.8 7476.6 7624.8 6994.9 7142.5 7151.0 7159.7 7160.1 4619.9 4950.6 5021.3 5065.9 5217.8 4945.1 5073.5 5218.2 5269.3 5360.2 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | [#4] prototype.html Cycles:180 Average:4273.83 Median:4710.36 stddev:1294.03 (27.5%) stddev-sans-first:1262.75 15:28:45 INFO - PROCESS | 1953 | Values: 296.2 298.3 303.9 309.5 309.9 4732.2 4739.5 5493.5 5496.6 5513.4 4836.9 5259.9 5415.4 5429.1 5438.4 3715.2 3888.2 4076.2 4082.7 4082.8 3439.1 3888.5 4086.7 4122.8 4128.0 3388.6 3560.3 3831.6 3886.1 4101.0 3901.7 4051.4 4103.5 4111.6 4147.2 3335.6 3438.1 3475.3 3539.3 3573.5 4657.8 5071.4 5167.8 5179.2 5189.0 4468.6 4549.5 4670.6 4672.5 4716.7 5445.2 5578.3 5645.4 5655.5 5674.6 3234.9 3545.5 3902.0 3916.6 3924.1 4765.5 5319.4 5667.6 5996.4 6022.8 3973.6 3981.9 3984.5 3987.2 3990.9 4059.7 4062.2 4065.2 4066.9 4081.3 3972.9 4535.9 4704.1 4717.4 4744.9 5224.3 5642.3 5700.5 5704.2 5734.9 1254.2 1384.3 1387.9 1389.8 1390.3 780.2 780.4 795.8 849.8 851.8 3762.7 3825.7 3962.8 4101.7 4502.8 4309.0 4596.2 4741.6 4911.0 4937.2 4029.5 4144.1 4424.9 4462.8 4495.7 2252.5 2402.2 2410.5 2453.6 2598.8 4993.4 5048.8 5062.5 5066.3 5067.4 4583.8 4716.8 4788.4 5007.5 5075.6 4057.0 4981.7 4986.6 5078.9 5199.8 3980.9 4787.6 4803.4 4899.3 4904.4 4598.5 4764.5 4799.7 4845.7 4883.9 4358.9 4371.0 4505.0 5028.3 5077.9 3435.5 3450.8 3454.7 3455.8 3504.0 4353.7 4622.0 4928.8 4940.7 4979.5 4078.2 4193.5 5011.5 5748.3 5769.4 5671.5 5694.6 5705.3 5709.3 5793.4 4720.5 4877.0 4907.8 5230.5 5602.8 4769.6 4817.4 5206.5 5723.7 5757.5 5160.3 5197.3 5607.7 5612.4 5685.7 15:28:45 INFO - PROCESS | 1953 | 15:28:45 INFO - PROCESS | 1953 | [#5] yui.html Cycles:180 Average:24137.85 Median:3531.32 stddev:37983.64 (1075.6%) stddev-sans-first:38049.42 15:28:45 INFO - PROCESS | 1953 | Values: 699.3 720.1 767.8 790.7 816.8 15863.4 16401.6 16425.6 16434.0 16446.1 14410.3 14423.9 14449.3 14493.6 14760.9 672.1 692.3 834.0 836.9 863.0 833.5 847.0 847.7 849.3 851.0 910.5 910.8 911.2 911.9 912.1 707.0 736.3 742.0 907.9 909.2 543.0 653.3 679.9 682.6 686.1 14793.6 14818.3 14836.2 14857.4 14883.2 13568.9 13656.5 13922.8 13979.9 14064.5 12540.7 12803.7 12847.1 13120.6 13343.3 4488.8 4542.2 4556.7 4736.0 4744.1 106848.0 109167.9 110337.4 111951.4 113911.0 10937.5 11004.5 11026.7 11059.9 11265.4 21624.4 21692.8 21696.0 21720.7 22118.0 7345.2 7617.5 7908.9 7998.3 8084.4 4318.6 4329.4 4344.5 4346.3 4459.9 3399.9 3527.6 3529.7 3532.9 3535.3 653.6 665.2 672.5 673.0 676.3 555.2 572.6 605.6 611.0 620.9 7039.0 8734.8 8956.1 9061.5 9127.6 550.4 581.5 595.7 612.8 640.8 3775.4 4174.1 4351.4 4405.4 4408.0 109641.0 140320.3 154219.0 154748.0 155697.0 80389.0 104321.9 108851.9 132241.0 139203.0 103566.0 103989.5 106114.0 106802.0 107076.5 9685.7 9906.5 9938.2 9948.2 9964.5 43511.3 55457.4 60098.7 62980.7 63449.0 91057.0 92201.5 93630.0 96494.0 97329.5 12732.5 12850.4 13024.0 13174.3 13544.0 13210.7 13891.2 14863.6 14948.9 15265.4 13735.7 13815.1 13818.4 13837.6 14459.2 13413.5 13639.7 13718.7 13790.9 14239.6 13470.3 13478.0 13581.9 13706.9 13770.2 10862.8 11074.6 11328.4 11335.5 11549.7 9784.9 11013.0 11135.2 11490.5 11527.3 15:28:45 INFO - PROCESS | 1953 | -------- Summary: end -------- 15:28:45 INFO - PROCESS | 1953 | 15:28:46 INFO - TEST-INFO | 1953: exit 0 15:28:46 INFO - TEST-OK | dromaeo_css | took 1117840ms 15:28:46 INFO - TEST-START | kraken 15:28:46 INFO - operating with platform_type : mac_ 15:28:46 INFO - Initialising browser for kraken test... 15:28:46 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpf9qoFI/profile http://localhost:50515/getInfo.html) 15:28:48 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 15:28:48 INFO - PROCESS | 1957 | colorDepth:24 15:28:48 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 15:28:48 INFO - PROCESS | 1957 | __metrics 15:28:48 INFO - PROCESS | 1957 | 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" 15:28:48 INFO - PROCESS | 1957 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:28:49 INFO - TEST-INFO | 1957: exit 0 15:28:49 INFO - Browser initialized. 15:28:49 INFO - Running cycle 1/1 for kraken test... 15:28:49 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpf9qoFI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:01 INFO - PROCESS | 1958 | RSS: Main: 234655744 15:29:01 INFO - PROCESS | 1958 | 15:29:04 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50515/tests/kraken/driver.html?testName=audio-beat-detection) 15:29:05 INFO - PROCESS | 1958 | RSS: Main: 294277120 15:29:05 INFO - PROCESS | 1958 | 15:29:07 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50515/tests/kraken/driver.html?testName=audio-dft) 15:29:08 INFO - PROCESS | 1958 | RSS: Main: 290066432 15:29:08 INFO - PROCESS | 1958 | 15:29:12 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50515/tests/kraken/driver.html?testName=audio-fft) 15:29:12 INFO - PROCESS | 1958 | RSS: Main: 377069568 15:29:12 INFO - PROCESS | 1958 | 15:29:14 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50515/tests/kraken/driver.html?testName=audio-oscillator) 15:29:15 INFO - PROCESS | 1958 | RSS: Main: 280559616 15:29:15 INFO - PROCESS | 1958 | 15:29:17 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50515/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:29:18 INFO - PROCESS | 1958 | RSS: Main: 282423296 15:29:18 INFO - PROCESS | 1958 | 15:29:20 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50515/tests/kraken/driver.html?testName=imaging-darkroom) 15:29:21 INFO - PROCESS | 1958 | RSS: Main: 320303104 15:29:21 INFO - PROCESS | 1958 | 15:29:24 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50515/tests/kraken/driver.html?testName=imaging-desaturate) 15:29:25 INFO - PROCESS | 1958 | RSS: Main: 319684608 15:29:25 INFO - PROCESS | 1958 | 15:29:27 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50515/tests/kraken/driver.html?testName=json-parse-financial) 15:29:28 INFO - PROCESS | 1958 | RSS: Main: 321236992 15:29:28 INFO - PROCESS | 1958 | 15:29:30 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50515/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:29:30 INFO - PROCESS | 1958 | RSS: Main: 297938944 15:29:30 INFO - PROCESS | 1958 | 15:29:32 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:29:33 INFO - PROCESS | 1958 | RSS: Main: 304087040 15:29:33 INFO - PROCESS | 1958 | 15:29:35 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:29:36 INFO - PROCESS | 1958 | RSS: Main: 306704384 15:29:36 INFO - PROCESS | 1958 | 15:29:38 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:29:39 INFO - PROCESS | 1958 | RSS: Main: 320061440 15:29:39 INFO - PROCESS | 1958 | 15:29:42 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:29:43 INFO - PROCESS | 1958 | RSS: Main: 316047360 15:29:43 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50515/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50515/tests/kraken/driver.html?testName=ai-astar) 15:29:44 INFO - PROCESS | 1958 | RSS: Main: 359497728 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | __start_tp_report 15:29:44 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 15:29:44 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 15:29:44 INFO - PROCESS | 1958 | |i|pagename|runs| 15:29:44 INFO - PROCESS | 1958 | |0;ai-astar;114;114;133;114;112;114;115;161;166;114 15:29:44 INFO - PROCESS | 1958 | |1;audio-beat-detection;150;115;131;167;107;130;129;108;160;108 15:29:44 INFO - PROCESS | 1958 | |2;audio-dft;187;185;202;261;200;186;182;183;186;184 15:29:44 INFO - PROCESS | 1958 | |3;audio-fft;75;78;75;190;59;67;80;85;147;57 15:29:44 INFO - PROCESS | 1958 | |4;audio-oscillator;81;85;135;71;70;78;119;68;69;120 15:29:44 INFO - PROCESS | 1958 | |5;imaging-gaussian-blur;90;86;90;87;93;128;133;87;90;85 15:29:44 INFO - PROCESS | 1958 | |6;imaging-darkroom;94;98;96;95;95;93;94;95;94;94 15:29:44 INFO - PROCESS | 1958 | |7;imaging-desaturate;85;100;139;135;83;85;86;86;87;85 15:29:44 INFO - PROCESS | 1958 | |8;json-parse-financial;54;53;53;53;53;55;54;52;52;53 15:29:44 INFO - PROCESS | 1958 | |9;json-stringify-tinderbox;66;66;67;70;73;79;74;71;71;72 15:29:44 INFO - PROCESS | 1958 | |10;stanford-crypto-aes;88;86;85;84;84;87;87;84;90;84 15:29:44 INFO - PROCESS | 1958 | |11;stanford-crypto-ccm;137;139;138;138;139;137;136;127;180;139 15:29:44 INFO - PROCESS | 1958 | |12;stanford-crypto-pbkdf2;166;170;170;168;166;171;163;164;168;169 15:29:44 INFO - PROCESS | 1958 | |13;stanford-crypto-sha256-iterative;68;68;66;65;65;67;67;67;67;66 15:29:44 INFO - PROCESS | 1958 | __end_tp_report 15:29:44 INFO - PROCESS | 1958 | __start_cc_report 15:29:44 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,2026 15:29:44 INFO - PROCESS | 1958 | __end_cc_report 15:29:44 INFO - PROCESS | 1958 | __startTimestamp1472682584981__endTimestamp 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | ------- Summary: start ------- 15:29:44 INFO - PROCESS | 1958 | Number of tests: 14 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#0] ai-astar Cycles:10 Average:125.70 Median:114.50 stddev:20.84 (18.2%) stddev-sans-first:21.67 15:29:44 INFO - PROCESS | 1958 | Values: 114.0 114.0 133.0 114.0 112.0 114.0 115.0 161.0 166.0 114.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:130.50 stddev:22.09 (16.9%) stddev-sans-first:22.27 15:29:44 INFO - PROCESS | 1958 | Values: 150.0 115.0 131.0 167.0 107.0 130.0 129.0 108.0 160.0 108.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#2] audio-dft Cycles:10 Average:195.60 Median:186.50 stddev:24.01 (12.9%) stddev-sans-first:25.26 15:29:44 INFO - PROCESS | 1958 | Values: 187.0 185.0 202.0 261.0 200.0 186.0 182.0 183.0 186.0 184.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#3] audio-fft Cycles:10 Average:91.30 Median:75.00 stddev:42.86 (57.1%) stddev-sans-first:45.05 15:29:44 INFO - PROCESS | 1958 | Values: 75.0 78.0 75.0 190.0 59.0 67.0 80.0 85.0 147.0 57.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:70.50 stddev:25.17 (35.7%) stddev-sans-first:26.50 15:29:44 INFO - PROCESS | 1958 | Values: 81.0 85.0 135.0 71.0 70.0 78.0 119.0 68.0 69.0 120.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:88.50 stddev:17.90 (20.2%) stddev-sans-first:18.81 15:29:44 INFO - PROCESS | 1958 | Values: 90.0 86.0 90.0 87.0 93.0 128.0 133.0 87.0 90.0 85.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:1.40 (1.5%) stddev-sans-first:1.45 15:29:44 INFO - PROCESS | 1958 | Values: 94.0 98.0 96.0 95.0 95.0 93.0 94.0 95.0 94.0 94.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:85.00 stddev:21.57 (25.4%) stddev-sans-first:22.43 15:29:44 INFO - PROCESS | 1958 | Values: 85.0 100.0 139.0 135.0 83.0 85.0 86.0 86.0 87.0 85.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 15:29:44 INFO - PROCESS | 1958 | Values: 54.0 53.0 53.0 53.0 53.0 55.0 54.0 52.0 52.0 53.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:4.01 (5.6%) stddev-sans-first:3.84 15:29:44 INFO - PROCESS | 1958 | Values: 66.0 66.0 67.0 70.0 73.0 79.0 74.0 71.0 71.0 72.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.50 stddev:2.08 (2.4%) stddev-sans-first:2.06 15:29:44 INFO - PROCESS | 1958 | Values: 88.0 86.0 85.0 84.0 84.0 87.0 87.0 84.0 90.0 84.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:138.50 stddev:14.16 (10.2%) stddev-sans-first:14.94 15:29:44 INFO - PROCESS | 1958 | Values: 137.0 139.0 138.0 138.0 139.0 137.0 136.0 127.0 180.0 139.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.50 stddev:2.68 (1.6%) stddev-sans-first:2.78 15:29:44 INFO - PROCESS | 1958 | Values: 166.0 170.0 170.0 168.0 166.0 171.0 163.0 164.0 168.0 169.0 15:29:44 INFO - PROCESS | 1958 | 15:29:44 INFO - PROCESS | 1958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.07 (1.6%) stddev-sans-first:1.01 15:29:44 INFO - PROCESS | 1958 | Values: 68.0 68.0 66.0 65.0 65.0 67.0 67.0 67.0 67.0 66.0 15:29:44 INFO - PROCESS | 1958 | -------- Summary: end -------- 15:29:44 INFO - PROCESS | 1958 | 15:29:45 INFO - TEST-INFO | 1958: exit 0 15:29:45 INFO - TEST-OK | kraken | took 59500ms 15:29:45 INFO - SUITE-END | took 1177s 15:29:46 INFO - Completed test suite (00:19:37) 15:29:46 INFO - javascript benchmark 15:29:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.0270820108067, 3304.785188962716, 3338.782979106358, 3359.865605375785, 3384.509246159307, 5628.831135065944, 5693.0, 5699.857503562413, 5755.39568345324, 5757.654540727559, 5761.1358296255585, 5793.942060579393, 5812.4768770810515, 5898.056310990242, 5931.169636250917, 5113.795448182068, 5185.429602743695, 5232.642339557437, 5276.630635855492, 5362.383325917531, 5334.466553344654, 5354.303940487731, 5359.919601205985, 5367.490088441592, 5372.274742909716, 5168.224766285088, 5205.29728486657, 5230.320058392384, 5242.68543887368, 5300.734963251842, 5195.766190521435, 5214.165733482625, 5232.895342093175, 5238.79044838206, 5254.65844720092, 5167.741612919376, 5189.065968125736, 5211.2443695663915, 5211.348581427322, 5226.268322434862, 5669.489745922846, 5703.800366987177, 5718.999175144333, 5795.420457954213, 5814.156947242668, 6139.201903752528, 6197.659128747917, 6211.937880621181, 6227.283862355842, 6231.065340198961, 4795.904081918342, 6108.175396321484, 6132.080177995968, 6169.074638804215, 6171.413715697002, 5846.269216347957, 5861.208736820475, 5868.4424979626865, 5870.090136028923, 5875.9316045927835, 6099.505621122853, 6193.938060619426, 6194.566380353423, 6200.658963757037, 6207.224096987877, 6092.482139018233, 6129.203203583595, 6142.877142457215, 6172.629642221481, 6189.442950134419, 6165.0, 6166.660833654192, 6172.845678857974, 6183.690815459299, 6191.442770150708, 6179.62922224668, 6188.721507532172, 6193.628382297076, 6196.008638617799, 6202.4417802398, 6104.216637118914, 6122.265328160649, 6146.678464130234, 6173.722182501709, 6187.969060154761, 5009.724465154379, 5010.924836127461, 5012.0, 5014.52362025607, 5025.120603894304, 4479.686421950498, 4489.6408287336935, 4489.708168969007, 4509.526499717548, 4558.840440584564, 5608.523275521627, 5669.40471250521, 5777.0, 5813.157092221604, 5870.295564532198, 5780.479756821906, 5886.999210134288, 6144.2488890665945, 6175.69121543921, 6188.597741146811, 5637.126245431965, 5729.169270455844, 5744.684042377626, 5756.481916627524, 5760.222369979999, 5381.327334083239, 5432.320959880015, 5441.891162176814, 5485.149801780651, 5486.945130548724, 6174.567780255339, 6179.969100154471, 6218.253809542884, 6229.719662615103, 6312.842178945563, 6156.538259630635, 6167.629942203482, 6175.59858609189, 6178.876422471615, 6203.658798766111, 6250.1874756281395, 6277.780277690259, 6301.873962520815, 6324.051392291193, 6328.019157030668, 6073.392660733891, 6095.63426194412, 6105.520399749632, 6144.218176728698, 6164.291106522807, 6166.075088736546, 6166.075088736726, 6177.166082578974, 6179.567430280017, 6197.938020619736, 6103.786367477117, 6115.694215289129, 6127.448529632355, 6127.969360153171, 6146.385361463817, 6021.638701677914, 6088.299845517645, 6098.91636565712, 6110.175126358063, 6113.449789518964, 4683.391159149289, 6062.0, 6062.969685151546, 6098.420650038239, 6109.602875813058, 6182.505399568114, 6201.3178550358525, 6202.937970620236, 6205.503559715302, 6205.596636218631, 5862.970685146547, 5969.313528944227, 5972.731227094878, 6046.879062418815, 6056.242969628796], "value": 5698.309300881989, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.15241802665076, 494.21173228700235, 509.8903735696824, 517.017666433776, 521.0594876248371, 29903.252418689517, 40330.0, 41845.7907710461, 41847.0, 41878.743637690844, 42606.147877042444, 43201.56798432015, 43341.0, 43429.0, 43459.78270108645, 8894.332925030616, 9777.071178238073, 9958.402495850272, 9959.850602240973, 9967.302288839785, 8888.73333799987, 11806.055515558735, 12130.757384852297, 12152.696182595417, 12291.93854030733, 12144.453499592539, 12211.389430528483, 12226.449809758538, 12228.960538354251, 12260.69348266296, 12074.154809163361, 12154.574589889357, 12173.20874143178, 12174.634760957097, 12180.81728774069, 11944.581939632159, 12016.0, 12027.819582706266, 12030.939845300718, 12062.035037197003, 38654.420183697264, 38968.41547376791, 39009.60990390117, 39018.41472377917, 39043.41434878479, 3188.4260833050043, 3317.2370354818286, 3775.7734535927657, 4007.3788562772816, 4029.5567487576345, 29355.825766969294, 29731.702682973326, 30242.092737217918, 30694.53958190629, 30713.0, 3266.5590145330316, 3287.3260981498734, 3309.3546758382126, 3336.349411864687, 3342.28140949697, 446752.0, 446838.0, 447162.0, 448069.0, 459036.70481647714, 60210.0, 60652.0, 60760.83532899753, 60768.69615651982, 60781.69609151926, 63697.363026369145, 63728.68135659292, 63778.0, 63796.36203637904, 63817.0, 16495.34018639241, 16705.498835034967, 16714.331426742796, 16724.83275167257, 16745.413910513074, 11546.538138474534, 11749.0, 11763.235389699814, 12774.872251277555, 12857.742845143044, 14295.428182872776, 14621.0, 14643.437398455228, 15936.601584960237, 16073.919630402008, 4708.204386275852, 5359.678419294777, 6222.191115155001, 6236.501079913596, 6236.688165591793, 863.6372723456163, 903.5888670654837, 941.5857022909897, 945.9006804285589, 1017.9185665146771, 27970.720292797218, 28109.859450703025, 29583.556246656317, 30262.09213723592, 30331.545026824617, 1010.1211945607369, 1094.3871431998107, 1114.57089020726, 1117.2626066795876, 1211.1098342718094, 14795.630109247355, 15188.164650944304, 15328.463503777537, 15502.0, 15920.363185472683, 575525.0, 581668.0, 584566.0, 585039.0, 586453.0, 57220.71389643025, 57380.0, 57469.71265143647, 57512.71243643755, 57532.0, 1216.531635320408, 1334.3128288931402, 1371.060823336018, 1383.8270216222973, 1397.0639671420172, 26627.0, 29110.41779164447, 30577.54133688041, 30755.538666919565, 30762.0, 55187.44812551823, 55771.44228557825, 55842.0, 55931.72034139803, 56016.15975760285, 537759.0, 540581.0, 541417.0, 542838.2858085685, 543748.0, 24559.0, 25285.494290113726, 25358.87320563459, 25845.612315814902, 25873.870630647478, 44714.55285447104, 44993.1001379964, 45088.54911450844, 45510.86222844323, 46240.0, 31798.52302215515, 36895.0, 37182.25635487221, 37721.434178486794, 38134.61865381311, 36430.453543196345, 36462.74803444403, 37828.24343513169, 38048.0, 38066.42900356552, 32325.676743232267, 37268.440973385965, 37660.0, 38112.428313575874, 38132.428013579265, 32856.67143328632, 33302.66697332996, 33801.0, 34384.968450946515, 34550.654493455746, 23020.884895575415, 24610.570097381926, 25437.10970116037, 25488.617670734584, 25828.0], "value": 19976.598899766224, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.48424331988673, 348.7976973355454, 376.1330134041036, 382.26986455869286, 403.43720509888703, 15070.321835517401, 16558.83441165588, 20100.09549570269, 20802.375928722126, 20902.99999999998, 20042.29851955182, 20638.89680551599, 20647.690284645705, 20655.586888262227, 20883.477913052182, 5265.394479634833, 5316.73416329184, 6094.055421409688, 6189.381061893797, 6198.814035578917, 6076.908846367308, 6189.319174890758, 6192.845178870519, 6199.318075011745, 6206.100115483275, 5900.262467191601, 6080.604760690563, 6137.601055931373, 6138.324784273748, 6160.876782464348, 5835.941640583582, 6065.666388348639, 6108.96945515274, 6120.173776540155, 6128.816135515964, 5201.401838788512, 5268.631195816294, 5275.947240527622, 5279.63042587019, 5280.445553216934, 25708.10021649252, 26059.21822345333, 26093.347666308306, 26229.344266393302, 26539.734602654113, 2491.314888405685, 3120.6723294054023, 3193.409219294415, 3216.2281052547455, 3249.8700051997894, 14902.925485372612, 15639.0, 15891.967022143524, 15994.360225590963, 16262.43081492154, 1675.8858645051712, 1776.4670598820303, 1780.5993651428455, 1917.4085509027473, 1935.4967708395777, 107019.0, 107168.0, 107585.46207268913, 110118.0, 110974.51556776116, 10571.101456376295, 10583.364998099985, 10709.892901071045, 11023.503942322615, 11068.944655276833, 11719.531218751325, 13834.446622135001, 13842.238676872868, 13849.584512464642, 13887.861121388656, 7415.814604634819, 8474.0, 8531.82936341282, 8605.870911936327, 8649.654013839501, 8673.392862499692, 8681.2186903179, 8681.479111253346, 8835.337349698799, 8871.822563548692, 5525.475079867406, 6682.732690692318, 6683.097781799492, 6698.229703584053, 6717.361850624183, 1630.7390817469147, 1809.6109336492718, 1860.6836837737617, 1868.7292641003976, 1903.1055403960117, 743.5352904434728, 842.7556008757392, 910.9498977556166, 956.7512446763892, 1008.2708155513329, 19147.659663823677, 19265.724826779482, 20386.28647997313, 21532.78467215339, 21557.137714491557, 885.1679421343916, 991.3679181772405, 1004.5830980143305, 1018.3737001743874, 1066.914646828252, 10093.293469457214, 11019.944900275448, 11574.594889179007, 11590.826137607943, 11668.299902005738, 546728.0, 550592.0, 555206.0, 561306.0, 561381.0, 79117.60441197873, 109303.45348273207, 109553.45223273992, 109793.90206097996, 112472.0, 960.9375390599589, 973.4694591447678, 997.4514017290461, 997.9750506237402, 1003.418017549834, 18999.620007600046, 19985.60028799445, 20052.0, 20101.899490503038, 20172.899135504227, 44089.795510225005, 44913.05956344734, 46955.0, 48007.0, 49288.0, 67731.0, 67821.3217867815, 68568.3143168562, 69699.95450068152, 76444.00451923076, 7608.0, 10971.670849874517, 11010.834837477605, 11414.315141091562, 11583.710407239887, 11757.647270581896, 12580.433880475584, 13150.539731109366, 13167.144135631153, 13266.0, 10805.783884322427, 10975.0, 11004.39475828837, 11051.944740276247, 11185.0, 10415.53130109162, 10861.782764344827, 10901.509432075418, 10983.890161098287, 11328.773424531628, 10692.89307106919, 11056.668299951014, 11269.0, 11368.488418021043, 11456.312621242752, 9514.23886089125, 11554.59558915434, 11572.94213528927, 11760.0, 11837.0, 10240.436775977392, 10399.896001039893, 10441.269111162146, 10492.790144197226, 10940.671779846618], "value": 9802.719204744591, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1527.0684882221847, 1557.8987365821222, 1776.3782676063372, 1908.9809101908995, 1941.592265624219, 8711.91288087119, 9252.53737313135, 9259.76850578735, 9261.444313341195, 9263.675771348004, 7944.721934732285, 8180.877286840702, 8592.355573331994, 8820.426672266298, 8973.91026089739, 2380.5476959377747, 2400.759924007603, 2405.603075492542, 2409.012304954969, 2416.8549887006834, 2372.7271363793125, 2389.474315650554, 2418.9758102419014, 2423.285130886393, 2424.806015518754, 2378.3816207786012, 2386.1529157149134, 2396.3769419950763, 2407.7953373963146, 2412.49337639096, 2366.6095094309417, 2377.120465427794, 2384.6899903012672, 2394.126143957452, 2397.2568503763887, 2118.4915620251027, 2363.6927199463958, 2367.597508423572, 2371.9407014824596, 2376.916807911732, 8941.239994600466, 8976.775580610472, 9041.276697864221, 9062.682806101755, 9069.274458043341, 5261.631685781997, 5420.756065977041, 5422.864428389283, 5434.130539113723, 5438.184272359178, 3499.5100685903994, 3507.57909050913, 3544.220271540257, 3573.624769399202, 3574.982125089358, 1042.3016578892161, 1042.4475028235047, 1043.6190790361538, 1055.250771951915, 1063.7127975446663, 11687.181897267195, 11831.704207394798, 11837.289762614271, 11866.52533898643, 11874.584389546411, 4548.931766023512, 4718.4337879454, 4816.012717392926, 4835.685680430761, 4842.249451335048, 8736.475811451333, 10052.798944021079, 10060.496975151213, 10097.677534167182, 10118.494075296207, 5415.675059496364, 5419.83740487786, 5430.809921652803, 5437.809676661298, 5443.401225865152, 4447.977760111179, 4593.311003349524, 4810.927836082462, 4954.9009019819405, 4959.380077490314, 3920.5295364555864, 3963.286608410456, 4019.577944315863, 4059.2693315202955, 4089.386592011222, 1162.8895254950767, 1308.483149156078, 1310.8361454818148, 1315.9736805264033, 1336.1715736243398, 808.5552945879742, 884.2307192742355, 899.4513346858411, 916.2088117729955, 945.385499425379, 5786.594938354275, 5906.3207731110615, 6095.725692343855, 6190.442860142519, 6245.6252624842655, 437.72860826287905, 439.63949561360016, 440.2295982031445, 454.56134829889317, 463.0507459707586, 3842.3852183650483, 3911.6870650347905, 4017.779022153751, 4023.6781057515327, 4030.0, 10618.362898225976, 11094.0, 11113.722156946142, 11127.27672701288, 11157.609483668033, 10707.678769636768, 10931.234813563133, 11114.833277500844, 11156.442177891073, 11170.832437513443, 8003.919960800434, 8293.295069919124, 8325.417220794607, 8411.537365444838, 8420.410571260076, 5961.672108033927, 6034.487058624016, 6275.717592708248, 6303.46420554258, 6306.9684651576445, 9917.702468925943, 10124.0, 10459.163266938778, 10501.002404771534, 10535.367877927349, 10216.131628811338, 10349.844752328872, 10511.684649460529, 10607.204459665401, 10612.416317102634, 6676.298988606127, 7064.788056358318, 7276.1996180419055, 7277.417806575567, 7908.84182316339, 7389.630518474162, 8081.636326365446, 8156.551389673625, 8167.14245004266, 8564.48613083217, 7596.392288616787, 7685.269899359552, 7697.961510192413, 7827.6868925244635, 7860.0, 6292.2134733158355, 6420.935790642221, 7434.7769566913075, 7476.5514069156025, 7624.8475030500185, 6994.860102798018, 7142.500024998408, 7151.0, 7159.749408770668, 7160.10498687664, 4619.884502887455, 4950.579200767975, 5021.3472248608905, 5065.898682026265, 5217.765200565907, 4945.109880221451, 5073.518015788494, 5218.217267409767, 5269.314989051399, 5360.16917377807], "value": 4557.28264339731, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1751522011199, 298.3064375327364, 303.9149038269284, 309.4615369257493, 309.88224474699626, 4732.160703577852, 4739.478657347693, 5493.45065493451, 5496.587755918302, 5513.448655134494, 4836.927446088312, 5259.948010397926, 5415.377231618365, 5429.072679198886, 5438.362394238801, 3715.1826598148364, 3888.2199227552364, 4076.1847630473876, 4082.6937979651498, 4082.7754473503946, 3439.0569328329416, 3888.475055867464, 4086.7139300248996, 4122.793860306988, 4128.009277773328, 3388.6272510023878, 3560.3413368526903, 3831.63599458051, 3886.14504808943, 4101.0, 3901.7463864848696, 4051.392291156321, 4103.528094269167, 4111.5815043809835, 4147.191297696951, 3335.5997280326274, 3438.0889064397957, 3475.2528206435736, 3539.256756081226, 3573.4818451324413, 4657.812257874254, 5071.442141364447, 5167.777855522124, 5179.249008893727, 5188.9740551297, 4468.642508599304, 4549.454065512126, 4670.6497012724185, 4672.486026537078, 4716.669833111683, 5445.215575175254, 5578.30271216098, 5645.435456454363, 5655.462731040545, 5674.631148975303, 3234.9353012939846, 3545.503629491873, 3901.9609803902167, 3916.5691773904846, 3924.13668992824, 4765.523447655241, 5319.361676598833, 5667.5749318800845, 5996.400359964013, 6022.759089636409, 3973.602639736061, 3981.9203615927518, 3984.5019372578427, 3987.1626958338493, 3990.940135897964, 4059.736117152435, 4062.1672557125717, 4065.1503565878047, 4066.9186616267507, 4081.3266560709967, 3972.8609498667406, 4535.863924082283, 4704.059188162381, 4717.386739723814, 4744.857654270378, 5224.268602395668, 5642.294713160855, 5700.515456186187, 5704.201411802351, 5734.913976290359, 1254.2411840836346, 1384.3424373422545, 1387.9167249965035, 1389.8262717160355, 1390.3395886953615, 780.1730166024033, 780.4029917113413, 795.8408318336355, 849.762066621347, 851.8381507513552, 3762.7177961652988, 3825.6907774775177, 3962.782046987386, 4101.651359634405, 4502.774861256924, 4309.030468144642, 4596.218642830727, 4741.596964258008, 4910.975445122752, 4937.185364414903, 4029.49631296088, 4144.052295421147, 4424.867253982386, 4462.776861156929, 4495.741192466115, 2252.549490101986, 2402.183257692393, 2410.4696966667307, 2453.576925383273, 2598.753118453744, 4993.400791904995, 5048.7728052237735, 5062.544371006627, 5066.340171410039, 5067.442581316052, 4583.839565615188, 4716.792581044922, 4788.371953535745, 5007.499250075036, 5075.593952483792, 4056.959430405658, 4981.651284410056, 4986.626003049786, 5078.949210507922, 5199.844004679866, 3980.920381592294, 4787.569118779326, 4803.447603525499, 4899.260762429429, 4904.411470623548, 4598.540145985375, 4764.499727528698, 4799.688020278671, 4845.709257444564, 4883.853484395474, 4358.934615980699, 4370.950971766817, 4504.977475112603, 5028.296038554532, 5077.949220507748, 3435.5362026126154, 3450.8447119880166, 3454.6890779829937, 3455.844486998041, 3503.969066757186, 4353.734698975768, 4622.0, 4928.771654566265, 4940.728259945738, 4979.502049794992, 4078.184363127327, 4193.477478917724, 5011.5489605935645, 5748.281464816898, 5769.423057694197, 5671.517920976598, 5694.601377903507, 5705.3153621564015, 5709.286339207599, 5793.391693872083, 4720.45714742809, 4877.024595080927, 4907.828226012215, 5230.450802665818, 5602.803901863578, 4769.594584460359, 4817.373741413594, 5206.453322401245, 5723.713814309352, 5757.481826635623, 5160.322895059196, 5197.298364720715, 5607.691576963306, 5612.410696876933, 5685.687287199244], "value": 3867.2501966524246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.2797418658781, 720.0531301338735, 767.838753861689, 790.671871173463, 816.8447994880972, 15863.444779432722, 16401.589960250985, 16425.589360266, 16434.0, 16446.095464749433, 14410.325367031419, 14423.92788036061, 14449.277536123205, 14493.5651930442, 14760.926195369035, 672.0826072411148, 692.2696555134331, 834.028356964136, 836.9246767790897, 862.9568521573896, 833.5248908122372, 847.0216899481109, 847.7117779954814, 849.3163003781953, 850.974470765878, 910.544727636182, 910.7723069232692, 911.2300106410073, 911.8768966189546, 912.1289168843738, 706.9611171385571, 736.2894806511688, 741.9814504637427, 907.8774365460645, 909.1771946388496, 542.9809956651537, 653.2716021635889, 679.8912174052128, 682.621145264378, 686.1423220973783, 14793.630159245997, 14818.33317500715, 14836.18400987956, 14857.405703771836, 14883.18142502162, 13568.93215533916, 13656.453741850313, 13922.791158132635, 13979.860201397958, 14064.495688076147, 12540.686482837911, 12803.679908002281, 12847.100702950796, 13120.55324152499, 13343.266120363376, 4488.84289049885, 4542.182407166709, 4556.658250631182, 4735.976320118378, 4744.07490539345, 106848.0, 109167.90832091657, 110337.44831275873, 111951.44024279909, 113911.0, 10937.507812148473, 11004.504797283982, 11026.669199924016, 11059.944700276446, 11265.436728163722, 21624.351269461942, 21692.783072169394, 21696.023678934485, 21720.674189887166, 22118.0, 7345.19202887682, 7617.542947423172, 7908.881366779503, 7998.280154786097, 8084.434089613788, 4318.5681431856565, 4329.415528903621, 4344.500382456058, 4346.2828633275785, 4459.888502787391, 3399.8810041648426, 3527.647235276503, 3529.6999755020597, 3532.947005794915, 3535.275268569937, 653.6078352988264, 665.171861033017, 672.5124284893413, 673.0409166937193, 676.3000294694967, 555.2448669809056, 572.5820151289582, 605.5639939243738, 610.9755609775648, 620.9472194863487, 7038.979347994512, 8734.781630459289, 8956.149165829236, 9061.456312621263, 9127.634894604274, 550.3780727777586, 581.4563383236688, 595.6842873277084, 612.7824622259034, 640.759715106835, 3775.3959366501226, 4174.068376916613, 4351.369051487517, 4405.427294451765, 4408.0, 109641.03589640972, 140320.2983985094, 154219.0, 154748.0, 155697.0, 80389.0, 104321.91356172835, 108851.91148088576, 132241.0, 139203.0, 103566.0, 103989.48005260076, 106113.99999999846, 106802.0, 107076.4646176764, 9685.6610018649, 9906.455144967096, 9938.155256803253, 9948.154406875206, 9964.451955142536, 43511.347329789445, 55457.44542554523, 60098.69950650219, 62980.68509657423, 63449.0, 91057.0, 92201.5389923046, 93630.0, 96494.0, 97329.51335243278, 12732.535758387903, 12850.42173102194, 13024.023198260358, 13174.275414852274, 13544.036114763501, 13210.735785284432, 13891.166530008231, 14863.554093377215, 14948.850511494746, 15265.3893844245, 13735.656608584466, 13815.102018368774, 13818.447262109403, 13837.584872453843, 14459.204743739196, 13413.530526431528, 13639.659008524866, 13718.65703357424, 13790.931045344709, 14239.644008899446, 13470.259135747629, 13478.0, 13581.932090339486, 13706.93146534261, 13770.173789572658, 10862.820289350086, 11074.557017719524, 11328.376939268419, 11335.489902954221, 11549.655144918788, 9784.9021509784, 11013.00882920541, 11135.245003287928, 11490.54037838477, 11527.250728702607], "value": 7749.906647406714, "unit": "score"}], "name": "dromaeo_css", "value": 7308.776788457955, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 133.0, 114.0, 112.0, 114.0, 115.0, 161.0, 166.0, 114.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 131.0, 167.0, 107.0, 130.0, 129.0, 108.0, 160.0, 108.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 202.0, 261.0, 200.0, 186.0, 182.0, 183.0, 186.0, 184.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 190.0, 59.0, 67.0, 80.0, 85.0, 147.0, 57.0], "value": 91.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 135.0, 71.0, 70.0, 78.0, 119.0, 68.0, 69.0, 120.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 93.0, 128.0, 133.0, 87.0, 90.0, 85.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 96.0, 95.0, 95.0, 93.0, 94.0, 95.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 100.0, 139.0, 135.0, 83.0, 85.0, 86.0, 86.0, 87.0, 85.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 53.0, 55.0, 54.0, 52.0, 52.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 70.0, 73.0, 79.0, 74.0, 71.0, 71.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 84.0, 84.0, 87.0, 87.0, 84.0, 90.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 138.0, 138.0, 139.0, 137.0, 136.0, 127.0, 180.0, 139.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 170.0, 168.0, 166.0, 171.0, 163.0, 164.0, 168.0, 169.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 65.0, 65.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1506.6, "alertThreshold": 2.0}]} 15:29:46 INFO - Return code: 0 15:29:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:29:46 INFO - # TBPL SUCCESS # 15:29:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:29:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:29:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:29:46 INFO - 2016-08-31 15:29:46.296 screenresolution[1962:13911] starting screenresolution argv=screenresolution get 15:29:46 INFO - 2016-08-31 15:29:46.299 screenresolution[1962:13911] Display 0: 1600x1200x32@60 15:29:46 INFO - 2016-08-31 15:29:46.305 screenresolution[1963:13914] starting screenresolution argv=screenresolution list 15:29:46 INFO - Available Modes on Display 0 15:29:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:29:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:29:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:29:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:29:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:29:46 INFO - Intel Iris: 15:29:46 INFO - Chipset Model: Intel Iris 15:29:46 INFO - Type: GPU 15:29:46 INFO - Bus: Built-In 15:29:46 INFO - VRAM (Dynamic, Max): 1536 MB 15:29:46 INFO - Vendor: Intel (0x8086) 15:29:46 INFO - Device ID: 0x0a2e 15:29:46 INFO - Revision ID: 0x0009 15:29:46 INFO - Displays: 15:29:46 INFO - Display: 15:29:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:29:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:29:46 INFO - Display Serial Number: 1600x1200 60 15:29:46 INFO - Main Display: Yes 15:29:46 INFO - Mirror: Off 15:29:46 INFO - Online: Yes 15:29:46 INFO - Rotation: Supported 15:29:46 INFO - Return code: 0 15:29:46 INFO - Running post-action listener: _resource_record_post_action 15:29:46 INFO - [mozharness: 2016-08-31 22:29:46.552927Z] Finished run-tests step (success) 15:29:46 INFO - Running post-run listener: _resource_record_post_run 15:29:46 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 851968; Write bytes: 705735168; Read time: 12; Write time: 3667 15:29:46 INFO - TinderboxPrint: CPU usage
53.8% 15:29:46 INFO - TinderboxPrint: I/O read bytes / time
851,968 / 12 15:29:46 INFO - TinderboxPrint: I/O write bytes / time
705,735,168 / 3,667 15:29:46 INFO - TinderboxPrint: CPU idle
2,242.6 (46.2%) 15:29:46 INFO - TinderboxPrint: CPU system
974.4 (20.1%) 15:29:46 INFO - TinderboxPrint: CPU user
1,632.7 (33.7%) 15:29:46 INFO - TinderboxPrint: Swap in / out
407,150,592 / 0 15:29:46 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394437120; Write bytes: 388659200; Read time: 29492; Write time: 733 15:29:46 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 843776; Write bytes: 296296960; Read time: 11; Write time: 2893 15:29:47 INFO - Running post-run listener: _upload_blobber_files 15:29:47 INFO - Blob upload gear active. 15:29:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:29:47 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 15:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:29:47 INFO - (blobuploader) - INFO - Open directory for files ... 15:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:29:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:48 INFO - (blobuploader) - INFO - Done attempting. 15:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:49 INFO - (blobuploader) - INFO - Done attempting. 15:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:29:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:29:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:29:51 INFO - (blobuploader) - INFO - Done attempting. 15:29:51 INFO - (blobuploader) - INFO - Iteration through files over. 15:29:51 INFO - Return code: 0 15:29:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:29:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:29:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07ee2ad9d732a50cac143c944e77bd2ec518e7ac71bddf77fd44b99f0193a584c4ad8962caa4ad6dfcd97573758118ae416b555394023a8301ba6d31eaf0283b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3252672ac55dc5d9d19eb8bca6b453225a2b56fa8e3610b4abceee8baef2264d8e41d2be10e5bdc9f90cd0dc090f7bb43df8ff110b43a33c873669e699e16b0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f251fdefcf21c6d22c5bd00f3117dca8adac5b2af90ebc13e8c4f9af5fb8d69198a61fb9c833c094df19cb7d8a061ec35fb3ecf2f718409811ad326ee8e27229"} 15:29:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:29:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:29:51 INFO - Contents: 15:29:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07ee2ad9d732a50cac143c944e77bd2ec518e7ac71bddf77fd44b99f0193a584c4ad8962caa4ad6dfcd97573758118ae416b555394023a8301ba6d31eaf0283b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3252672ac55dc5d9d19eb8bca6b453225a2b56fa8e3610b4abceee8baef2264d8e41d2be10e5bdc9f90cd0dc090f7bb43df8ff110b43a33c873669e699e16b0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f251fdefcf21c6d22c5bd00f3117dca8adac5b2af90ebc13e8c4f9af5fb8d69198a61fb9c833c094df19cb7d8a061ec35fb3ecf2f718409811ad326ee8e27229"} 15:29:51 INFO - Running post-run listener: copy_logs_to_upload_dir 15:29:51 INFO - Copying logs to upload dir... 15:29:51 INFO - mkdir: /builds/slave/test/build/upload/logs 15:29:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.550613 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-31 15:29:52.018163) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 15:29:52.021855) ========= 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.G1wE9OQkvF/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.WCTkURcqPj/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07ee2ad9d732a50cac143c944e77bd2ec518e7ac71bddf77fd44b99f0193a584c4ad8962caa4ad6dfcd97573758118ae416b555394023a8301ba6d31eaf0283b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3252672ac55dc5d9d19eb8bca6b453225a2b56fa8e3610b4abceee8baef2264d8e41d2be10e5bdc9f90cd0dc090f7bb43df8ff110b43a33c873669e699e16b0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f251fdefcf21c6d22c5bd00f3117dca8adac5b2af90ebc13e8c4f9af5fb8d69198a61fb9c833c094df19cb7d8a061ec35fb3ecf2f718409811ad326ee8e27229"} build_url:https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010975 build_url: 'https://queue.taskcluster.net/v1/task/XVPlEK43RoCr7KKLQKhfFw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07ee2ad9d732a50cac143c944e77bd2ec518e7ac71bddf77fd44b99f0193a584c4ad8962caa4ad6dfcd97573758118ae416b555394023a8301ba6d31eaf0283b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3252672ac55dc5d9d19eb8bca6b453225a2b56fa8e3610b4abceee8baef2264d8e41d2be10e5bdc9f90cd0dc090f7bb43df8ff110b43a33c873669e699e16b0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f251fdefcf21c6d22c5bd00f3117dca8adac5b2af90ebc13e8c4f9af5fb8d69198a61fb9c833c094df19cb7d8a061ec35fb3ecf2f718409811ad326ee8e27229"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 15:29:52.059768) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:29:52.060204) ========= 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.G1wE9OQkvF/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.WCTkURcqPj/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005254 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 15:29:52.109631) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 15:29:52.110005) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 15:29:52.110361) ========= ========= Total master_lag: 0.20 =========