builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0298 starttime: 1471968958.08 results: success (0) buildid: 20160823070554 builduid: 8e6b88c416b0411497f38d1021bc05a8 revision: 052656fc513c05da969590ac5934abd67271a897 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.080071) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.080559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.080874) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.104940) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.105511) ========= 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.MyMJTS90YT/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.UtIGNa3GrS/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-23 09:15:58-- 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.31M=0.001s 2016-08-23 09:15:58 (8.31 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127307 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.256018) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.256337) ========= 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.MyMJTS90YT/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.UtIGNa3GrS/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.020872 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.305407) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:58.305819) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 052656fc513c05da969590ac5934abd67271a897 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 052656fc513c05da969590ac5934abd67271a897 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MyMJTS90YT/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.UtIGNa3GrS/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-23 09:15:58,390 truncating revision to first 12 chars 2016-08-23 09:15:58,390 Setting DEBUG logging. 2016-08-23 09:15:58,390 attempt 1/10 2016-08-23 09:15:58,390 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/052656fc513c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 09:15:58,892 unpacking tar archive at: mozilla-central-052656fc513c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.763011 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:59.091135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:59.091479) ========= script_repo_revision: 052656fc513c05da969590ac5934abd67271a897 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:59.091866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:59.092137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:59.110888) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-23 09:15:59.111274) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MyMJTS90YT/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.UtIGNa3GrS/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:15:59 INFO - MultiFileLogger online at 20160823 09:15:59 in /builds/slave/test 09:15:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:15:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:59 INFO - {'append_to_log': False, 09:15:59 INFO - 'base_work_dir': '/builds/slave/test', 09:15:59 INFO - 'blob_upload_branch': 'Firefox', 09:15:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:59 INFO - 'branch': 'Firefox', 09:15:59 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:59 INFO - 'config_files': ('talos/mac_config.py',), 09:15:59 INFO - 'default_actions': ('clobber', 09:15:59 INFO - 'read-buildbot-config', 09:15:59 INFO - 'download-and-extract', 09:15:59 INFO - 'populate-webroot', 09:15:59 INFO - 'create-virtualenv', 09:15:59 INFO - 'install', 09:15:59 INFO - 'run-tests'), 09:15:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:59 INFO - 'download_minidump_stackwalk': True, 09:15:59 INFO - 'download_symbols': 'ondemand', 09:15:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:59 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:59 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:59 INFO - 'installer_path': 'installer.exe', 09:15:59 INFO - 'log_level': 'info', 09:15:59 INFO - 'log_name': 'talos', 09:15:59 INFO - 'log_to_console': True, 09:15:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:59 INFO - 'opt_config_files': (), 09:15:59 INFO - 'pip_index': False, 09:15:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:59 INFO - 'cmd': ('bash', 09:15:59 INFO - '-c', 09:15:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:59 INFO - 'enabled': True, 09:15:59 INFO - 'halt_on_failure': False, 09:15:59 INFO - 'name': 'check_screen_resolution'},), 09:15:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:59 INFO - 'cmd': ('bash', 09:15:59 INFO - '-c', 09:15:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:59 INFO - 'enabled': True, 09:15:59 INFO - 'halt_on_failure': False, 09:15:59 INFO - 'name': 'check_screen_resolution'},), 09:15:59 INFO - 'run_cmd_checks_enabled': True, 09:15:59 INFO - 'sps_profile': False, 09:15:59 INFO - 'sps_profile_interval': 0, 09:15:59 INFO - 'suite': 'dromaeojs', 09:15:59 INFO - 'system_bits': '32', 09:15:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:59 INFO - 'title': 't-yosemite-r7-0298', 09:15:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:59 INFO - 'use_talos_json': True, 09:15:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:59 INFO - 'work_dir': 'build'} 09:15:59 INFO - [mozharness: 2016-08-23 16:15:59.306979Z] Running clobber step. 09:15:59 INFO - Running pre-action listener: _resource_record_pre_action 09:15:59 INFO - Running main action method: clobber 09:15:59 INFO - rmtree: /builds/slave/test/build 09:15:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:00 INFO - Running post-action listener: _resource_record_post_action 09:16:00 INFO - [mozharness: 2016-08-23 16:16:00.811371Z] Finished clobber step (success) 09:16:00 INFO - [mozharness: 2016-08-23 16:16:00.811523Z] Running read-buildbot-config step. 09:16:00 INFO - Running pre-action listener: _resource_record_pre_action 09:16:00 INFO - Running main action method: read_buildbot_config 09:16:00 INFO - Using buildbot properties: 09:16:00 INFO - { 09:16:00 INFO - "project": "", 09:16:00 INFO - "product": "firefox", 09:16:00 INFO - "script_repo_revision": "production", 09:16:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:16:00 INFO - "repository": "", 09:16:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:16:00 INFO - "buildid": "20160823070554", 09:16:00 INFO - "pgo_build": "False", 09:16:00 INFO - "basedir": "/builds/slave/test", 09:16:00 INFO - "buildnumber": 4, 09:16:00 INFO - "slavename": "t-yosemite-r7-0298", 09:16:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:16:00 INFO - "platform": "yosemite_r7", 09:16:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:16:00 INFO - "branch": "mozilla-central", 09:16:00 INFO - "repo_path": "mozilla-central", 09:16:00 INFO - "revision": "052656fc513c05da969590ac5934abd67271a897", 09:16:00 INFO - "stage_platform": "macosx64", 09:16:00 INFO - "builduid": "8e6b88c416b0411497f38d1021bc05a8", 09:16:00 INFO - "slavebuilddir": "test" 09:16:00 INFO - } 09:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:16:00 INFO - Running post-action listener: _resource_record_post_action 09:16:00 INFO - [mozharness: 2016-08-23 16:16:00.814956Z] Finished read-buildbot-config step (success) 09:16:00 INFO - [mozharness: 2016-08-23 16:16:00.815064Z] Running download-and-extract step. 09:16:00 INFO - Running pre-action listener: _resource_record_pre_action 09:16:00 INFO - Running main action method: download_and_extract 09:16:00 INFO - mkdir: /builds/slave/test/build/tests 09:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:00 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:01 INFO - Downloaded 1199 bytes. 09:16:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:16:01 INFO - Using the following test package requirements: 09:16:01 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:16:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:16:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:16:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'jsshell-mac.zip'], 09:16:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:16:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:16:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:16:01 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:16:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:16:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:16:01 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:16:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:01 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:16:01 INFO - Downloading https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:02 INFO - Downloaded 16346182 bytes. 09:16:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:16:09 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 09:16:09 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')]} 09:16:09 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:16:09 INFO - Downloading https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:10 INFO - Downloaded 16346182 bytes. 09:16:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:16 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:16:16 INFO - Downloading https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:17 INFO - Downloaded 14413981 bytes. 09:16:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:16:18 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')]} 09:16:18 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:16:18 INFO - Downloading https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/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 09:16:20 INFO - Downloaded 111514633 bytes. 09:16:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:16:20 INFO - mkdir: /builds/slave/test/properties 09:16:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:20 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:20 INFO - Contents: 09:16:20 INFO - build_url:https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:16:21 INFO - Running post-action listener: _resource_record_post_action 09:16:21 INFO - Running post-action listener: set_extra_try_arguments 09:16:21 INFO - [mozharness: 2016-08-23 16:16:21.613059Z] Finished download-and-extract step (success) 09:16:21 INFO - [mozharness: 2016-08-23 16:16:21.613178Z] Running populate-webroot step. 09:16:21 INFO - Running pre-action listener: _resource_record_pre_action 09:16:21 INFO - Running main action method: populate_webroot 09:16:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:21 INFO - '--tppagecycles', 09:16:21 INFO - '7'], 09:16:21 INFO - 'tests': ['tsvgm']}}, 09:16:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['damp', 'tps']}, 09:16:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'tests': ['damp', 'tps']}, 09:16:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['dromaeo_dom']}, 09:16:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['basic_compositor_video']}, 09:16:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['a11yr', 09:16:21 INFO - 'ts_paint', 09:16:21 INFO - 'tpaint', 09:16:21 INFO - 'sessionrestore', 09:16:21 INFO - 'sessionrestore_no_auto_restore', 09:16:21 INFO - 'tabpaint']}, 09:16:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['tsvgx', 09:16:21 INFO - 'tsvgr_opacity', 09:16:21 INFO - 'tart', 09:16:21 INFO - 'tscrollx', 09:16:21 INFO - 'cart']}, 09:16:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:21 INFO - 'tsvgr_opacity', 09:16:21 INFO - 'tart', 09:16:21 INFO - 'tscrollx', 09:16:21 INFO - 'cart']}, 09:16:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'talos_options': ['--disable-e10s'], 09:16:21 INFO - 'tests': ['tp5o']}, 09:16:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'tests': ['tp5o']}, 09:16:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'talos_options': ['--disable-e10s', 09:16:21 INFO - '--xperf_path', 09:16:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:21 INFO - 'tests': ['tp5n']}, 09:16:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:16:21 INFO - 'talos_options': ['--xperf_path', 09:16:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:21 INFO - 'tests': ['tp5n']}}, 09:16:21 INFO - 'talos.zip': {'path': '', 09:16:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:21 INFO - Running post-action listener: _resource_record_post_action 09:16:21 INFO - [mozharness: 2016-08-23 16:16:21.632019Z] Finished populate-webroot step (success) 09:16:21 INFO - [mozharness: 2016-08-23 16:16:21.632148Z] Running create-virtualenv step. 09:16:21 INFO - Running pre-action listener: _resource_record_pre_action 09:16:21 INFO - Running main action method: create_virtualenv 09:16:21 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:21 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 09:16:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:21 INFO - Using real prefix '/tools/python27' 09:16:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:22 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:25 INFO - Installing pip.................done. 09:16:25 INFO - Return code: 0 09:16:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:25 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')]} 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 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')]} 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:25 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 09:16:25 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 09:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:25 INFO - 'HOME': '/Users/cltbld', 09:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PAGER': '/bin/cat', 09:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test', 09:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:25 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - 'XPC_FLAGS': '0x0', 09:16:25 INFO - 'XPC_SERVICE_NAME': '0', 09:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:25 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 09:16:25 INFO - Cleaning up... 09:16:25 INFO - Return code: 0 09:16:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:25 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')]} 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 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')]} 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:25 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 09:16:25 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 09:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:25 INFO - 'HOME': '/Users/cltbld', 09:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PAGER': '/bin/cat', 09:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test', 09:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:25 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - 'XPC_FLAGS': '0x0', 09:16:25 INFO - 'XPC_SERVICE_NAME': '0', 09:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:25 INFO - Downloading/unpacking psutil>=3.1.1 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:28 INFO - Installing collected packages: psutil 09:16:28 INFO - Running setup.py install for psutil 09:16:28 INFO - building 'psutil._psutil_osx' extension 09:16:28 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 09:16:29 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 09:16:29 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 09:16:29 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 09:16:29 INFO - building 'psutil._psutil_posix' extension 09:16:29 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 09:16:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:29 INFO - ^ 09:16:29 INFO - 1 warning generated. 09:16:29 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 09:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:29 INFO - Successfully installed psutil 09:16:29 INFO - Cleaning up... 09:16:29 INFO - Return code: 0 09:16:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:29 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 09:16:29 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 09:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:29 INFO - 'HOME': '/Users/cltbld', 09:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:29 INFO - 'LOGNAME': 'cltbld', 09:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:29 INFO - 'MOZ_NO_REMOTE': '1', 09:16:29 INFO - 'NO_EM_RESTART': '1', 09:16:29 INFO - 'PAGER': '/bin/cat', 09:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:29 INFO - 'PWD': '/builds/slave/test', 09:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:29 INFO - 'SHELL': '/bin/bash', 09:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:29 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:29 INFO - 'USER': 'cltbld', 09:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:29 INFO - 'XPC_FLAGS': '0x0', 09:16:29 INFO - 'XPC_SERVICE_NAME': '0', 09:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:29 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 09:16:29 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 09:16:29 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 09:16:29 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 09:16:29 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 09:16:29 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 09:16:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:32 INFO - Installing collected packages: mozsystemmonitor 09:16:32 INFO - Running setup.py install for mozsystemmonitor 09:16:32 INFO - Successfully installed mozsystemmonitor 09:16:32 INFO - Cleaning up... 09:16:32 INFO - Return code: 0 09:16:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:32 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:32 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 09:16:32 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 09:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:32 INFO - 'HOME': '/Users/cltbld', 09:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:32 INFO - 'LOGNAME': 'cltbld', 09:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:32 INFO - 'MOZ_NO_REMOTE': '1', 09:16:32 INFO - 'NO_EM_RESTART': '1', 09:16:32 INFO - 'PAGER': '/bin/cat', 09:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:32 INFO - 'PWD': '/builds/slave/test', 09:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:32 INFO - 'SHELL': '/bin/bash', 09:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:32 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:32 INFO - 'USER': 'cltbld', 09:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:32 INFO - 'XPC_FLAGS': '0x0', 09:16:32 INFO - 'XPC_SERVICE_NAME': '0', 09:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:32 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:32 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 09:16:32 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 09:16:32 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 09:16:32 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 09:16:32 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 09:16:32 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 09:16:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:35 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 09:16:35 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 09:16:35 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 09:16:35 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 09:16:35 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 09:16:35 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 09:16:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:35 INFO - warning: no files found matching '*.txt' 09:16:35 INFO - no previously-included directories found matching 'build' 09:16:35 INFO - no previously-included directories found matching 'dist' 09:16:35 INFO - no previously-included directories found matching '.git*' 09:16:35 INFO - Installing collected packages: jsonschema, functools32 09:16:35 INFO - Running setup.py install for functools32 09:16:36 INFO - warning: no files found matching '*.txt' 09:16:36 INFO - no previously-included directories found matching 'build' 09:16:36 INFO - no previously-included directories found matching 'dist' 09:16:36 INFO - no previously-included directories found matching '.git*' 09:16:36 INFO - Successfully installed jsonschema functools32 09:16:36 INFO - Cleaning up... 09:16:36 INFO - Return code: 0 09:16:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:36 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 09:16:36 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 09:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:36 INFO - 'HOME': '/Users/cltbld', 09:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:36 INFO - 'LOGNAME': 'cltbld', 09:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:36 INFO - 'MOZ_NO_REMOTE': '1', 09:16:36 INFO - 'NO_EM_RESTART': '1', 09:16:36 INFO - 'PAGER': '/bin/cat', 09:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:36 INFO - 'PWD': '/builds/slave/test', 09:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:36 INFO - 'SHELL': '/bin/bash', 09:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:36 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:36 INFO - 'USER': 'cltbld', 09:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:36 INFO - 'XPC_FLAGS': '0x0', 09:16:36 INFO - 'XPC_SERVICE_NAME': '0', 09:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:36 INFO - Cleaning up... 09:16:36 INFO - Return code: 0 09:16:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:36 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 09:16:36 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 09:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:36 INFO - 'HOME': '/Users/cltbld', 09:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:36 INFO - 'LOGNAME': 'cltbld', 09:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:36 INFO - 'MOZ_NO_REMOTE': '1', 09:16:36 INFO - 'NO_EM_RESTART': '1', 09:16:36 INFO - 'PAGER': '/bin/cat', 09:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:36 INFO - 'PWD': '/builds/slave/test', 09:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:36 INFO - 'SHELL': '/bin/bash', 09:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:36 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:36 INFO - 'USER': 'cltbld', 09:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:36 INFO - 'XPC_FLAGS': '0x0', 09:16:36 INFO - 'XPC_SERVICE_NAME': '0', 09:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:39 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 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 09:16:39 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 09:16:40 INFO - Downloading docopt-0.6.1.tar.gz 09:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:40 INFO - Running setup.py install for blobuploader 09:16:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:40 INFO - Running setup.py install for requests 09:16:41 INFO - Running setup.py install for docopt 09:16:41 INFO - Successfully installed blobuploader requests docopt 09:16:41 INFO - Cleaning up... 09:16:41 INFO - Return code: 0 09:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:41 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:41 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 09:16:41 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 09:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:41 INFO - 'HOME': '/Users/cltbld', 09:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:41 INFO - 'LOGNAME': 'cltbld', 09:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:41 INFO - 'MOZ_NO_REMOTE': '1', 09:16:41 INFO - 'NO_EM_RESTART': '1', 09:16:41 INFO - 'PAGER': '/bin/cat', 09:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:41 INFO - 'PWD': '/builds/slave/test', 09:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:41 INFO - 'SHELL': '/bin/bash', 09:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:41 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:41 INFO - 'USER': 'cltbld', 09:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:41 INFO - 'XPC_FLAGS': '0x0', 09:16:41 INFO - 'XPC_SERVICE_NAME': '0', 09:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:41 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-fSWauv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:41 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-XNDtVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:41 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-oD7ed6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:41 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-yPaX1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Yzxxcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-CbQzqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-iKQQsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-GXj5YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-rX74MY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-13ZTVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-consTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-opCCTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-XbBnyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:42 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-rlQaES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:43 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-DPtUCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:43 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-UBzoox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:43 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-S9RFN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:43 INFO - Running setup.py install for manifestparser 09:16:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:43 INFO - Running setup.py install for mozcrash 09:16:43 INFO - Running setup.py install for mozdebug 09:16:43 INFO - Running setup.py install for mozdevice 09:16:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:43 INFO - Running setup.py install for mozfile 09:16:44 INFO - Running setup.py install for mozhttpd 09:16:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Running setup.py install for mozinfo 09:16:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Running setup.py install for mozInstall 09:16:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Running setup.py install for mozleak 09:16:44 INFO - Running setup.py install for mozlog 09:16:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Running setup.py install for moznetwork 09:16:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:44 INFO - Running setup.py install for mozprocess 09:16:45 INFO - Running setup.py install for mozprofile 09:16:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozrunner 09:16:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Running setup.py install for mozscreenshot 09:16:45 INFO - Running setup.py install for moztest 09:16:45 INFO - Running setup.py install for mozversion 09:16:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:45 INFO - Cleaning up... 09:16:45 INFO - Return code: 0 09:16:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:45 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:45 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 09:16:45 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 09:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:45 INFO - 'HOME': '/Users/cltbld', 09:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:45 INFO - 'LOGNAME': 'cltbld', 09:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:45 INFO - 'MOZ_NO_REMOTE': '1', 09:16:45 INFO - 'NO_EM_RESTART': '1', 09:16:45 INFO - 'PAGER': '/bin/cat', 09:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:45 INFO - 'PWD': '/builds/slave/test', 09:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:45 INFO - 'SHELL': '/bin/bash', 09:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:45 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:45 INFO - 'USER': 'cltbld', 09:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:45 INFO - 'XPC_FLAGS': '0x0', 09:16:45 INFO - 'XPC_SERVICE_NAME': '0', 09:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-aOMRAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-_4PiDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-q1CpMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-AmPKfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Rf9pL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-NF1ypZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-YufNjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:46 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)) 09:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Ej1bBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-WiHvWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Q6UiiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-iCuCDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Zcr0Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-XVXOcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-YtZGQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-HqQRnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-V2aqif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:47 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)) 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-9UwHJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:48 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)) 09:16:48 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)) 09:16:48 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)) 09:16:48 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)) 09:16:48 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)) 09:16:48 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)) 09:16:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:48 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 09:16:48 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 09:16:48 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 09:16:48 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 09:16:48 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 09:16:48 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 09:16:50 INFO - Downloading blessings-1.6.tar.gz 09:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:50 INFO - Installing collected packages: blessings 09:16:50 INFO - Running setup.py install for blessings 09:16:50 INFO - Successfully installed blessings 09:16:50 INFO - Cleaning up... 09:16:50 INFO - Return code: 0 09:16:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:51 INFO - Reading from file tmpfile_stdout 09:16:51 INFO - Current package versions: 09:16:51 INFO - blessings == 1.6 09:16:51 INFO - blobuploader == 1.2.4 09:16:51 INFO - docopt == 0.6.1 09:16:51 INFO - functools32 == 3.2.3-2 09:16:51 INFO - jsonschema == 2.5.1 09:16:51 INFO - manifestparser == 1.1 09:16:51 INFO - mozInstall == 1.12 09:16:51 INFO - mozcrash == 0.17 09:16:51 INFO - mozdebug == 0.1 09:16:51 INFO - mozdevice == 0.48 09:16:51 INFO - mozfile == 1.2 09:16:51 INFO - mozhttpd == 0.7 09:16:51 INFO - mozinfo == 0.9 09:16:51 INFO - mozleak == 0.1 09:16:51 INFO - mozlog == 3.3 09:16:51 INFO - moznetwork == 0.27 09:16:51 INFO - mozprocess == 0.23 09:16:51 INFO - mozprofile == 0.28 09:16:51 INFO - mozrunner == 6.12 09:16:51 INFO - mozscreenshot == 0.1 09:16:51 INFO - mozsystemmonitor == 0.3 09:16:51 INFO - moztest == 0.7 09:16:51 INFO - mozversion == 1.4 09:16:51 INFO - psutil == 3.1.1 09:16:51 INFO - requests == 1.2.3 09:16:51 INFO - wsgiref == 0.1.2 09:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:51 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 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:51 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 09:16:51 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 09:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:51 INFO - 'HOME': '/Users/cltbld', 09:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:51 INFO - 'LOGNAME': 'cltbld', 09:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:51 INFO - 'MOZ_NO_REMOTE': '1', 09:16:51 INFO - 'NO_EM_RESTART': '1', 09:16:51 INFO - 'PAGER': '/bin/cat', 09:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:51 INFO - 'PWD': '/builds/slave/test', 09:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:51 INFO - 'SHELL': '/bin/bash', 09:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:51 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:51 INFO - 'USER': 'cltbld', 09:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:51 INFO - 'XPC_FLAGS': '0x0', 09:16:51 INFO - 'XPC_SERVICE_NAME': '0', 09:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 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)) 09:16:51 INFO - Cleaning up... 09:16:51 INFO - Return code: 0 09:16:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10699ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a30618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcb95d25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, '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.MyMJTS90YT/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.UtIGNa3GrS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 09:16:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:51 INFO - 'HOME': '/Users/cltbld', 09:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:51 INFO - 'LOGNAME': 'cltbld', 09:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:51 INFO - 'MOZ_NO_REMOTE': '1', 09:16:51 INFO - 'NO_EM_RESTART': '1', 09:16:51 INFO - 'PAGER': '/bin/cat', 09:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:51 INFO - 'PWD': '/builds/slave/test', 09:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:51 INFO - 'SHELL': '/bin/bash', 09:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:16:51 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:51 INFO - 'USER': 'cltbld', 09:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:51 INFO - 'XPC_FLAGS': '0x0', 09:16:51 INFO - 'XPC_SERVICE_NAME': '0', 09:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:51 INFO - Cleaning up... 09:16:51 INFO - Return code: 0 09:16:51 INFO - Running post-action listener: _resource_record_post_action 09:16:51 INFO - Running post-action listener: _start_resource_monitoring 09:16:51 INFO - Starting resource monitoring. 09:16:51 INFO - [mozharness: 2016-08-23 16:16:51.761347Z] Finished create-virtualenv step (success) 09:16:51 INFO - [mozharness: 2016-08-23 16:16:51.762412Z] Running install step. 09:16:51 INFO - Running pre-action listener: _resource_record_pre_action 09:16:51 INFO - Running main action method: install 09:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:52 INFO - Reading from file tmpfile_stdout 09:16:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:52 INFO - Reading from file tmpfile_stdout 09:16:52 INFO - Output received: 09:16:52 INFO - Usage: mozinstall [options] installer 09:16:52 INFO - Options: 09:16:52 INFO - -h, --help show this help message and exit 09:16:52 INFO - -d DEST, --destination=DEST 09:16:52 INFO - Directory to install application into. [default: 09:16:52 INFO - "/builds/slave/test"] 09:16:52 INFO - --app=APP Application being installed. [default: firefox] 09:16:52 INFO - mkdir: /builds/slave/test/build/application 09:16:52 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'] 09:16:52 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 09:17:26 INFO - Reading from file tmpfile_stdout 09:17:26 INFO - Output received: 09:17:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:17:26 INFO - Running post-action listener: _resource_record_post_action 09:17:26 INFO - [mozharness: 2016-08-23 16:17:26.189991Z] Finished install step (success) 09:17:26 INFO - [mozharness: 2016-08-23 16:17:26.190132Z] Running run-tests step. 09:17:26 INFO - Running pre-action listener: _resource_record_pre_action 09:17:26 INFO - Running main action method: run_tests 09:17:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:26 INFO - 2016-08-23 09:17:26.204 screenresolution[2843:46994] starting screenresolution argv=screenresolution get 09:17:26 INFO - 2016-08-23 09:17:26.207 screenresolution[2843:46994] Display 0: 1600x1200x32@60 09:17:26 INFO - 2016-08-23 09:17:26.213 screenresolution[2844:46996] starting screenresolution argv=screenresolution list 09:17:26 INFO - Available Modes on Display 0 09:17:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:26 INFO - Intel Iris: 09:17:26 INFO - Chipset Model: Intel Iris 09:17:26 INFO - Type: GPU 09:17:26 INFO - Bus: Built-In 09:17:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:26 INFO - Vendor: Intel (0x8086) 09:17:26 INFO - Device ID: 0x0a2e 09:17:26 INFO - Revision ID: 0x0009 09:17:26 INFO - Displays: 09:17:26 INFO - Display: 09:17:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:26 INFO - Display Serial Number: 1600x1200 60 09:17:26 INFO - Main Display: Yes 09:17:26 INFO - Mirror: Off 09:17:26 INFO - Online: Yes 09:17:26 INFO - Rotation: Supported 09:17:26 INFO - Return code: 0 09:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:26 INFO - Python 2.7.3 09:17:26 INFO - Return code: 0 09:17:26 INFO - grabbing minidump binary from tooltool 09:17: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')]} 09:17:26 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 0x1068ffd30>, '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 0x106664db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:26 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 09:17:26 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 09:17:26 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 09:17:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:26 INFO - Return code: 0 09:17:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 09:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 09:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyMJTS90YT/Render', 09:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:26 INFO - 'HOME': '/Users/cltbld', 09:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:26 INFO - 'LOGNAME': 'cltbld', 09:17:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:26 INFO - 'MOZ_NO_REMOTE': '1', 09:17:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:26 INFO - 'NO_EM_RESTART': '1', 09:17:26 INFO - 'PAGER': '/bin/cat', 09:17:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:26 INFO - 'PWD': '/builds/slave/test', 09:17:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:26 INFO - 'SHELL': '/bin/bash', 09:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UtIGNa3GrS/Listeners', 09:17:26 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 09:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:26 INFO - 'USER': 'cltbld', 09:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:26 INFO - 'XPC_FLAGS': '0x0', 09:17:26 INFO - 'XPC_SERVICE_NAME': '0', 09:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 09:17:26 INFO - mozversion application_buildid: 20160823070554 09:17:26 INFO - mozversion application_changeset: 052656fc513c05da969590ac5934abd67271a897 09:17:26 INFO - mozversion application_display_name: Nightly 09:17:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:26 INFO - mozversion application_name: Firefox 09:17:26 INFO - mozversion application_remotingname: firefox 09:17:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:17:26 INFO - mozversion application_vendor: Mozilla 09:17:26 INFO - mozversion application_version: 51.0a1 09:17:26 INFO - mozversion platform_buildid: 20160823070554 09:17:26 INFO - mozversion platform_changeset: 052656fc513c05da969590ac5934abd67271a897 09:17:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:17:26 INFO - mozversion platform_version: 51.0a1 09:17:26 INFO - using testdate: 1471969046 09:17:26 INFO - actual date: 1471969046 09:17:26 INFO - starting webserver on 'localhost:61136' 09:17:26 INFO - SUITE-START | Running 2 tests 09:17:26 INFO - TEST-START | dromaeo_css 09:17:26 INFO - operating with platform_type : mac_ 09:17:26 INFO - Initialising browser for dromaeo_css test... 09:17:26 INFO - TEST-INFO | started process 2854 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp_EvQPw/profile http://localhost:61136/getInfo.html) 09:17:28 INFO - PROCESS | 2854 | __metrics Screen width/height:1600/1200 09:17:28 INFO - PROCESS | 2854 | colorDepth:24 09:17:28 INFO - PROCESS | 2854 | Browser inner width/height: 1024/689 09:17:28 INFO - PROCESS | 2854 | __metrics 09:17:29 INFO - PROCESS | 2854 | 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" 09:17:29 INFO - PROCESS | 2854 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:29 INFO - TEST-INFO | 2854: exit 0 09:17:29 INFO - Browser initialized. 09:17:29 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:29 INFO - TEST-INFO | started process 2856 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp_EvQPw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:41 INFO - PROCESS | 2856 | RSS: Main: 234741760 09:17:41 INFO - PROCESS | 2856 | 09:18:33 INFO - PROCESS | 2856 | 1471969113314 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] 09:20:45 INFO - PROCESS | 2856 | Cycle 1(1): loaded http://localhost:61136/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61136/tests/dromaeo/cssquery-ext.html) 09:20:46 INFO - PROCESS | 2856 | RSS: Main: 373649408 09:20:46 INFO - PROCESS | 2856 | 09:23:49 INFO - PROCESS | 2856 | Cycle 1(1): loaded http://localhost:61136/tests/dromaeo/cssquery-ext.html (next: http://localhost:61136/tests/dromaeo/cssquery-jquery.html) 09:23:50 INFO - PROCESS | 2856 | RSS: Main: 374263808 09:23:50 INFO - PROCESS | 2856 | 09:26:52 INFO - PROCESS | 2856 | Cycle 1(1): loaded http://localhost:61136/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61136/tests/dromaeo/cssquery-mootools.html) 09:26:53 INFO - PROCESS | 2856 | RSS: Main: 334725120 09:26:53 INFO - PROCESS | 2856 | 09:29:55 INFO - PROCESS | 2856 | Cycle 1(1): loaded http://localhost:61136/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61136/tests/dromaeo/cssquery-prototype.html) 09:29:56 INFO - PROCESS | 2856 | RSS: Main: 348557312 09:29:56 INFO - PROCESS | 2856 | 09:32:59 INFO - PROCESS | 2856 | Cycle 1(1): loaded http://localhost:61136/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61136/tests/dromaeo/cssquery-yui.html) 09:33:00 INFO - PROCESS | 2856 | RSS: Main: 333959168 09:33:00 INFO - PROCESS | 2856 | 09:36:03 INFO - PROCESS | 2856 | Cycle 1(1): loaded http://localhost:61136/tests/dromaeo/cssquery-yui.html (next: http://localhost:61136/tests/dromaeo/cssquery-dojo.html) 09:36:04 INFO - PROCESS | 2856 | RSS: Main: 494759936 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | __start_tp_report 09:36:04 INFO - PROCESS | 2856 | _x_x_mozilla_page_load 09:36:04 INFO - PROCESS | 2856 | _x_x_mozilla_page_load_details 09:36:04 INFO - PROCESS | 2856 | |i|pagename|runs| 09:36:04 INFO - PROCESS | 2856 | |0;dojo.html;3265.314284000359;3278.6557411471795;3301.2407146356354;3330.4338262495376;3370.511275865;5630.1554766785;5667.23492328536;5741;5757.625754325966;5768.250127483427;5731.226284451608;5775.162601422801;5776.711164441782;5779.566532510057;5782.855428614287;5197.142471492209;5205.349331333583;5213.504717051893;5242.397124330693;5303.681779093247;5274.736263186845;5299.54953828925;5319.627626066177;5324.387695415038;5326.307580014612;5220.634555581111;5222.138347172712;5234.18870075139;5262.079136151158;5300.708461034642;5298.470152984709;5341.305630268041;5342.2787923630585;5346.652467589594;5350.973245133789;5105.617078719111;5116.948830511685;5138.563222126124;5255.86860328491;5270.420253772082;5523.889522209533;5559.527440167591;5570.303712035996;5577.163425486169;5707.571932105108;6142.8771424571705;6146.200993870812;6161.476274516672;6161.630302181883;6183.567150299481;6153.753849846001;6187.628742275478;6202.100695399187;6222.471089957359;6238.095476155932;5812.941870581282;5910;5913.852153696106;5932.169496270526;5938.554608404387;5310.548603368679;6231.127642130105;6266.874662506724;6273.905891411632;6274.090256912813;6056.666883321372;6143.232095988002;6197.070439434121;6218.22272215973;6225.190725205694;4976.601871850244;6181.629102253879;6233.096201050822;6282.2147231596055;6304.5586808922935;6206.658633775186;6273.341299163613;6275.184226050585;6282.2147231596055;6292.842678933063;6130.540209484307;6218.409251121137;6288.5598008138995;6293.37066293367;6319.494440444753;4963.230699241623;4992.226204938241;5018.096742586369;5036.942242129194;5083.923741143886;4455;4463.152001119777;4464.732116073046;4467.329900514948;4503.437070366204;5068.960863023145;5717.599768016282;5735.4551317624755;5741.741621627037;5743.59794814359;6138.725610151984;6201.131841542187;6213.502919766408;6251.374862513712;6254.562180647402;5719.113537401709;5740.167675687002;5752.539796816244;5755.597108202385;5771.538276937835;5036.697798132051;5413.404525502192;5475.917861232085;5494.835154945358;5496.862578435491;6138.602983404897;6210.347913469202;6248.4064013918605;6284.905726414199;6305.432511074117;6275.435210830865;6286.497080233479;6293.811185664437;6302;6303.401176888188;6311.652859092794;6318.6840657965995;6321.810345689637;6321.96839015802;6327.905081423874;6094.299155597163;6132.386102851662;6134.263888333428;6151.415615516519;6210.875782484235;6246.531510136667;6284.308726040041;6286.057091436322;6288.654124023223;6291.591046581958;6179.165812615419;6184.5052395809125;6192.3807619239515;6221.160143380585;6232.937670623417;6015.157877897011;6097.827165393427;6105.236845394325;6124.74712746768;6172.69136543162;5910.143029260819;6058.856898319099;6083.862415405024;6090.147379366804;6140.5394595406415;6158.884691383819;6192.535559833121;6197.876042479215;6209.534284928739;6222.844428889133;5408.972955135199;5899.026660600953;5932.0508718604815;6009.36901625323;6037.396260373927 09:36:04 INFO - PROCESS | 2856 | |1;ext.html;466.65933868276164;519.6518332717081;525.7082319312781;525.9342582177228;551.771015028763;31224;31830.840845795767;41384;42343.57656423435;42381.15237695244;44671.77664111675;45057.09885802282;45071.36607924009;45076;45096.32355514662;9732.270079744012;9941.304108712393;9964.651237206699;9965.20278377728;9966.302358834884;9933.155681767059;10038.046385593358;10075;10075.294729368947;10087.94956025219;8711.738647840575;9991.900080999207;10061.094501494865;10064.74838129049;10074.395536267848;9863.90136098637;9937.155341795957;9952.353097048668;9957.651482198089;9966.551505182211;9812.558434870376;9902.950485247527;9921.206303495776;9931.453770042644;9962.601495940155;42286.36570451446;42532;42556;42601.36097958533;42644;3414.9829250853836;3513.2446523997464;3936.6063393660406;4189.560096189917;4334.393184954109;31936.840315798505;32811.17972050694;33149.50275745866;33474.832625836956;33657;3053.5572342010505;3242.967570324314;3362.243495213582;3413.76103672743;3463.6363181865795;520397;524641;524954;526409;538126;61009.38990610037;62021.68989155025;62066;62153.68923155355;62296;64609;64615.35384646093;64659.67670161619;64665.70668586696;64668;16814.495565133064;17079.975201487952;17092;17118.229679664444;17163.313467461412;13127.671808204872;13152.868471315356;13164.539241126515;13206;13213;15780;16437.26032328548;16493.505194844172;16635.41776037833;16659.33362665504;6715.899261511081;6752.189737231563;6758.628275444898;6809.285025072395;6824.351686589766;874.8337815814975;963.9132478076866;963.9878127965608;1014.7361685961555;1129.3788416370962;29528;31083.844580777404;31167.220819479695;31411.842940785606;31664.841675791475;1086.7772106718103;1122.6239209864623;1127.5940661361903;1143.8112711402525;1198.6871049273145;15498.457553985556;16142.112183830002;16179.43371981975;16228.107454090139;16677.666446671;677876;681674;681974;683169;685379;60568.69715651393;60802.783944320865;60854.39145608576;60886;60899;1117.7876203521469;1159.4144956796763;1289.0654275650265;1394.400407824645;1409.8660627240395;29882.85058574693;31496;31953.36093278075;32410.992445430853;34081.65918340785;58457;59219.81560368855;59265.81468370694;59468.70265648644;59565;559385;581553;584831;588637;590153;26721;26902.192934212006;27057.86471067711;27107.45785084248;27108.051218207267;49012.75493622652;49168.26247606217;49731.75134124451;49955.00089998252;50540.74729626328;35301;39825.60174398219;39965.60034399735;41104;42261;39896;40766.9808254796;41479.37780933228;41730.37404438875;42339.86072019602;39452.61915832932;39907.2018559633;40119.598804012756;40279.1944161121;40552.391714124904;32660.651353446236;34897.65102348944;35411.46882796709;35717.74987875412;35779.28441431209;22784.886075569517;23387.76612233856;23758.812059397307;26781.92872285087;26813.597796033464 09:36:04 INFO - PROCESS | 2856 | |2;jquery.html;322.96770322967694;352.31474781549895;375.0885348602895;382.9234153169366;403.09304065851836;16006.03963762175;17020.574485637866;20814.687779683278;21200.25799097032;21299.68050479242;21201.363959081253;21437.8209198494;21930.793806340644;22251.888740556275;22272.66591001136;5918.66829963257;5965.821025369224;6144.385561443864;6172.722227499751;6253.593516421441;4257.680673949466;6086.17836592061;6094.1772860663905;6126.663033533154;6139.324674285847;6537.477001839865;6804.591724496546;6877;6893.862122757542;6958.582485050913;5629.521490673257;6576.80269591913;6818.897716534256;6831.69257383419;6862.588244705309;5851.38560451155;5919.970400148015;5929.525637948954;5936.287645482526;5955.285365756137;26121.477570448675;26906.730932690618;26992;27182.5922611161;27445.90216391342;2315.953680926372;2392.7607239275935;2455.7789798918006;2539.073238268037;2926.5610158476397;16212.918935405247;16237.025778453331;16403.145260863686;16408.933419327706;16696.49910502687;1878.5867109235946;1926.0562324461052;1930.9613807723767;1971.4775584470126;1995.952125134316;119297;121198.75804346894;121476.39261803635;124270.75729242682;124413;10654;10851.51168197433;11223.571190546078;11299.54801807935;11480.081593472501;14131;14132.364043618061;14134.151950882775;14143.575692729235;14190.787138192936;7091.92908070923;7618.657160427794;8503.149685031447;8715.346349023848;8841.955790221007;8773.868391974125;8777.385583009129;8800.515971621622;8998.14517620825;9001.009888912215;6706.26231114577;6831.487638427137;6841.452683785285;6842.213145488333;6851.657417129124;1702.446704820938;1834.3213011185946;1871.5788947486706;1872.0733237047477;1873.203888347447;672.9865402691919;837.6230696186618;919.9402038867452;1033.6950599573145;1070.8875568065241;22707.772922270895;23071.307860764206;23444.296671099895;23574.174903878284;23658;813.8372325534916;964.9517524123765;1007.1439276614965;1016;1061.0397590180899;10515.579376824995;11562.595309164308;12413.44139513724;12420.565280215329;12437.62687119388;464694;475569;577395;614266.9286653538;621260;82709.58645206735;95655.04311961005;98115.05654830366;144016;144386.27806860898;977.5161295158941;996.5266498413341;1007.43080159711;1042.4891803016467;1045.1116550931647;19258.90370548138;19439.8056019438;19495;19537.511562211057;19752.802471975097;54494;56327.15509267446;57404.43445975748;57470.42529574651;58362.708186458796;69222.30777692159;75696;80217.59891200507;82921.75617365865;84916;7202.855942881218;7393.334599886036;7519.43604229674;7989.680412783656;8359.665613375397;9417.952910235404;13088.738225235633;13529.661758456117;14046.016778825384;14168.008239423141;11429.542818287175;11458.94270528642;11939.16425850217;11939.880601194225;11995.700107497383;10820.5130769114;10913.727156821144;11020.393878336768;11248.381339026104;11613.419329033346;11418.371989540656;11603.767924641292;11898.405079746151;11928.701782455508;12049.216800908263;7489.550626962399;7546.358559522502;7612.200718924433;7674.9232507674205;7689.8462030757955;9550.235981121632;11568.942155289506;11824.645260642195;11869.287842729464;12034.63896083119 09:36:04 INFO - PROCESS | 2856 | |3;mootools.html;1537.31589442698;1565.3816742386757;1732.3330517750662;1851.3982955539436;1864.6270745850825;8533.317334613232;9098.590563424645;9103.135202155801;9106.908930910706;9127.543622818866;7870.212978702127;8106.797330066751;8514.91485085149;8827.646894124244;8886.46681199127;2383.570957227694;2427.1383658801137;2430.756924307564;2431.051889762994;2442.3894026493376;2388.9641655375185;2422.9394265143337;2429.975700242993;2432.963505547418;2436.3189399314506;2008.728821609086;2397.7722116399;2410.710714714237;2426.0902161689364;2437.2079074300837;2373.41851246444;2386.5823480890863;2395.197608801045;2399.2682231919257;2405.579023670868;2317.640765681322;2337.322176568793;2339.1695947938406;2339.567180071675;2347.8473899196497;8883.689070882489;8944.552772361356;8991.235744961687;8996.730098097067;9025.503597302146;5265.921011184835;5443.564514838803;5444.537214336786;5449.4005659377435;5449.427810079924;3080.8921687741044;3573.0352804742834;3622.293652737691;3651.543557055368;3653.0319465341704;1064.9041586257274;1070.347088276151;1071.9088877445427;1083.6586475260267;1090.0788833435734;10098.343607665478;11892.345920974343;11900.761984760344;11912.463939122674;11922.761544769142;4579.198640237945;4660.5106463821485;4853.708777473363;4860.586850117779;4869.391326084239;10098.091171794575;10109.898901010896;10114.696559103239;10114.797704046025;10126.848097278547;5421.945780542223;5437.619366644297;5440.129579267298;5445.047116754631;5450.373207081237;4060.715749897538;4417.293233082692;4587.38070360497;4956.677815941952;4968.205087186033;3915.334393153171;3945.5659877413464;4046.109855831712;4053.2096241233266;4149.066460046465;1097.7584931315257;1309.3191540398939;1312.8884044856295;1319.663485811112;1322.7222283320418;908.2869947091501;919.2691810011057;925.5418567808978;933.9672267823302;960.8709766024892;5767.106098554731;5863.124009607434;5976.61152025117;6032.939670603238;6132;446.5043801380465;454.9021960278556;456.7579183032998;458.6193459428733;464.30122665388376;3936.5866584008268;3999.7200195986;4063.2889244382113;4081.8571350002608;4156.418101465797;10440.947795260976;10970.232083754221;10986.340819550853;11006.95433933775;11028.0074793269;10435.530401131815;10909.23635345534;10935.617253396093;10954.287971281841;10976.835347469794;8168.346532277404;8404.117567655308;8424.705135320357;8427.662893484314;8434.746957591282;4871.634627402888;5763.9135412968;5830.708464576839;5867.32525759526;5954.791582294599;9923.55344009507;10223.079922806983;10260.743481413025;10281;10296.691099267035;9790;10253.33353332031;10274.743131421475;10357.482125893524;10441.164706823587;7051.858962820817;7286.927130728837;7291.30732580404;7657;7864.567448790142;8037.7588672339925;8115.431919765796;8136.633851476579;8308.792280193044;8606;7379.372753316028;7709.151993280847;7727.11138219112;7754.612269386621;7851.60741962911;7426.331630153312;7468.775936721907;7540;7598;7726.381889448943;6930.7227710891;7066.187388450188;7097.964510177416;7103.893441598277;7120.893186602101;4995.700257984533;5044.621653375938;5096.311997880239;5113.846584602468;5197.948020519746;5035.471275516018;5039.3448851649055;5187.144121219956;5199.740012999259;5301.098813201688 09:36:04 INFO - PROCESS | 2856 | |4;prototype.html;294.96755356910745;295.3855979562509;307.5095223119126;311.0962653491608;311.5669219784501;4120.278951183546;4608.787781864707;5245.7377131143485;5280.313559237298;5353.9196912046355;4498.955010449895;5056.519630635093;5164.39198204906;5248.343957005374;5304.92042619361;3377.527146199534;4044.6157615026527;4056.9391459128137;4059.3707975263806;4076.388541718751;3544.7164226861787;3848.557415897166;4084.4281800547947;4103.610157035077;4118.4028315894175;3744.2698673758628;3975.9602403976023;3978.5225772907293;3996.3814655064743;4007.2386246613196;3728.7576307540057;3967.841286348543;3968.9404658929993;3996.620321069494;3997.2205419943125;3171.5876935998403;3210.678932106794;3461.826908654557;3474.7915125092577;3478.8608455661742;4764.809407623691;5047.7476126193915;5137.152369858969;5156.8968620627375;5210.6613070150315;4488.349189367556;4523.231050721385;4540.727556346596;4541.364209010741;4548.8862778430475;5207.9479205207845;5505.476979686963;5509;5517.724113794295;5550.27846379972;3186.5060915558124;3631.673149416565;3724.329620668306;3767.8869633911027;3784.7161462890117;4891.190259603934;5767.452092051249;5825.621139738942;5864.912026319608;5878.412158784087;3846.7114966377076;3859.517560304962;3864.14988702485;3877.4571559981623;3879.165979314461;3342.298117395326;3887.3002859485564;3893.3381325174787;3910.7066969977363;3912.532800199925;4280.914381712349;4521.299198624219;4529.501754806968;4542.545745425497;4571.2000399929875;4590.724556526619;5574.554035677136;5583;5583.972080139573;5585.776568937197;1165.7901577715922;1242.944067516964;1259.005385745251;1285.9292738899264;1306.4518545523586;739.6301849075462;829.875518672204;875.7635438431588;898.7079199260266;907.4600612635603;4688.5545873142;4818.469968303484;5118.360204974379;5139.254808052811;5162.354705661793;4427.734335939854;5260.763265653055;5381.569474442035;5652.208690783294;5683.373460274299;4804.351412559329;5069.44236134025;5103.617228707788;5118.97440512795;5146.408163061295;2614.516314481827;2796.3428594280326;2964.399224418833;2966.679827476752;3008.7284100504417;4981.701097934136;5199.896002079937;5901.734421951022;5994.850128746729;6007.309159446632;5408.675479471244;5428.972855135699;5643.5767317451355;5868.151532267322;5910.083936989688;5989.940100598938;5995.790147344909;6016.669083200379;6032.879342413039;6044.917092282967;5439.401665816836;5802.549362659335;5826.834633073487;5866.823995280149;5869.647821130746;5640.32316122068;5682.431756824284;5705.857353566194;5712.65724056558;5725.570582206268;5008.899822003613;5995.710922151584;6002.969985150046;6056.969715151572;6103.176071230505;3377.9900958929397;3612.385894397906;3889.436031775433;3907.4138879167217;3919.5688474268377;5341.409416432949;5354.946450535601;5375.704336261543;5673.517750991212;5765.942340576708;4021.617946295097;5102.260172275073;5192.05298013245;5424.728763561885;5433.646812957145;4705.317728929354;5308;5617.5505959522325;5626.746796394254;5674.74463649145;5376.596052310181;5383.973080134574;5418.8595504776185;5526.585506086979;5536;4905.484924083063;5578;5591.552675785846;5612.663240205601;5614.943850561442;5043.773030213722;5101.336826212569;5227.607929405233;5464.1257398816;5514.420985796433 09:36:04 INFO - PROCESS | 2856 | |5;yui.html;722.9783106506807;732.2055569706868;774.8760198368259;833.7123692326145;839.1608391608391;15333.846661533395;16587.585310367245;16614.252358643877;16624.33502659895;16627.83372166278;14962.40150393983;15171;15180.013299135548;15184.772228416583;15199.544013679608;687.9071325371061;705.724767340735;841.70540310891;852.6205838401913;879.5426378283289;857.8970523537184;860.4063196394499;868.5179725252482;869.1265278395203;871.2507243770352;918.7335672654922;919.103873723118;921.235374639048;922.2852289475643;926.2266007883426;715.8353578676881;890.0965519997143;916.2166347772692;916.4913473022469;916.587535608972;663.5236598568196;670.1957650819037;683.3269229808684;684.5413572906165;689.8447849233933;15453.845461545354;15494.302756375877;15508.379664813394;15520.92239538795;15541.222938853125;13073.411696473682;14309.427622895071;14362.64093397663;14581.48964786238;14616.70766584673;13541.390637421242;13659.317034148253;13769.518066867711;13860.653483662889;14173.929130354281;4668.906621867578;4786.593139583139;4806.543378379049;4811.350467686852;4913.656044076916;121744.17325747685;124202;124832;124834.37582812119;125225;10973.50619222137;11450.541978320774;11520.94239528797;11521;11622.6513204604;21609.56780864374;23222.767772322397;23382.64926026111;23426.297211083696;23454.061837526307;8316.085230624627;8330.291925186306;8507;8666.739997800076;8761.299096072426;4426.136903303889;4450.2434586120435;4512.323151527232;4544.636429085665;4646.95353046472;3761.586225515191;3769.0577355661085;3804.8668296609485;4034.7377420467574;4034.8184331705143;709.9538529995534;720;725.2384995754437;728.4682182007059;733.3436574266002;551.7903196785196;584.1121495327079;586.4223739616474;647.4755448087064;706.8937113417485;9343.90656093444;9779.462129582942;9855.457949812686;9925.404475731479;9962.750931226632;552.8783667593123;577.2178697864417;585.7012923409092;664.6244871647503;702.9894551581731;4530.411046563926;4764.4282686077195;4779.904401911942;4784.617230621542;4800.903981920342;96500;105817;105825.41261881078;154573;178163;77243.61378193073;78559.60720196362;79870.60064699639;81318;144749;102314;112079;113157;113296.06927514994;114245.28632070526;9716.125548700651;10015.899841001497;10170.593176273162;10208.591656333663;10216.540255688364;60659;64751.676241618494;66952.6652366735;67305.99041014486;68046.9792953116;95410.04589954289;99501;100464.48374306865;100481;102901.48549257506;12966.611001669966;13143.934280328536;13433.529826456028;13451.327433628083;13608;14582.052166609137;14634.048866752788;14844.480443184868;14860.405583776528;14895.627609309853;13821.723565528431;14204.644883877985;14338.354774035402;14475;14507.029640906258;13706.24615646149;14020.439182432996;14088.436462541793;14096.295185240902;14329.355179017179;13564.660883477598;13730.107543009672;13743.450261989408;13989.720205595628;14220.644483887985;10785.568577256821;11201.495932683212;11334.94332528332;11364.988278106473;11499.310041397543;8972.506512141896;11347.659570212907;11394.94302528482;11449.255798373079;11564.479598417924 09:36:04 INFO - PROCESS | 2856 | __end_tp_report 09:36:04 INFO - PROCESS | 2856 | __start_cc_report 09:36:04 INFO - PROCESS | 2856 | _x_x_mozilla_cycle_collect,1300 09:36:04 INFO - PROCESS | 2856 | __end_cc_report 09:36:04 INFO - PROCESS | 2856 | __startTimestamp1471970164015__endTimestamp 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | ------- Summary: start ------- 09:36:04 INFO - PROCESS | 2856 | Number of tests: 6 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | [#0] dojo.html Cycles:165 Average:5752.07 Median:6015.16 stddev:642.13 (10.7%) stddev-sans-first:613.76 09:36:04 INFO - PROCESS | 2856 | Values: 3265.3 3278.7 3301.2 3330.4 3370.5 5630.2 5667.2 5741.0 5757.6 5768.3 5731.2 5775.2 5776.7 5779.6 5782.9 5197.1 5205.3 5213.5 5242.4 5303.7 5274.7 5299.5 5319.6 5324.4 5326.3 5220.6 5222.1 5234.2 5262.1 5300.7 5298.5 5341.3 5342.3 5346.7 5351.0 5105.6 5116.9 5138.6 5255.9 5270.4 5523.9 5559.5 5570.3 5577.2 5707.6 6142.9 6146.2 6161.5 6161.6 6183.6 6153.8 6187.6 6202.1 6222.5 6238.1 5812.9 5910.0 5913.9 5932.2 5938.6 5310.5 6231.1 6266.9 6273.9 6274.1 6056.7 6143.2 6197.1 6218.2 6225.2 4976.6 6181.6 6233.1 6282.2 6304.6 6206.7 6273.3 6275.2 6282.2 6292.8 6130.5 6218.4 6288.6 6293.4 6319.5 4963.2 4992.2 5018.1 5036.9 5083.9 4455.0 4463.2 4464.7 4467.3 4503.4 5069.0 5717.6 5735.5 5741.7 5743.6 6138.7 6201.1 6213.5 6251.4 6254.6 5719.1 5740.2 5752.5 5755.6 5771.5 5036.7 5413.4 5475.9 5494.8 5496.9 6138.6 6210.3 6248.4 6284.9 6305.4 6275.4 6286.5 6293.8 6302.0 6303.4 6311.7 6318.7 6321.8 6322.0 6327.9 6094.3 6132.4 6134.3 6151.4 6210.9 6246.5 6284.3 6286.1 6288.7 6291.6 6179.2 6184.5 6192.4 6221.2 6232.9 6015.2 6097.8 6105.2 6124.7 6172.7 5910.1 6058.9 6083.9 6090.1 6140.5 6158.9 6192.5 6197.9 6209.5 6222.8 5409.0 5899.0 5932.1 6009.4 6037.4 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | [#1] ext.html Cycles:180 Average:73476.57 Median:41431.69 stddev:160402.47 (387.1%) stddev-sans-first:160758.78 09:36:04 INFO - PROCESS | 2856 | Values: 466.7 519.7 525.7 525.9 551.8 31224.0 31830.8 41384.0 42343.6 42381.2 44671.8 45057.1 45071.4 45076.0 45096.3 9732.3 9941.3 9964.7 9965.2 9966.3 9933.2 10038.0 10075.0 10075.3 10087.9 8711.7 9991.9 10061.1 10064.7 10074.4 9863.9 9937.2 9952.4 9957.7 9966.6 9812.6 9903.0 9921.2 9931.5 9962.6 42286.4 42532.0 42556.0 42601.4 42644.0 3415.0 3513.2 3936.6 4189.6 4334.4 31936.8 32811.2 33149.5 33474.8 33657.0 3053.6 3243.0 3362.2 3413.8 3463.6 520397.0 524641.0 524954.0 526409.0 538126.0 61009.4 62021.7 62066.0 62153.7 62296.0 64609.0 64615.4 64659.7 64665.7 64668.0 16814.5 17080.0 17092.0 17118.2 17163.3 13127.7 13152.9 13164.5 13206.0 13213.0 15780.0 16437.3 16493.5 16635.4 16659.3 6715.9 6752.2 6758.6 6809.3 6824.4 874.8 963.9 964.0 1014.7 1129.4 29528.0 31083.8 31167.2 31411.8 31664.8 1086.8 1122.6 1127.6 1143.8 1198.7 15498.5 16142.1 16179.4 16228.1 16677.7 677876.0 681674.0 681974.0 683169.0 685379.0 60568.7 60802.8 60854.4 60886.0 60899.0 1117.8 1159.4 1289.1 1394.4 1409.9 29882.9 31496.0 31953.4 32411.0 34081.7 58457.0 59219.8 59265.8 59468.7 59565.0 559385.0 581553.0 584831.0 588637.0 590153.0 26721.0 26902.2 27057.9 27107.5 27108.1 49012.8 49168.3 49731.8 49955.0 50540.7 35301.0 39825.6 39965.6 41104.0 42261.0 39896.0 40767.0 41479.4 41730.4 42339.9 39452.6 39907.2 40119.6 40279.2 40552.4 32660.7 34897.7 35411.5 35717.7 35779.3 22784.9 23387.8 23758.8 26781.9 26813.6 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | [#2] jquery.html Cycles:180 Average:34003.95 Median:2497.43 stddev:92812.01 (3716.3%) stddev-sans-first:93037.91 09:36:04 INFO - PROCESS | 2856 | Values: 323.0 352.3 375.1 382.9 403.1 16006.0 17020.6 20814.7 21200.3 21299.7 21201.4 21437.8 21930.8 22251.9 22272.7 5918.7 5965.8 6144.4 6172.7 6253.6 4257.7 6086.2 6094.2 6126.7 6139.3 6537.5 6804.6 6877.0 6893.9 6958.6 5629.5 6576.8 6818.9 6831.7 6862.6 5851.4 5920.0 5929.5 5936.3 5955.3 26121.5 26906.7 26992.0 27182.6 27445.9 2316.0 2392.8 2455.8 2539.1 2926.6 16212.9 16237.0 16403.1 16408.9 16696.5 1878.6 1926.1 1931.0 1971.5 1996.0 119297.0 121198.8 121476.4 124270.8 124413.0 10654.0 10851.5 11223.6 11299.5 11480.1 14131.0 14132.4 14134.2 14143.6 14190.8 7091.9 7618.7 8503.1 8715.3 8842.0 8773.9 8777.4 8800.5 8998.1 9001.0 6706.3 6831.5 6841.5 6842.2 6851.7 1702.4 1834.3 1871.6 1872.1 1873.2 673.0 837.6 919.9 1033.7 1070.9 22707.8 23071.3 23444.3 23574.2 23658.0 813.8 965.0 1007.1 1016.0 1061.0 10515.6 11562.6 12413.4 12420.6 12437.6 464694.0 475569.0 577395.0 614266.9 621260.0 82709.6 95655.0 98115.1 144016.0 144386.3 977.5 996.5 1007.4 1042.5 1045.1 19258.9 19439.8 19495.0 19537.5 19752.8 54494.0 56327.2 57404.4 57470.4 58362.7 69222.3 75696.0 80217.6 82921.8 84916.0 7202.9 7393.3 7519.4 7989.7 8359.7 9418.0 13088.7 13529.7 14046.0 14168.0 11429.5 11458.9 11939.2 11939.9 11995.7 10820.5 10913.7 11020.4 11248.4 11613.4 11418.4 11603.8 11898.4 11928.7 12049.2 7489.6 7546.4 7612.2 7674.9 7689.8 9550.2 11568.9 11824.6 11869.3 12034.6 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | [#3] mootools.html Cycles:180 Average:5765.57 Median:2525.84 stddev:3255.44 (128.9%) stddev-sans-first:3249.06 09:36:04 INFO - PROCESS | 2856 | Values: 1537.3 1565.4 1732.3 1851.4 1864.6 8533.3 9098.6 9103.1 9106.9 9127.5 7870.2 8106.8 8514.9 8827.6 8886.5 2383.6 2427.1 2430.8 2431.1 2442.4 2389.0 2422.9 2430.0 2433.0 2436.3 2008.7 2397.8 2410.7 2426.1 2437.2 2373.4 2386.6 2395.2 2399.3 2405.6 2317.6 2337.3 2339.2 2339.6 2347.8 8883.7 8944.6 8991.2 8996.7 9025.5 5265.9 5443.6 5444.5 5449.4 5449.4 3080.9 3573.0 3622.3 3651.5 3653.0 1064.9 1070.3 1071.9 1083.7 1090.1 10098.3 11892.3 11900.8 11912.5 11922.8 4579.2 4660.5 4853.7 4860.6 4869.4 10098.1 10109.9 10114.7 10114.8 10126.8 5421.9 5437.6 5440.1 5445.0 5450.4 4060.7 4417.3 4587.4 4956.7 4968.2 3915.3 3945.6 4046.1 4053.2 4149.1 1097.8 1309.3 1312.9 1319.7 1322.7 908.3 919.3 925.5 934.0 960.9 5767.1 5863.1 5976.6 6032.9 6132.0 446.5 454.9 456.8 458.6 464.3 3936.6 3999.7 4063.3 4081.9 4156.4 10440.9 10970.2 10986.3 11007.0 11028.0 10435.5 10909.2 10935.6 10954.3 10976.8 8168.3 8404.1 8424.7 8427.7 8434.7 4871.6 5763.9 5830.7 5867.3 5954.8 9923.6 10223.1 10260.7 10281.0 10296.7 9790.0 10253.3 10274.7 10357.5 10441.2 7051.9 7286.9 7291.3 7657.0 7864.6 8037.8 8115.4 8136.6 8308.8 8606.0 7379.4 7709.2 7727.1 7754.6 7851.6 7426.3 7468.8 7540.0 7598.0 7726.4 6930.7 7066.2 7098.0 7103.9 7120.9 4995.7 5044.6 5096.3 5113.8 5197.9 5035.5 5039.3 5187.1 5199.7 5301.1 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | [#4] prototype.html Cycles:180 Average:4497.83 Median:5102.94 stddev:1401.83 (27.5%) stddev-sans-first:1369.81 09:36:04 INFO - PROCESS | 2856 | Values: 295.0 295.4 307.5 311.1 311.6 4120.3 4608.8 5245.7 5280.3 5353.9 4499.0 5056.5 5164.4 5248.3 5304.9 3377.5 4044.6 4056.9 4059.4 4076.4 3544.7 3848.6 4084.4 4103.6 4118.4 3744.3 3976.0 3978.5 3996.4 4007.2 3728.8 3967.8 3968.9 3996.6 3997.2 3171.6 3210.7 3461.8 3474.8 3478.9 4764.8 5047.7 5137.2 5156.9 5210.7 4488.3 4523.2 4540.7 4541.4 4548.9 5207.9 5505.5 5509.0 5517.7 5550.3 3186.5 3631.7 3724.3 3767.9 3784.7 4891.2 5767.5 5825.6 5864.9 5878.4 3846.7 3859.5 3864.1 3877.5 3879.2 3342.3 3887.3 3893.3 3910.7 3912.5 4280.9 4521.3 4529.5 4542.5 4571.2 4590.7 5574.6 5583.0 5584.0 5585.8 1165.8 1242.9 1259.0 1285.9 1306.5 739.6 829.9 875.8 898.7 907.5 4688.6 4818.5 5118.4 5139.3 5162.4 4427.7 5260.8 5381.6 5652.2 5683.4 4804.4 5069.4 5103.6 5119.0 5146.4 2614.5 2796.3 2964.4 2966.7 3008.7 4981.7 5199.9 5901.7 5994.9 6007.3 5408.7 5429.0 5643.6 5868.2 5910.1 5989.9 5995.8 6016.7 6032.9 6044.9 5439.4 5802.5 5826.8 5866.8 5869.6 5640.3 5682.4 5705.9 5712.7 5725.6 5008.9 5995.7 6003.0 6057.0 6103.2 3378.0 3612.4 3889.4 3907.4 3919.6 5341.4 5354.9 5375.7 5673.5 5765.9 4021.6 5102.3 5192.1 5424.7 5433.6 4705.3 5308.0 5617.6 5626.7 5674.7 5376.6 5384.0 5418.9 5526.6 5536.0 4905.5 5578.0 5591.6 5612.7 5614.9 5043.8 5101.3 5227.6 5464.1 5514.4 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - PROCESS | 2856 | [#5] yui.html Cycles:180 Average:24333.71 Median:13607.82 stddev:37659.60 (276.7%) stddev-sans-first:37723.52 09:36:04 INFO - PROCESS | 2856 | Values: 723.0 732.2 774.9 833.7 839.2 15333.8 16587.6 16614.3 16624.3 16627.8 14962.4 15171.0 15180.0 15184.8 15199.5 687.9 705.7 841.7 852.6 879.5 857.9 860.4 868.5 869.1 871.3 918.7 919.1 921.2 922.3 926.2 715.8 890.1 916.2 916.5 916.6 663.5 670.2 683.3 684.5 689.8 15453.8 15494.3 15508.4 15520.9 15541.2 13073.4 14309.4 14362.6 14581.5 14616.7 13541.4 13659.3 13769.5 13860.7 14173.9 4668.9 4786.6 4806.5 4811.4 4913.7 121744.2 124202.0 124832.0 124834.4 125225.0 10973.5 11450.5 11520.9 11521.0 11622.7 21609.6 23222.8 23382.6 23426.3 23454.1 8316.1 8330.3 8507.0 8666.7 8761.3 4426.1 4450.2 4512.3 4544.6 4647.0 3761.6 3769.1 3804.9 4034.7 4034.8 710.0 720.0 725.2 728.5 733.3 551.8 584.1 586.4 647.5 706.9 9343.9 9779.5 9855.5 9925.4 9962.8 552.9 577.2 585.7 664.6 703.0 4530.4 4764.4 4779.9 4784.6 4800.9 96500.0 105817.0 105825.4 154573.0 178163.0 77243.6 78559.6 79870.6 81318.0 144749.0 102314.0 112079.0 113157.0 113296.1 114245.3 9716.1 10015.9 10170.6 10208.6 10216.5 60659.0 64751.7 66952.7 67306.0 68047.0 95410.0 99501.0 100464.5 100481.0 102901.5 12966.6 13143.9 13433.5 13451.3 13608.0 14582.1 14634.0 14844.5 14860.4 14895.6 13821.7 14204.6 14338.4 14475.0 14507.0 13706.2 14020.4 14088.4 14096.3 14329.4 13564.7 13730.1 13743.5 13989.7 14220.6 10785.6 11201.5 11334.9 11365.0 11499.3 8972.5 11347.7 11394.9 11449.3 11564.5 09:36:04 INFO - PROCESS | 2856 | -------- Summary: end -------- 09:36:04 INFO - PROCESS | 2856 | 09:36:04 INFO - TEST-INFO | 2856: exit 0 09:36:04 INFO - TEST-OK | dromaeo_css | took 1117826ms 09:36:04 INFO - TEST-START | kraken 09:36:04 INFO - operating with platform_type : mac_ 09:36:04 INFO - Initialising browser for kraken test... 09:36:04 INFO - TEST-INFO | started process 2859 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpJqThZk/profile http://localhost:61136/getInfo.html) 09:36:06 INFO - PROCESS | 2859 | __metrics Screen width/height:1600/1200 09:36:06 INFO - PROCESS | 2859 | colorDepth:24 09:36:06 INFO - PROCESS | 2859 | Browser inner width/height: 1024/689 09:36:06 INFO - PROCESS | 2859 | __metrics 09:36:06 INFO - PROCESS | 2859 | 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" 09:36:06 INFO - PROCESS | 2859 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:07 INFO - TEST-INFO | 2859: exit 0 09:36:07 INFO - Browser initialized. 09:36:07 INFO - Running cycle 1/1 for kraken test... 09:36:07 INFO - TEST-INFO | started process 2860 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpJqThZk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:19 INFO - PROCESS | 2860 | RSS: Main: 234815488 09:36:19 INFO - PROCESS | 2860 | 09:36:22 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61136/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:23 INFO - PROCESS | 2860 | RSS: Main: 294006784 09:36:23 INFO - PROCESS | 2860 | 09:36:25 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61136/tests/kraken/driver.html?testName=audio-dft) 09:36:26 INFO - PROCESS | 2860 | RSS: Main: 291557376 09:36:26 INFO - PROCESS | 2860 | 09:36:30 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61136/tests/kraken/driver.html?testName=audio-fft) 09:36:30 INFO - PROCESS | 2860 | RSS: Main: 380497920 09:36:30 INFO - PROCESS | 2860 | 09:36:32 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61136/tests/kraken/driver.html?testName=audio-oscillator) 09:36:33 INFO - PROCESS | 2860 | RSS: Main: 284319744 09:36:33 INFO - PROCESS | 2860 | 09:36:35 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61136/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:36 INFO - PROCESS | 2860 | RSS: Main: 288329728 09:36:36 INFO - PROCESS | 2860 | 09:36:38 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61136/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:39 INFO - PROCESS | 2860 | RSS: Main: 327901184 09:36:39 INFO - PROCESS | 2860 | 09:36:42 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61136/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:42 INFO - PROCESS | 2860 | RSS: Main: 329543680 09:36:42 INFO - PROCESS | 2860 | 09:36:45 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61136/tests/kraken/driver.html?testName=json-parse-financial) 09:36:46 INFO - PROCESS | 2860 | RSS: Main: 327118848 09:36:46 INFO - PROCESS | 2860 | 09:36:48 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61136/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:48 INFO - PROCESS | 2860 | RSS: Main: 300183552 09:36:48 INFO - PROCESS | 2860 | 09:36:50 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:51 INFO - PROCESS | 2860 | RSS: Main: 307310592 09:36:51 INFO - PROCESS | 2860 | 09:36:53 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:54 INFO - PROCESS | 2860 | RSS: Main: 302395392 09:36:54 INFO - PROCESS | 2860 | 09:36:56 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:57 INFO - PROCESS | 2860 | RSS: Main: 313405440 09:36:57 INFO - PROCESS | 2860 | 09:37:00 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:01 INFO - PROCESS | 2860 | RSS: Main: 314888192 09:37:01 INFO - PROCESS | 2860 | 09:37:02 INFO - PROCESS | 2860 | Cycle 1(1): loaded http://localhost:61136/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61136/tests/kraken/driver.html?testName=ai-astar) 09:37:03 INFO - PROCESS | 2860 | RSS: Main: 358174720 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | __start_tp_report 09:37:03 INFO - PROCESS | 2860 | _x_x_mozilla_page_load 09:37:03 INFO - PROCESS | 2860 | _x_x_mozilla_page_load_details 09:37:03 INFO - PROCESS | 2860 | |i|pagename|runs| 09:37:03 INFO - PROCESS | 2860 | |0;ai-astar;111;158;162;112;111;113;111;111;111;119 09:37:03 INFO - PROCESS | 2860 | |1;audio-beat-detection;151;113;131;163;109;132;128;108;156;128 09:37:03 INFO - PROCESS | 2860 | |2;audio-dft;185;184;184;184;185;183;184;185;184;182 09:37:03 INFO - PROCESS | 2860 | |3;audio-fft;76;75;77;158;62;81;79;84;143;57 09:37:03 INFO - PROCESS | 2860 | |4;audio-oscillator;83;82;135;70;72;90;122;70;69;123 09:37:03 INFO - PROCESS | 2860 | |5;imaging-gaussian-blur;88;87;90;85;87;83;90;84;88;84 09:37:03 INFO - PROCESS | 2860 | |6;imaging-darkroom;92;93;93;94;94;92;92;93;91;93 09:37:03 INFO - PROCESS | 2860 | |7;imaging-desaturate;84;85;84;85;101;143;141;83;85;87 09:37:03 INFO - PROCESS | 2860 | |8;json-parse-financial;55;52;53;54;52;55;53;55;54;53 09:37:03 INFO - PROCESS | 2860 | |9;json-stringify-tinderbox;68;73;74;76;72;73;70;71;71;71 09:37:03 INFO - PROCESS | 2860 | |10;stanford-crypto-aes;88;86;86;84;83;85;84;83;84;88 09:37:03 INFO - PROCESS | 2860 | |11;stanford-crypto-ccm;138;137;135;135;140;134;135;137;155;138 09:37:03 INFO - PROCESS | 2860 | |12;stanford-crypto-pbkdf2;170;166;167;167;166;166;167;173;166;164 09:37:03 INFO - PROCESS | 2860 | |13;stanford-crypto-sha256-iterative;66;67;68;68;66;68;68;67;66;65 09:37:03 INFO - PROCESS | 2860 | __end_tp_report 09:37:03 INFO - PROCESS | 2860 | __start_cc_report 09:37:03 INFO - PROCESS | 2860 | _x_x_mozilla_cycle_collect,2194 09:37:03 INFO - PROCESS | 2860 | __end_cc_report 09:37:03 INFO - PROCESS | 2860 | __startTimestamp1471970223040__endTimestamp 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | ------- Summary: start ------- 09:37:03 INFO - PROCESS | 2860 | Number of tests: 14 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#0] ai-astar Cycles:10 Average:121.90 Median:112.50 stddev:20.25 (18.0%) stddev-sans-first:21.09 09:37:03 INFO - PROCESS | 2860 | Values: 111.0 158.0 162.0 112.0 111.0 113.0 111.0 111.0 111.0 119.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:131.50 stddev:19.43 (14.8%) stddev-sans-first:19.34 09:37:03 INFO - PROCESS | 2860 | Values: 151.0 113.0 131.0 163.0 109.0 132.0 128.0 108.0 156.0 128.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:0.94 (0.5%) stddev-sans-first:0.93 09:37:03 INFO - PROCESS | 2860 | Values: 185.0 184.0 184.0 184.0 185.0 183.0 184.0 185.0 184.0 182.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#3] audio-fft Cycles:10 Average:89.20 Median:76.50 stddev:33.55 (43.9%) stddev-sans-first:35.24 09:37:03 INFO - PROCESS | 2860 | Values: 76.0 75.0 77.0 158.0 62.0 81.0 79.0 84.0 143.0 57.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:71.00 stddev:25.36 (35.7%) stddev-sans-first:26.71 09:37:03 INFO - PROCESS | 2860 | Values: 83.0 82.0 135.0 70.0 72.0 90.0 122.0 70.0 69.0 123.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:2.50 (2.9%) stddev-sans-first:2.60 09:37:03 INFO - PROCESS | 2860 | Values: 88.0 87.0 90.0 85.0 87.0 83.0 90.0 84.0 88.0 84.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.95 (1.0%) stddev-sans-first:0.97 09:37:03 INFO - PROCESS | 2860 | Values: 92.0 93.0 93.0 94.0 94.0 92.0 92.0 93.0 91.0 93.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#7] imaging-desaturate Cycles:10 Average:97.80 Median:84.50 stddev:23.87 (28.2%) stddev-sans-first:24.79 09:37:03 INFO - PROCESS | 2860 | Values: 84.0 85.0 84.0 85.0 101.0 143.0 141.0 83.0 85.0 87.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.17 (2.2%) stddev-sans-first:1.13 09:37:03 INFO - PROCESS | 2860 | Values: 55.0 52.0 53.0 54.0 52.0 55.0 53.0 55.0 54.0 53.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:2.23 (3.1%) stddev-sans-first:1.87 09:37:03 INFO - PROCESS | 2860 | Values: 68.0 73.0 74.0 76.0 72.0 73.0 70.0 71.0 71.0 71.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.50 stddev:1.85 (2.2%) stddev-sans-first:1.64 09:37:03 INFO - PROCESS | 2860 | Values: 88.0 86.0 86.0 84.0 83.0 85.0 84.0 83.0 84.0 88.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:137.50 stddev:6.11 (4.4%) stddev-sans-first:6.48 09:37:03 INFO - PROCESS | 2860 | Values: 138.0 137.0 135.0 135.0 140.0 134.0 135.0 137.0 155.0 138.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.00 stddev:2.53 (1.5%) stddev-sans-first:2.47 09:37:03 INFO - PROCESS | 2860 | Values: 170.0 166.0 167.0 167.0 166.0 166.0 167.0 173.0 166.0 164.0 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - PROCESS | 2860 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.50 stddev:1.10 (1.6%) stddev-sans-first:1.12 09:37:03 INFO - PROCESS | 2860 | Values: 66.0 67.0 68.0 68.0 66.0 68.0 68.0 67.0 66.0 65.0 09:37:03 INFO - PROCESS | 2860 | -------- Summary: end -------- 09:37:03 INFO - PROCESS | 2860 | 09:37:03 INFO - TEST-INFO | 2860: exit 0 09:37:03 INFO - TEST-OK | kraken | took 59266ms 09:37:03 INFO - SUITE-END | took 1177s 09:37:04 INFO - Completed test suite (00:19:37) 09:37:04 INFO - javascript benchmark 09:37:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.314284000359, 3278.6557411471795, 3301.2407146356354, 3330.4338262495376, 3370.511275865, 5630.1554766785, 5667.23492328536, 5741.0, 5757.625754325966, 5768.250127483427, 5731.226284451608, 5775.162601422801, 5776.711164441782, 5779.566532510057, 5782.855428614287, 5197.142471492209, 5205.349331333583, 5213.504717051893, 5242.397124330693, 5303.681779093247, 5274.736263186845, 5299.54953828925, 5319.627626066177, 5324.387695415038, 5326.307580014612, 5220.634555581111, 5222.138347172712, 5234.18870075139, 5262.079136151158, 5300.708461034642, 5298.470152984709, 5341.305630268041, 5342.2787923630585, 5346.652467589594, 5350.973245133789, 5105.617078719111, 5116.948830511685, 5138.563222126124, 5255.86860328491, 5270.420253772082, 5523.889522209533, 5559.527440167591, 5570.303712035996, 5577.163425486169, 5707.571932105108, 6142.8771424571705, 6146.200993870812, 6161.476274516672, 6161.630302181883, 6183.567150299481, 6153.753849846001, 6187.628742275478, 6202.100695399187, 6222.471089957359, 6238.095476155932, 5812.941870581282, 5910.0, 5913.852153696106, 5932.169496270526, 5938.554608404387, 5310.548603368679, 6231.127642130105, 6266.874662506724, 6273.905891411632, 6274.090256912813, 6056.666883321372, 6143.232095988002, 6197.070439434121, 6218.22272215973, 6225.190725205694, 4976.601871850244, 6181.629102253879, 6233.096201050822, 6282.2147231596055, 6304.5586808922935, 6206.658633775186, 6273.341299163613, 6275.184226050585, 6282.2147231596055, 6292.842678933063, 6130.540209484307, 6218.409251121137, 6288.5598008138995, 6293.37066293367, 6319.494440444753, 4963.230699241623, 4992.226204938241, 5018.096742586369, 5036.942242129194, 5083.923741143886, 4455.0, 4463.152001119777, 4464.732116073046, 4467.329900514948, 4503.437070366204, 5068.960863023145, 5717.599768016282, 5735.4551317624755, 5741.741621627037, 5743.59794814359, 6138.725610151984, 6201.131841542187, 6213.502919766408, 6251.374862513712, 6254.562180647402, 5719.113537401709, 5740.167675687002, 5752.539796816244, 5755.597108202385, 5771.538276937835, 5036.697798132051, 5413.404525502192, 5475.917861232085, 5494.835154945358, 5496.862578435491, 6138.602983404897, 6210.347913469202, 6248.4064013918605, 6284.905726414199, 6305.432511074117, 6275.435210830865, 6286.497080233479, 6293.811185664437, 6302.0, 6303.401176888188, 6311.652859092794, 6318.6840657965995, 6321.810345689637, 6321.96839015802, 6327.905081423874, 6094.299155597163, 6132.386102851662, 6134.263888333428, 6151.415615516519, 6210.875782484235, 6246.531510136667, 6284.308726040041, 6286.057091436322, 6288.654124023223, 6291.591046581958, 6179.165812615419, 6184.5052395809125, 6192.3807619239515, 6221.160143380585, 6232.937670623417, 6015.157877897011, 6097.827165393427, 6105.236845394325, 6124.74712746768, 6172.69136543162, 5910.143029260819, 6058.856898319099, 6083.862415405024, 6090.147379366804, 6140.5394595406415, 6158.884691383819, 6192.535559833121, 6197.876042479215, 6209.534284928739, 6222.844428889133, 5408.972955135199, 5899.026660600953, 5932.0508718604815, 6009.36901625323, 6037.396260373927], "value": 5710.906606970966, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.65933868276164, 519.6518332717081, 525.7082319312781, 525.9342582177228, 551.771015028763, 31224.0, 31830.840845795767, 41384.0, 42343.57656423435, 42381.15237695244, 44671.77664111675, 45057.09885802282, 45071.36607924009, 45076.0, 45096.32355514662, 9732.270079744012, 9941.304108712393, 9964.651237206699, 9965.20278377728, 9966.302358834884, 9933.155681767059, 10038.046385593358, 10075.0, 10075.294729368947, 10087.94956025219, 8711.738647840575, 9991.900080999207, 10061.094501494865, 10064.74838129049, 10074.395536267848, 9863.90136098637, 9937.155341795957, 9952.353097048668, 9957.651482198089, 9966.551505182211, 9812.558434870376, 9902.950485247527, 9921.206303495776, 9931.453770042644, 9962.601495940155, 42286.36570451446, 42532.0, 42556.0, 42601.36097958533, 42644.0, 3414.9829250853836, 3513.2446523997464, 3936.6063393660406, 4189.560096189917, 4334.393184954109, 31936.840315798505, 32811.17972050694, 33149.50275745866, 33474.832625836956, 33657.0, 3053.5572342010505, 3242.967570324314, 3362.243495213582, 3413.76103672743, 3463.6363181865795, 520397.0, 524641.0, 524954.0, 526409.0, 538126.0, 61009.38990610037, 62021.68989155025, 62066.0, 62153.68923155355, 62296.0, 64609.0, 64615.35384646093, 64659.67670161619, 64665.70668586696, 64668.0, 16814.495565133064, 17079.975201487952, 17092.0, 17118.229679664444, 17163.313467461412, 13127.671808204872, 13152.868471315356, 13164.539241126515, 13206.0, 13213.0, 15780.0, 16437.26032328548, 16493.505194844172, 16635.41776037833, 16659.33362665504, 6715.899261511081, 6752.189737231563, 6758.628275444898, 6809.285025072395, 6824.351686589766, 874.8337815814975, 963.9132478076866, 963.9878127965608, 1014.7361685961555, 1129.3788416370962, 29528.0, 31083.844580777404, 31167.220819479695, 31411.842940785606, 31664.841675791475, 1086.7772106718103, 1122.6239209864623, 1127.5940661361903, 1143.8112711402525, 1198.6871049273145, 15498.457553985556, 16142.112183830002, 16179.43371981975, 16228.107454090139, 16677.666446671, 677876.0, 681674.0, 681974.0, 683169.0, 685379.0, 60568.69715651393, 60802.783944320865, 60854.39145608576, 60886.0, 60899.0, 1117.7876203521469, 1159.4144956796763, 1289.0654275650265, 1394.400407824645, 1409.8660627240395, 29882.85058574693, 31496.0, 31953.36093278075, 32410.992445430853, 34081.65918340785, 58457.0, 59219.81560368855, 59265.81468370694, 59468.70265648644, 59565.0, 559385.0, 581553.0, 584831.0, 588637.0, 590153.0, 26721.0, 26902.192934212006, 27057.86471067711, 27107.45785084248, 27108.051218207267, 49012.75493622652, 49168.26247606217, 49731.75134124451, 49955.00089998252, 50540.74729626328, 35301.0, 39825.60174398219, 39965.60034399735, 41104.0, 42261.0, 39896.0, 40766.9808254796, 41479.37780933228, 41730.37404438875, 42339.86072019602, 39452.61915832932, 39907.2018559633, 40119.598804012756, 40279.1944161121, 40552.391714124904, 32660.651353446236, 34897.65102348944, 35411.46882796709, 35717.74987875412, 35779.28441431209, 22784.886075569517, 23387.76612233856, 23758.812059397307, 26781.92872285087, 26813.597796033464], "value": 20599.065115043333, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.96770322967694, 352.31474781549895, 375.0885348602895, 382.9234153169366, 403.09304065851836, 16006.03963762175, 17020.574485637866, 20814.687779683278, 21200.25799097032, 21299.68050479242, 21201.363959081253, 21437.8209198494, 21930.793806340644, 22251.888740556275, 22272.66591001136, 5918.66829963257, 5965.821025369224, 6144.385561443864, 6172.722227499751, 6253.593516421441, 4257.680673949466, 6086.17836592061, 6094.1772860663905, 6126.663033533154, 6139.324674285847, 6537.477001839865, 6804.591724496546, 6877.0, 6893.862122757542, 6958.582485050913, 5629.521490673257, 6576.80269591913, 6818.897716534256, 6831.69257383419, 6862.588244705309, 5851.38560451155, 5919.970400148015, 5929.525637948954, 5936.287645482526, 5955.285365756137, 26121.477570448675, 26906.730932690618, 26992.0, 27182.5922611161, 27445.90216391342, 2315.953680926372, 2392.7607239275935, 2455.7789798918006, 2539.073238268037, 2926.5610158476397, 16212.918935405247, 16237.025778453331, 16403.145260863686, 16408.933419327706, 16696.49910502687, 1878.5867109235946, 1926.0562324461052, 1930.9613807723767, 1971.4775584470126, 1995.952125134316, 119297.0, 121198.75804346894, 121476.39261803635, 124270.75729242682, 124413.0, 10654.0, 10851.51168197433, 11223.571190546078, 11299.54801807935, 11480.081593472501, 14131.0, 14132.364043618061, 14134.151950882775, 14143.575692729235, 14190.787138192936, 7091.92908070923, 7618.657160427794, 8503.149685031447, 8715.346349023848, 8841.955790221007, 8773.868391974125, 8777.385583009129, 8800.515971621622, 8998.14517620825, 9001.009888912215, 6706.26231114577, 6831.487638427137, 6841.452683785285, 6842.213145488333, 6851.657417129124, 1702.446704820938, 1834.3213011185946, 1871.5788947486706, 1872.0733237047477, 1873.203888347447, 672.9865402691919, 837.6230696186618, 919.9402038867452, 1033.6950599573145, 1070.8875568065241, 22707.772922270895, 23071.307860764206, 23444.296671099895, 23574.174903878284, 23658.0, 813.8372325534916, 964.9517524123765, 1007.1439276614965, 1016.0, 1061.0397590180899, 10515.579376824995, 11562.595309164308, 12413.44139513724, 12420.565280215329, 12437.62687119388, 464694.0, 475569.0, 577395.0, 614266.9286653538, 621260.0, 82709.58645206735, 95655.04311961005, 98115.05654830366, 144016.0, 144386.27806860898, 977.5161295158941, 996.5266498413341, 1007.43080159711, 1042.4891803016467, 1045.1116550931647, 19258.90370548138, 19439.8056019438, 19495.0, 19537.511562211057, 19752.802471975097, 54494.0, 56327.15509267446, 57404.43445975748, 57470.42529574651, 58362.708186458796, 69222.30777692159, 75696.0, 80217.59891200507, 82921.75617365865, 84916.0, 7202.855942881218, 7393.334599886036, 7519.43604229674, 7989.680412783656, 8359.665613375397, 9417.952910235404, 13088.738225235633, 13529.661758456117, 14046.016778825384, 14168.008239423141, 11429.542818287175, 11458.94270528642, 11939.16425850217, 11939.880601194225, 11995.700107497383, 10820.5130769114, 10913.727156821144, 11020.393878336768, 11248.381339026104, 11613.419329033346, 11418.371989540656, 11603.767924641292, 11898.405079746151, 11928.701782455508, 12049.216800908263, 7489.550626962399, 7546.358559522502, 7612.200718924433, 7674.9232507674205, 7689.8462030757955, 9550.235981121632, 11568.942155289506, 11824.645260642195, 11869.287842729464, 12034.63896083119], "value": 9984.630143706454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.31589442698, 1565.3816742386757, 1732.3330517750662, 1851.3982955539436, 1864.6270745850825, 8533.317334613232, 9098.590563424645, 9103.135202155801, 9106.908930910706, 9127.543622818866, 7870.212978702127, 8106.797330066751, 8514.91485085149, 8827.646894124244, 8886.46681199127, 2383.570957227694, 2427.1383658801137, 2430.756924307564, 2431.051889762994, 2442.3894026493376, 2388.9641655375185, 2422.9394265143337, 2429.975700242993, 2432.963505547418, 2436.3189399314506, 2008.728821609086, 2397.7722116399, 2410.710714714237, 2426.0902161689364, 2437.2079074300837, 2373.41851246444, 2386.5823480890863, 2395.197608801045, 2399.2682231919257, 2405.579023670868, 2317.640765681322, 2337.322176568793, 2339.1695947938406, 2339.567180071675, 2347.8473899196497, 8883.689070882489, 8944.552772361356, 8991.235744961687, 8996.730098097067, 9025.503597302146, 5265.921011184835, 5443.564514838803, 5444.537214336786, 5449.4005659377435, 5449.427810079924, 3080.8921687741044, 3573.0352804742834, 3622.293652737691, 3651.543557055368, 3653.0319465341704, 1064.9041586257274, 1070.347088276151, 1071.9088877445427, 1083.6586475260267, 1090.0788833435734, 10098.343607665478, 11892.345920974343, 11900.761984760344, 11912.463939122674, 11922.761544769142, 4579.198640237945, 4660.5106463821485, 4853.708777473363, 4860.586850117779, 4869.391326084239, 10098.091171794575, 10109.898901010896, 10114.696559103239, 10114.797704046025, 10126.848097278547, 5421.945780542223, 5437.619366644297, 5440.129579267298, 5445.047116754631, 5450.373207081237, 4060.715749897538, 4417.293233082692, 4587.38070360497, 4956.677815941952, 4968.205087186033, 3915.334393153171, 3945.5659877413464, 4046.109855831712, 4053.2096241233266, 4149.066460046465, 1097.7584931315257, 1309.3191540398939, 1312.8884044856295, 1319.663485811112, 1322.7222283320418, 908.2869947091501, 919.2691810011057, 925.5418567808978, 933.9672267823302, 960.8709766024892, 5767.106098554731, 5863.124009607434, 5976.61152025117, 6032.939670603238, 6132.0, 446.5043801380465, 454.9021960278556, 456.7579183032998, 458.6193459428733, 464.30122665388376, 3936.5866584008268, 3999.7200195986, 4063.2889244382113, 4081.8571350002608, 4156.418101465797, 10440.947795260976, 10970.232083754221, 10986.340819550853, 11006.95433933775, 11028.0074793269, 10435.530401131815, 10909.23635345534, 10935.617253396093, 10954.287971281841, 10976.835347469794, 8168.346532277404, 8404.117567655308, 8424.705135320357, 8427.662893484314, 8434.746957591282, 4871.634627402888, 5763.9135412968, 5830.708464576839, 5867.32525759526, 5954.791582294599, 9923.55344009507, 10223.079922806983, 10260.743481413025, 10281.0, 10296.691099267035, 9790.0, 10253.33353332031, 10274.743131421475, 10357.482125893524, 10441.164706823587, 7051.858962820817, 7286.927130728837, 7291.30732580404, 7657.0, 7864.567448790142, 8037.7588672339925, 8115.431919765796, 8136.633851476579, 8308.792280193044, 8606.0, 7379.372753316028, 7709.151993280847, 7727.11138219112, 7754.612269386621, 7851.60741962911, 7426.331630153312, 7468.775936721907, 7540.0, 7598.0, 7726.381889448943, 6930.7227710891, 7066.187388450188, 7097.964510177416, 7103.893441598277, 7120.893186602101, 4995.700257984533, 5044.621653375938, 5096.311997880239, 5113.846584602468, 5197.948020519746, 5035.471275516018, 5039.3448851649055, 5187.144121219956, 5199.740012999259, 5301.098813201688], "value": 4556.059230578906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.96755356910745, 295.3855979562509, 307.5095223119126, 311.0962653491608, 311.5669219784501, 4120.278951183546, 4608.787781864707, 5245.7377131143485, 5280.313559237298, 5353.9196912046355, 4498.955010449895, 5056.519630635093, 5164.39198204906, 5248.343957005374, 5304.92042619361, 3377.527146199534, 4044.6157615026527, 4056.9391459128137, 4059.3707975263806, 4076.388541718751, 3544.7164226861787, 3848.557415897166, 4084.4281800547947, 4103.610157035077, 4118.4028315894175, 3744.2698673758628, 3975.9602403976023, 3978.5225772907293, 3996.3814655064743, 4007.2386246613196, 3728.7576307540057, 3967.841286348543, 3968.9404658929993, 3996.620321069494, 3997.2205419943125, 3171.5876935998403, 3210.678932106794, 3461.826908654557, 3474.7915125092577, 3478.8608455661742, 4764.809407623691, 5047.7476126193915, 5137.152369858969, 5156.8968620627375, 5210.6613070150315, 4488.349189367556, 4523.231050721385, 4540.727556346596, 4541.364209010741, 4548.8862778430475, 5207.9479205207845, 5505.476979686963, 5509.0, 5517.724113794295, 5550.27846379972, 3186.5060915558124, 3631.673149416565, 3724.329620668306, 3767.8869633911027, 3784.7161462890117, 4891.190259603934, 5767.452092051249, 5825.621139738942, 5864.912026319608, 5878.412158784087, 3846.7114966377076, 3859.517560304962, 3864.14988702485, 3877.4571559981623, 3879.165979314461, 3342.298117395326, 3887.3002859485564, 3893.3381325174787, 3910.7066969977363, 3912.532800199925, 4280.914381712349, 4521.299198624219, 4529.501754806968, 4542.545745425497, 4571.2000399929875, 4590.724556526619, 5574.554035677136, 5583.0, 5583.972080139573, 5585.776568937197, 1165.7901577715922, 1242.944067516964, 1259.005385745251, 1285.9292738899264, 1306.4518545523586, 739.6301849075462, 829.875518672204, 875.7635438431588, 898.7079199260266, 907.4600612635603, 4688.5545873142, 4818.469968303484, 5118.360204974379, 5139.254808052811, 5162.354705661793, 4427.734335939854, 5260.763265653055, 5381.569474442035, 5652.208690783294, 5683.373460274299, 4804.351412559329, 5069.44236134025, 5103.617228707788, 5118.97440512795, 5146.408163061295, 2614.516314481827, 2796.3428594280326, 2964.399224418833, 2966.679827476752, 3008.7284100504417, 4981.701097934136, 5199.896002079937, 5901.734421951022, 5994.850128746729, 6007.309159446632, 5408.675479471244, 5428.972855135699, 5643.5767317451355, 5868.151532267322, 5910.083936989688, 5989.940100598938, 5995.790147344909, 6016.669083200379, 6032.879342413039, 6044.917092282967, 5439.401665816836, 5802.549362659335, 5826.834633073487, 5866.823995280149, 5869.647821130746, 5640.32316122068, 5682.431756824284, 5705.857353566194, 5712.65724056558, 5725.570582206268, 5008.899822003613, 5995.710922151584, 6002.969985150046, 6056.969715151572, 6103.176071230505, 3377.9900958929397, 3612.385894397906, 3889.436031775433, 3907.4138879167217, 3919.5688474268377, 5341.409416432949, 5354.946450535601, 5375.704336261543, 5673.517750991212, 5765.942340576708, 4021.617946295097, 5102.260172275073, 5192.05298013245, 5424.728763561885, 5433.646812957145, 4705.317728929354, 5308.0, 5617.5505959522325, 5626.746796394254, 5674.74463649145, 5376.596052310181, 5383.973080134574, 5418.8595504776185, 5526.585506086979, 5536.0, 4905.484924083063, 5578.0, 5591.552675785846, 5612.663240205601, 5614.943850561442, 5043.773030213722, 5101.336826212569, 5227.607929405233, 5464.1257398816, 5514.420985796433], "value": 4046.8648223978503, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.9783106506807, 732.2055569706868, 774.8760198368259, 833.7123692326145, 839.1608391608391, 15333.846661533395, 16587.585310367245, 16614.252358643877, 16624.33502659895, 16627.83372166278, 14962.40150393983, 15171.0, 15180.013299135548, 15184.772228416583, 15199.544013679608, 687.9071325371061, 705.724767340735, 841.70540310891, 852.6205838401913, 879.5426378283289, 857.8970523537184, 860.4063196394499, 868.5179725252482, 869.1265278395203, 871.2507243770352, 918.7335672654922, 919.103873723118, 921.235374639048, 922.2852289475643, 926.2266007883426, 715.8353578676881, 890.0965519997143, 916.2166347772692, 916.4913473022469, 916.587535608972, 663.5236598568196, 670.1957650819037, 683.3269229808684, 684.5413572906165, 689.8447849233933, 15453.845461545354, 15494.302756375877, 15508.379664813394, 15520.92239538795, 15541.222938853125, 13073.411696473682, 14309.427622895071, 14362.64093397663, 14581.48964786238, 14616.70766584673, 13541.390637421242, 13659.317034148253, 13769.518066867711, 13860.653483662889, 14173.929130354281, 4668.906621867578, 4786.593139583139, 4806.543378379049, 4811.350467686852, 4913.656044076916, 121744.17325747685, 124202.0, 124832.0, 124834.37582812119, 125225.0, 10973.50619222137, 11450.541978320774, 11520.94239528797, 11521.0, 11622.6513204604, 21609.56780864374, 23222.767772322397, 23382.64926026111, 23426.297211083696, 23454.061837526307, 8316.085230624627, 8330.291925186306, 8507.0, 8666.739997800076, 8761.299096072426, 4426.136903303889, 4450.2434586120435, 4512.323151527232, 4544.636429085665, 4646.95353046472, 3761.586225515191, 3769.0577355661085, 3804.8668296609485, 4034.7377420467574, 4034.8184331705143, 709.9538529995534, 720.0, 725.2384995754437, 728.4682182007059, 733.3436574266002, 551.7903196785196, 584.1121495327079, 586.4223739616474, 647.4755448087064, 706.8937113417485, 9343.90656093444, 9779.462129582942, 9855.457949812686, 9925.404475731479, 9962.750931226632, 552.8783667593123, 577.2178697864417, 585.7012923409092, 664.6244871647503, 702.9894551581731, 4530.411046563926, 4764.4282686077195, 4779.904401911942, 4784.617230621542, 4800.903981920342, 96500.0, 105817.0, 105825.41261881078, 154573.0, 178163.0, 77243.61378193073, 78559.60720196362, 79870.60064699639, 81318.0, 144749.0, 102314.0, 112079.0, 113157.0, 113296.06927514994, 114245.28632070526, 9716.125548700651, 10015.899841001497, 10170.593176273162, 10208.591656333663, 10216.540255688364, 60659.0, 64751.676241618494, 66952.6652366735, 67305.99041014486, 68046.9792953116, 95410.04589954289, 99501.0, 100464.48374306865, 100481.0, 102901.48549257506, 12966.611001669966, 13143.934280328536, 13433.529826456028, 13451.327433628083, 13608.0, 14582.052166609137, 14634.048866752788, 14844.480443184868, 14860.405583776528, 14895.627609309853, 13821.723565528431, 14204.644883877985, 14338.354774035402, 14475.0, 14507.029640906258, 13706.24615646149, 14020.439182432996, 14088.436462541793, 14096.295185240902, 14329.355179017179, 13564.660883477598, 13730.107543009672, 13743.450261989408, 13989.720205595628, 14220.644483887985, 10785.568577256821, 11201.495932683212, 11334.94332528332, 11364.988278106473, 11499.310041397543, 8972.506512141896, 11347.659570212907, 11394.94302528482, 11449.255798373079, 11564.479598417924], "value": 8036.203563447632, "unit": "score"}], "name": "dromaeo_css", "value": 7472.203380934801, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 158.0, 162.0, 112.0, 111.0, 113.0, 111.0, 111.0, 111.0, 119.0], "value": 121.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 131.0, 163.0, 109.0, 132.0, 128.0, 108.0, 156.0, 128.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 184.0, 185.0, 183.0, 184.0, 185.0, 184.0, 182.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 158.0, 62.0, 81.0, 79.0, 84.0, 143.0, 57.0], "value": 89.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 135.0, 70.0, 72.0, 90.0, 122.0, 70.0, 69.0, 123.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 85.0, 87.0, 83.0, 90.0, 84.0, 88.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 94.0, 92.0, 92.0, 93.0, 91.0, 93.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 85.0, 101.0, 143.0, 141.0, 83.0, 85.0, 87.0], "value": 97.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 54.0, 52.0, 55.0, 53.0, 55.0, 54.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 74.0, 76.0, 72.0, 73.0, 70.0, 71.0, 71.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 84.0, 83.0, 85.0, 84.0, 83.0, 84.0, 88.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 135.0, 135.0, 140.0, 134.0, 135.0, 137.0, 155.0, 138.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 167.0, 167.0, 166.0, 166.0, 167.0, 173.0, 166.0, 164.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 68.0, 66.0, 68.0, 68.0, 67.0, 66.0, 65.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1478.8000000000002, "alertThreshold": 2.0}]} 09:37:04 INFO - Return code: 0 09:37:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:04 INFO - # TBPL SUCCESS # 09:37:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:04 INFO - 2016-08-23 09:37:04.356 screenresolution[2864:48945] starting screenresolution argv=screenresolution get 09:37:04 INFO - 2016-08-23 09:37:04.358 screenresolution[2864:48945] Display 0: 1600x1200x32@60 09:37:04 INFO - 2016-08-23 09:37:04.364 screenresolution[2865:48948] starting screenresolution argv=screenresolution list 09:37:04 INFO - Available Modes on Display 0 09:37:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:04 INFO - Intel Iris: 09:37:04 INFO - Chipset Model: Intel Iris 09:37:04 INFO - Type: GPU 09:37:04 INFO - Bus: Built-In 09:37:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:04 INFO - Vendor: Intel (0x8086) 09:37:04 INFO - Device ID: 0x0a2e 09:37:04 INFO - Revision ID: 0x0009 09:37:04 INFO - Displays: 09:37:04 INFO - Display: 09:37:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:04 INFO - Display Serial Number: 1600x1200 60 09:37:04 INFO - Main Display: Yes 09:37:04 INFO - Mirror: Off 09:37:04 INFO - Online: Yes 09:37:04 INFO - Rotation: Supported 09:37:04 INFO - Return code: 0 09:37:04 INFO - Running post-action listener: _resource_record_post_action 09:37:04 INFO - [mozharness: 2016-08-23 16:37:04.602497Z] Finished run-tests step (success) 09:37:04 INFO - Running post-run listener: _resource_record_post_run 09:37:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 139264; Write bytes: 705146368; Read time: 1; Write time: 1992 09:37:04 INFO - TinderboxPrint: CPU usage
53.6% 09:37:04 INFO - TinderboxPrint: I/O read bytes / time
139,264 / 1 09:37:04 INFO - TinderboxPrint: I/O write bytes / time
705,146,368 / 1,992 09:37:04 INFO - TinderboxPrint: CPU idle
2,251.2 (46.4%) 09:37:04 INFO - TinderboxPrint: CPU system
973.3 (20.1%) 09:37:04 INFO - TinderboxPrint: CPU user
1,625.3 (33.5%) 09:37:04 INFO - TinderboxPrint: Swap in / out
405,082,112 / 0 09:37:04 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398836224; Write bytes: 394209280; Read time: 29494; Write time: 841 09:37:04 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 131072; Write bytes: 295011840; Read time: 1; Write time: 1108 09:37:05 INFO - Running post-run listener: _upload_blobber_files 09:37:05 INFO - Blob upload gear active. 09:37:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:05 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 09:37:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:37:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:37:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:06 INFO - (blobuploader) - INFO - Done attempting. 09:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:37:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:07 INFO - (blobuploader) - INFO - Done attempting. 09:37:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:10 INFO - (blobuploader) - INFO - Done attempting. 09:37:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:10 INFO - Return code: 0 09:37:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccdced0199176aed63df35b553457cc3ca5df304ccae7f3b8d539acb410dcd07c5057d19c65b6e0f6f24a66b920b6aab660b8d4146490d4a2edbb4e6a7b599f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35d09b83a03b50cee339ff8f0d9c83aa281983c7cada60956928ab5fbdf47ea9a9a0e830e281f5e77c22744c4c8ae1cc2bf735411c7679ad3ea997aadba9b0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b082f54d2441c9cdaa1bb01a008edcc62c7b0bbe7a8457fc07caca4c41b4898db83721c8329b4fc2f6cff0337e6afb4157843443350300fc72121bcdde0809e6"} 09:37:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:10 INFO - Contents: 09:37:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccdced0199176aed63df35b553457cc3ca5df304ccae7f3b8d539acb410dcd07c5057d19c65b6e0f6f24a66b920b6aab660b8d4146490d4a2edbb4e6a7b599f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35d09b83a03b50cee339ff8f0d9c83aa281983c7cada60956928ab5fbdf47ea9a9a0e830e281f5e77c22744c4c8ae1cc2bf735411c7679ad3ea997aadba9b0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b082f54d2441c9cdaa1bb01a008edcc62c7b0bbe7a8457fc07caca4c41b4898db83721c8329b4fc2f6cff0337e6afb4157843443350300fc72121bcdde0809e6"} 09:37:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:10 INFO - Copying logs to upload dir... 09:37:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.494020 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-23 09:37:10.647295) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 09:37:10.651480) ========= 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.MyMJTS90YT/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.UtIGNa3GrS/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/Firefox/sha512/ccdced0199176aed63df35b553457cc3ca5df304ccae7f3b8d539acb410dcd07c5057d19c65b6e0f6f24a66b920b6aab660b8d4146490d4a2edbb4e6a7b599f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35d09b83a03b50cee339ff8f0d9c83aa281983c7cada60956928ab5fbdf47ea9a9a0e830e281f5e77c22744c4c8ae1cc2bf735411c7679ad3ea997aadba9b0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b082f54d2441c9cdaa1bb01a008edcc62c7b0bbe7a8457fc07caca4c41b4898db83721c8329b4fc2f6cff0337e6afb4157843443350300fc72121bcdde0809e6"} build_url:https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ccdced0199176aed63df35b553457cc3ca5df304ccae7f3b8d539acb410dcd07c5057d19c65b6e0f6f24a66b920b6aab660b8d4146490d4a2edbb4e6a7b599f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35d09b83a03b50cee339ff8f0d9c83aa281983c7cada60956928ab5fbdf47ea9a9a0e830e281f5e77c22744c4c8ae1cc2bf735411c7679ad3ea997aadba9b0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b082f54d2441c9cdaa1bb01a008edcc62c7b0bbe7a8457fc07caca4c41b4898db83721c8329b4fc2f6cff0337e6afb4157843443350300fc72121bcdde0809e6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 09:37:10.690058) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:37:10.690455) ========= 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.MyMJTS90YT/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.UtIGNa3GrS/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005342 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:37:10.740391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 09:37:10.740724) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 09:37:10.741137) ========= ========= Total master_lag: 0.19 =========