builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1471968911.63 results: success (0) buildid: 20160823070554 builduid: 8e6b88c416b0411497f38d1021bc05a8 revision: 052656fc513c05da969590ac5934abd67271a897 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.645533) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.646061) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.646342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.672085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.672456) ========= 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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:11-- 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% 16.2M=0.001s 2016-08-23 09:15:11 (16.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147853 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.847575) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.847902) ========= 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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.025851 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:11.899608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 09:15:11.899954) ========= 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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:11,972 truncating revision to first 12 chars 2016-08-23 09:15:11,972 Setting DEBUG logging. 2016-08-23 09:15:11,972 attempt 1/10 2016-08-23 09:15:11,972 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:13,068 unpacking tar archive at: mozilla-central-052656fc513c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.362973 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 09:15:13.286545) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:13.286901) ========= script_repo_revision: 052656fc513c05da969590ac5934abd67271a897 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:13.287285) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:13.287547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 09:15:13.307190) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-23 09:15:13.307572) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:13 INFO - MultiFileLogger online at 20160823 09:15:13 in /builds/slave/test 09:15:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:15:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:13 INFO - {'append_to_log': False, 09:15:13 INFO - 'base_work_dir': '/builds/slave/test', 09:15:13 INFO - 'blob_upload_branch': 'Firefox', 09:15:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:13 INFO - 'branch': 'Firefox', 09:15:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:13 INFO - 'config_files': ('talos/mac_config.py',), 09:15:13 INFO - 'default_actions': ('clobber', 09:15:13 INFO - 'read-buildbot-config', 09:15:13 INFO - 'download-and-extract', 09:15:13 INFO - 'populate-webroot', 09:15:13 INFO - 'create-virtualenv', 09:15:13 INFO - 'install', 09:15:13 INFO - 'run-tests'), 09:15:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:13 INFO - 'download_minidump_stackwalk': True, 09:15:13 INFO - 'download_symbols': 'ondemand', 09:15:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:13 INFO - 'installer_path': 'installer.exe', 09:15:13 INFO - 'log_level': 'info', 09:15:13 INFO - 'log_name': 'talos', 09:15:13 INFO - 'log_to_console': True, 09:15:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:13 INFO - 'opt_config_files': (), 09:15:13 INFO - 'pip_index': False, 09:15:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:13 INFO - 'cmd': ('bash', 09:15:13 INFO - '-c', 09:15:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:13 INFO - 'enabled': True, 09:15:13 INFO - 'halt_on_failure': False, 09:15:13 INFO - 'name': 'check_screen_resolution'},), 09:15:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:13 INFO - 'cmd': ('bash', 09:15:13 INFO - '-c', 09:15:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:13 INFO - 'enabled': True, 09:15:13 INFO - 'halt_on_failure': False, 09:15:13 INFO - 'name': 'check_screen_resolution'},), 09:15:13 INFO - 'run_cmd_checks_enabled': True, 09:15:13 INFO - 'sps_profile': False, 09:15:13 INFO - 'sps_profile_interval': 0, 09:15:13 INFO - 'suite': 'dromaeojs-e10s', 09:15:13 INFO - 'system_bits': '32', 09:15:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:13 INFO - 'title': 't-yosemite-r7-0123', 09:15:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:13 INFO - 'use_talos_json': True, 09:15:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:13 INFO - 'work_dir': 'build'} 09:15:13 INFO - [mozharness: 2016-08-23 16:15:13.522291Z] Running clobber step. 09:15:13 INFO - Running pre-action listener: _resource_record_pre_action 09:15:13 INFO - Running main action method: clobber 09:15:13 INFO - rmtree: /builds/slave/test/build 09:15:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:15 INFO - Running post-action listener: _resource_record_post_action 09:15:15 INFO - [mozharness: 2016-08-23 16:15:15.772868Z] Finished clobber step (success) 09:15:15 INFO - [mozharness: 2016-08-23 16:15:15.773116Z] Running read-buildbot-config step. 09:15:15 INFO - Running pre-action listener: _resource_record_pre_action 09:15:15 INFO - Running main action method: read_buildbot_config 09:15:15 INFO - Using buildbot properties: 09:15:15 INFO - { 09:15:15 INFO - "project": "", 09:15:15 INFO - "product": "firefox", 09:15:15 INFO - "script_repo_revision": "production", 09:15:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:15:15 INFO - "repository": "", 09:15:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:15:15 INFO - "buildid": "20160823070554", 09:15:15 INFO - "pgo_build": "False", 09:15:15 INFO - "basedir": "/builds/slave/test", 09:15:15 INFO - "buildnumber": 38, 09:15:15 INFO - "slavename": "t-yosemite-r7-0123", 09:15:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:15:15 INFO - "platform": "yosemite_r7", 09:15:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:15:15 INFO - "branch": "mozilla-central", 09:15:15 INFO - "repo_path": "mozilla-central", 09:15:15 INFO - "revision": "052656fc513c05da969590ac5934abd67271a897", 09:15:15 INFO - "stage_platform": "macosx64", 09:15:15 INFO - "builduid": "8e6b88c416b0411497f38d1021bc05a8", 09:15:15 INFO - "slavebuilddir": "test" 09:15:15 INFO - } 09:15:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:15:15 INFO - Running post-action listener: _resource_record_post_action 09:15:15 INFO - [mozharness: 2016-08-23 16:15:15.778772Z] Finished read-buildbot-config step (success) 09:15:15 INFO - [mozharness: 2016-08-23 16:15:15.778996Z] Running download-and-extract step. 09:15:15 INFO - Running pre-action listener: _resource_record_pre_action 09:15:15 INFO - Running main action method: download_and_extract 09:15:15 INFO - mkdir: /builds/slave/test/build/tests 09:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:15 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:15:15 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:15:15 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:15:20 INFO - Downloaded 1199 bytes. 09:15:20 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:15:20 INFO - Using the following test package requirements: 09:15:20 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:15:20 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:15:20 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:15:20 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'jsshell-mac.zip'], 09:15:20 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:15:20 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:15:20 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:15:20 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:15:20 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:15:20 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:15:20 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:15:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:20 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:15:20 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:15:20 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:15:21 INFO - Downloaded 16346182 bytes. 09:15:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:15:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:28 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:15:28 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:15:28 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:15:29 INFO - Downloaded 16346182 bytes. 09:15:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:15: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:15:36 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:15:36 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:15:36 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:15:37 INFO - Downloaded 14413981 bytes. 09:15:37 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:37 INFO - trying https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:15:37 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:15:37 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:15:42 INFO - Downloaded 111514633 bytes. 09:15:42 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:15:42 INFO - mkdir: /builds/slave/test/properties 09:15:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:42 INFO - Contents: 09:15:42 INFO - build_url:https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:15:42 INFO - Running post-action listener: _resource_record_post_action 09:15:42 INFO - Running post-action listener: set_extra_try_arguments 09:15:42 INFO - [mozharness: 2016-08-23 16:15:42.889848Z] Finished download-and-extract step (success) 09:15:42 INFO - [mozharness: 2016-08-23 16:15:42.889952Z] Running populate-webroot step. 09:15:42 INFO - Running pre-action listener: _resource_record_pre_action 09:15:42 INFO - Running main action method: populate_webroot 09:15:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:42 INFO - '--tppagecycles', 09:15:42 INFO - '7'], 09:15:42 INFO - 'tests': ['tsvgm']}}, 09:15:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['damp', 'tps']}, 09:15:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'tests': ['damp', 'tps']}, 09:15:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['dromaeo_dom']}, 09:15:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['basic_compositor_video']}, 09:15:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:15:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['a11yr', 09:15:42 INFO - 'ts_paint', 09:15:42 INFO - 'tpaint', 09:15:42 INFO - 'sessionrestore', 09:15:42 INFO - 'sessionrestore_no_auto_restore', 09:15:42 INFO - 'tabpaint']}, 09:15:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:42 INFO - 'ts_paint', 09:15:42 INFO - 'tpaint', 09:15:42 INFO - 'sessionrestore', 09:15:42 INFO - 'sessionrestore_no_auto_restore', 09:15:42 INFO - 'tabpaint']}, 09:15:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:42 INFO - 'ts_paint', 09:15:42 INFO - 'tpaint', 09:15:42 INFO - 'sessionrestore', 09:15:42 INFO - 'sessionrestore_no_auto_restore', 09:15:42 INFO - 'tabpaint']}, 09:15:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:42 INFO - 'ts_paint', 09:15:42 INFO - 'tpaint', 09:15:42 INFO - 'sessionrestore', 09:15:42 INFO - 'sessionrestore_no_auto_restore', 09:15:42 INFO - 'tabpaint']}, 09:15:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['a11yr', 09:15:42 INFO - 'ts_paint', 09:15:42 INFO - 'tpaint', 09:15:42 INFO - 'sessionrestore', 09:15:42 INFO - 'sessionrestore_no_auto_restore', 09:15:42 INFO - 'tabpaint']}, 09:15:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['a11yr', 09:15:42 INFO - 'ts_paint', 09:15:42 INFO - 'tpaint', 09:15:42 INFO - 'sessionrestore', 09:15:42 INFO - 'sessionrestore_no_auto_restore', 09:15:42 INFO - 'tabpaint']}, 09:15:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['tsvgx', 09:15:42 INFO - 'tsvgr_opacity', 09:15:42 INFO - 'tart', 09:15:42 INFO - 'tscrollx', 09:15:42 INFO - 'cart']}, 09:15:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:42 INFO - 'tsvgr_opacity', 09:15:42 INFO - 'tart', 09:15:42 INFO - 'tscrollx', 09:15:42 INFO - 'cart']}, 09:15:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'talos_options': ['--disable-e10s'], 09:15:42 INFO - 'tests': ['tp5o']}, 09:15:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'tests': ['tp5o']}, 09:15:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'talos_options': ['--disable-e10s', 09:15:42 INFO - '--xperf_path', 09:15:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:42 INFO - 'tests': ['tp5n']}, 09:15:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:42 INFO - 'talos_options': ['--xperf_path', 09:15:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:42 INFO - 'tests': ['tp5n']}}, 09:15:42 INFO - 'talos.zip': {'path': '', 09:15:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:42 INFO - Running post-action listener: _resource_record_post_action 09:15:42 INFO - [mozharness: 2016-08-23 16:15:42.908824Z] Finished populate-webroot step (success) 09:15:42 INFO - [mozharness: 2016-08-23 16:15:42.908962Z] Running create-virtualenv step. 09:15:42 INFO - Running pre-action listener: _resource_record_pre_action 09:15:42 INFO - Running main action method: create_virtualenv 09:15:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:42 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:15:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:43 INFO - Using real prefix '/tools/python27' 09:15:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:46 INFO - Installing pip.................done. 09:15:46 INFO - Return code: 0 09:15:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:46 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:15:46 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:15:46 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:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:46 INFO - 'HOME': '/Users/cltbld', 09:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:46 INFO - 'LOGNAME': 'cltbld', 09:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:46 INFO - 'MOZ_NO_REMOTE': '1', 09:15:46 INFO - 'NO_EM_RESTART': '1', 09:15:46 INFO - 'PAGER': '/bin/cat', 09:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:46 INFO - 'PWD': '/builds/slave/test', 09:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:46 INFO - 'SHELL': '/bin/bash', 09:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:15:46 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:46 INFO - 'USER': 'cltbld', 09:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:46 INFO - 'XPC_FLAGS': '0x0', 09:15:46 INFO - 'XPC_SERVICE_NAME': '0', 09:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:46 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:15:46 INFO - Cleaning up... 09:15:46 INFO - Return code: 0 09:15:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:46 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:15:46 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:15:46 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:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:46 INFO - 'HOME': '/Users/cltbld', 09:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:46 INFO - 'LOGNAME': 'cltbld', 09:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:46 INFO - 'MOZ_NO_REMOTE': '1', 09:15:46 INFO - 'NO_EM_RESTART': '1', 09:15:46 INFO - 'PAGER': '/bin/cat', 09:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:46 INFO - 'PWD': '/builds/slave/test', 09:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:46 INFO - 'SHELL': '/bin/bash', 09:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:15:46 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:46 INFO - 'USER': 'cltbld', 09:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:46 INFO - 'XPC_FLAGS': '0x0', 09:15:46 INFO - 'XPC_SERVICE_NAME': '0', 09:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:47 INFO - Downloading/unpacking psutil>=3.1.1 09:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:50 INFO - Installing collected packages: psutil 09:15:50 INFO - Running setup.py install for psutil 09:15:50 INFO - building 'psutil._psutil_osx' extension 09:15:50 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:15:51 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:15:51 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:15:51 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:15:51 INFO - building 'psutil._psutil_posix' extension 09:15:51 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:15:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:15:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:15:51 INFO - ^ 09:15:51 INFO - 1 warning generated. 09:15:51 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:15:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:51 INFO - Successfully installed psutil 09:15:51 INFO - Cleaning up... 09:15:51 INFO - Return code: 0 09:15:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15: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:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:15: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 mozsystemmonitor==0.3 09:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:51 INFO - 'HOME': '/Users/cltbld', 09:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:51 INFO - 'LOGNAME': 'cltbld', 09:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:51 INFO - 'MOZ_NO_REMOTE': '1', 09:15:51 INFO - 'NO_EM_RESTART': '1', 09:15:51 INFO - 'PAGER': '/bin/cat', 09:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:51 INFO - 'PWD': '/builds/slave/test', 09:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:51 INFO - 'SHELL': '/bin/bash', 09:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:15:51 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:51 INFO - 'USER': 'cltbld', 09:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:51 INFO - 'XPC_FLAGS': '0x0', 09:15:51 INFO - 'XPC_SERVICE_NAME': '0', 09:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:54 INFO - Installing collected packages: mozsystemmonitor 09:15:54 INFO - Running setup.py install for mozsystemmonitor 09:15:54 INFO - Successfully installed mozsystemmonitor 09:15:54 INFO - Cleaning up... 09:15:54 INFO - Return code: 0 09:15:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15:54 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:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:54 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:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:54 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:15:54 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:15:54 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:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:54 INFO - 'HOME': '/Users/cltbld', 09:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:54 INFO - 'LOGNAME': 'cltbld', 09:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:54 INFO - 'MOZ_NO_REMOTE': '1', 09:15:54 INFO - 'NO_EM_RESTART': '1', 09:15:54 INFO - 'PAGER': '/bin/cat', 09:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:54 INFO - 'PWD': '/builds/slave/test', 09:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:54 INFO - 'SHELL': '/bin/bash', 09:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:15:54 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:54 INFO - 'USER': 'cltbld', 09:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:54 INFO - 'XPC_FLAGS': '0x0', 09:15:54 INFO - 'XPC_SERVICE_NAME': '0', 09:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:54 INFO - Downloading/unpacking jsonschema==2.5.1 09:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:57 INFO - warning: no files found matching '*.txt' 09:15:57 INFO - no previously-included directories found matching 'build' 09:15:57 INFO - no previously-included directories found matching 'dist' 09:15:57 INFO - no previously-included directories found matching '.git*' 09:15:57 INFO - Installing collected packages: jsonschema, functools32 09:15:57 INFO - Running setup.py install for functools32 09:15:58 INFO - warning: no files found matching '*.txt' 09:15:58 INFO - no previously-included directories found matching 'build' 09:15:58 INFO - no previously-included directories found matching 'dist' 09:15:58 INFO - no previously-included directories found matching '.git*' 09:15:58 INFO - Successfully installed jsonschema functools32 09:15:58 INFO - Cleaning up... 09:15:58 INFO - Return code: 0 09:15:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:15:58 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:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:15:58 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:15:58 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:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:58 INFO - 'HOME': '/Users/cltbld', 09:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:58 INFO - 'LOGNAME': 'cltbld', 09:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:58 INFO - 'MOZ_NO_REMOTE': '1', 09:15:58 INFO - 'NO_EM_RESTART': '1', 09:15:58 INFO - 'PAGER': '/bin/cat', 09:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:58 INFO - 'PWD': '/builds/slave/test', 09:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:58 INFO - 'SHELL': '/bin/bash', 09:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:15:58 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:58 INFO - 'USER': 'cltbld', 09:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:58 INFO - 'XPC_FLAGS': '0x0', 09:15:58 INFO - 'XPC_SERVICE_NAME': '0', 09:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:15:58 INFO - Cleaning up... 09:15:58 INFO - Return code: 0 09:15:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:58 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:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:15:58 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:15:58 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:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:58 INFO - 'HOME': '/Users/cltbld', 09:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:58 INFO - 'LOGNAME': 'cltbld', 09:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:58 INFO - 'MOZ_NO_REMOTE': '1', 09:15:58 INFO - 'NO_EM_RESTART': '1', 09:15:58 INFO - 'PAGER': '/bin/cat', 09:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:58 INFO - 'PWD': '/builds/slave/test', 09:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:58 INFO - 'SHELL': '/bin/bash', 09:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:15:58 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:58 INFO - 'USER': 'cltbld', 09:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:58 INFO - 'XPC_FLAGS': '0x0', 09:15:58 INFO - 'XPC_SERVICE_NAME': '0', 09:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:15:58 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:15:58 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:15:58 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:15:58 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:15:58 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:15:58 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:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:01 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:01 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:01 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:01 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:01 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:01 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:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:01 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:01 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:01 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:01 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:01 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:01 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:02 INFO - Downloading docopt-0.6.1.tar.gz 09:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:02 INFO - Running setup.py install for blobuploader 09:16:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:02 INFO - Running setup.py install for requests 09:16:03 INFO - Running setup.py install for docopt 09:16:03 INFO - Successfully installed blobuploader requests docopt 09:16:03 INFO - Cleaning up... 09:16:03 INFO - Return code: 0 09:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:03 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:03 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:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:03 INFO - 'HOME': '/Users/cltbld', 09:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PAGER': '/bin/cat', 09:16:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:16:03 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - 'XPC_FLAGS': '0x0', 09:16:03 INFO - 'XPC_SERVICE_NAME': '0', 09:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:03 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ksvJSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:03 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BfJNS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-95npBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-PJyID8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-z2bLzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GO3Fhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7hRW_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-PSwz9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JYppFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-8CsrIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-O7W4Ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-n9cpT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_0SPKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-SbWbe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-CAPXMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-A9HscT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LCpujE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:05 INFO - Running setup.py install for manifestparser 09:16:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Running setup.py install for mozcrash 09:16:05 INFO - Running setup.py install for mozdebug 09:16:06 INFO - Running setup.py install for mozdevice 09:16:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozfile 09:16:06 INFO - Running setup.py install for mozhttpd 09:16:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozinfo 09:16:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozInstall 09:16:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozleak 09:16:06 INFO - Running setup.py install for mozlog 09:16:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:07 INFO - Running setup.py install for moznetwork 09:16:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:07 INFO - Running setup.py install for mozprocess 09:16:07 INFO - Running setup.py install for mozprofile 09:16:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:07 INFO - Running setup.py install for mozrunner 09:16:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:07 INFO - Running setup.py install for mozscreenshot 09:16:07 INFO - Running setup.py install for moztest 09:16:08 INFO - Running setup.py install for mozversion 09:16:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:08 INFO - Cleaning up... 09:16:08 INFO - Return code: 0 09:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:08 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:08 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:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:08 INFO - 'HOME': '/Users/cltbld', 09:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:08 INFO - 'LOGNAME': 'cltbld', 09:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:08 INFO - 'MOZ_NO_REMOTE': '1', 09:16:08 INFO - 'NO_EM_RESTART': '1', 09:16:08 INFO - 'PAGER': '/bin/cat', 09:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:08 INFO - 'PWD': '/builds/slave/test', 09:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:08 INFO - 'SHELL': '/bin/bash', 09:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:16:08 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:08 INFO - 'USER': 'cltbld', 09:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:08 INFO - 'XPC_FLAGS': '0x0', 09:16:08 INFO - 'XPC_SERVICE_NAME': '0', 09:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hgCApH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-O01sxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1EoGB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1eEl3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:08 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:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0Gv0Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6_LhUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fgdbl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-FzCrPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-2KQlMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-AtVyOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BRdpAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-iQQXS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-YzimZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:09 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:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-WXOFte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:10 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:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yAMok3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:10 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:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vWDuTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:10 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:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-5ASGR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:10 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:10 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:10 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:10 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:10 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:10 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:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:13 INFO - Downloading blessings-1.6.tar.gz 09:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:13 INFO - Installing collected packages: blessings 09:16:13 INFO - Running setup.py install for blessings 09:16:13 INFO - Successfully installed blessings 09:16:13 INFO - Cleaning up... 09:16:13 INFO - Return code: 0 09:16:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:13 INFO - Reading from file tmpfile_stdout 09:16:13 INFO - Current package versions: 09:16:13 INFO - blessings == 1.6 09:16:13 INFO - blobuploader == 1.2.4 09:16:13 INFO - docopt == 0.6.1 09:16:13 INFO - functools32 == 3.2.3-2 09:16:13 INFO - jsonschema == 2.5.1 09:16:13 INFO - manifestparser == 1.1 09:16:13 INFO - mozInstall == 1.12 09:16:13 INFO - mozcrash == 0.17 09:16:13 INFO - mozdebug == 0.1 09:16:13 INFO - mozdevice == 0.48 09:16:13 INFO - mozfile == 1.2 09:16:13 INFO - mozhttpd == 0.7 09:16:13 INFO - mozinfo == 0.9 09:16:13 INFO - mozleak == 0.1 09:16:13 INFO - mozlog == 3.3 09:16:13 INFO - moznetwork == 0.27 09:16:13 INFO - mozprocess == 0.23 09:16:13 INFO - mozprofile == 0.28 09:16:13 INFO - mozrunner == 6.12 09:16:13 INFO - mozscreenshot == 0.1 09:16:13 INFO - mozsystemmonitor == 0.3 09:16:13 INFO - moztest == 0.7 09:16:13 INFO - mozversion == 1.4 09:16:13 INFO - psutil == 3.1.1 09:16:13 INFO - requests == 1.2.3 09:16:13 INFO - wsgiref == 0.1.2 09:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:13 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:13 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:13 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:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:13 INFO - 'HOME': '/Users/cltbld', 09:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:13 INFO - 'LOGNAME': 'cltbld', 09:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:13 INFO - 'MOZ_NO_REMOTE': '1', 09:16:13 INFO - 'NO_EM_RESTART': '1', 09:16:13 INFO - 'PAGER': '/bin/cat', 09:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:13 INFO - 'PWD': '/builds/slave/test', 09:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:13 INFO - 'SHELL': '/bin/bash', 09:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:16:13 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:13 INFO - 'USER': 'cltbld', 09:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:13 INFO - 'XPC_FLAGS': '0x0', 09:16:13 INFO - 'XPC_SERVICE_NAME': '0', 09:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 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:14 INFO - Cleaning up... 09:16:14 INFO - Return code: 0 09:16:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:14 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:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:14 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:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:14 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 0x10356da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103602618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fd2f021f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, '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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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:14 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:14 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:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:16:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:14 INFO - 'HOME': '/Users/cltbld', 09:16:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:14 INFO - 'LOGNAME': 'cltbld', 09:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:14 INFO - 'MOZ_NO_REMOTE': '1', 09:16:14 INFO - 'NO_EM_RESTART': '1', 09:16:14 INFO - 'PAGER': '/bin/cat', 09:16:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:14 INFO - 'PWD': '/builds/slave/test', 09:16:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:14 INFO - 'SHELL': '/bin/bash', 09:16:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:16:14 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:16:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:14 INFO - 'USER': 'cltbld', 09:16:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:14 INFO - 'XPC_FLAGS': '0x0', 09:16:14 INFO - 'XPC_SERVICE_NAME': '0', 09:16:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:14 INFO - Cleaning up... 09:16:14 INFO - Return code: 0 09:16:14 INFO - Running post-action listener: _resource_record_post_action 09:16:14 INFO - Running post-action listener: _start_resource_monitoring 09:16:14 INFO - Starting resource monitoring. 09:16:14 INFO - [mozharness: 2016-08-23 16:16:14.313855Z] Finished create-virtualenv step (success) 09:16:14 INFO - [mozharness: 2016-08-23 16:16:14.314838Z] Running install step. 09:16:14 INFO - Running pre-action listener: _resource_record_pre_action 09:16:14 INFO - Running main action method: install 09:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:14 INFO - Reading from file tmpfile_stdout 09:16:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:14 INFO - Reading from file tmpfile_stdout 09:16:14 INFO - Output received: 09:16:14 INFO - Usage: mozinstall [options] installer 09:16:14 INFO - Options: 09:16:14 INFO - -h, --help show this help message and exit 09:16:14 INFO - -d DEST, --destination=DEST 09:16:14 INFO - Directory to install application into. [default: 09:16:14 INFO - "/builds/slave/test"] 09:16:14 INFO - --app=APP Application being installed. [default: firefox] 09:16:14 INFO - mkdir: /builds/slave/test/build/application 09:16:14 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:14 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:16:49 INFO - Reading from file tmpfile_stdout 09:16:49 INFO - Output received: 09:16:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:16:49 INFO - Running post-action listener: _resource_record_post_action 09:16:49 INFO - [mozharness: 2016-08-23 16:16:49.792008Z] Finished install step (success) 09:16:49 INFO - [mozharness: 2016-08-23 16:16:49.792182Z] Running run-tests step. 09:16:49 INFO - Running pre-action listener: _resource_record_pre_action 09:16:49 INFO - Running main action method: run_tests 09:16:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:49 INFO - 2016-08-23 09:16:49.806 screenresolution[1635:7627] starting screenresolution argv=screenresolution get 09:16:49 INFO - 2016-08-23 09:16:49.809 screenresolution[1635:7627] Display 0: 1600x1200x32@60 09:16:49 INFO - 2016-08-23 09:16:49.815 screenresolution[1636:7629] starting screenresolution argv=screenresolution list 09:16:49 INFO - Available Modes on Display 0 09:16:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:50 INFO - Intel Iris: 09:16:50 INFO - Chipset Model: Intel Iris 09:16:50 INFO - Type: GPU 09:16:50 INFO - Bus: Built-In 09:16:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:50 INFO - Vendor: Intel (0x8086) 09:16:50 INFO - Device ID: 0x0a2e 09:16:50 INFO - Revision ID: 0x0009 09:16:50 INFO - Displays: 09:16:50 INFO - Display: 09:16:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:50 INFO - Display Serial Number: 1600x1200 60 09:16:50 INFO - Main Display: Yes 09:16:50 INFO - Mirror: Off 09:16:50 INFO - Online: Yes 09:16:50 INFO - Rotation: Supported 09:16:50 INFO - Return code: 0 09:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:50 INFO - Python 2.7.3 09:16:50 INFO - Return code: 0 09:16:50 INFO - grabbing minidump binary from tooltool 09:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:50 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 0x1034d1d30>, '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 0x103236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:50 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:16:50 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:16:50 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:16:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:50 INFO - Return code: 0 09:16:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:16:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:16:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --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-0123 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vr5Z0vOCdc/Render', 09:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:50 INFO - 'HOME': '/Users/cltbld', 09:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:50 INFO - 'LOGNAME': 'cltbld', 09:16:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:50 INFO - 'MOZ_NO_REMOTE': '1', 09:16:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:50 INFO - 'NO_EM_RESTART': '1', 09:16:50 INFO - 'PAGER': '/bin/cat', 09:16:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:50 INFO - 'PWD': '/builds/slave/test', 09:16:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:50 INFO - 'SHELL': '/bin/bash', 09:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIatAnMIty/Listeners', 09:16:50 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:50 INFO - 'USER': 'cltbld', 09:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:50 INFO - 'XPC_FLAGS': '0x0', 09:16:50 INFO - 'XPC_SERVICE_NAME': '0', 09:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--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-0123', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:16:50 INFO - mozversion application_buildid: 20160823070554 09:16:50 INFO - mozversion application_changeset: 052656fc513c05da969590ac5934abd67271a897 09:16:50 INFO - mozversion application_display_name: Nightly 09:16:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:50 INFO - mozversion application_name: Firefox 09:16:50 INFO - mozversion application_remotingname: firefox 09:16:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:16:50 INFO - mozversion application_vendor: Mozilla 09:16:50 INFO - mozversion application_version: 51.0a1 09:16:50 INFO - mozversion platform_buildid: 20160823070554 09:16:50 INFO - mozversion platform_changeset: 052656fc513c05da969590ac5934abd67271a897 09:16:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:16:50 INFO - mozversion platform_version: 51.0a1 09:16:50 INFO - using testdate: 1471969010 09:16:50 INFO - actual date: 1471969010 09:16:50 INFO - starting webserver on 'localhost:49279' 09:16:50 INFO - SUITE-START | Running 2 tests 09:16:50 INFO - TEST-START | dromaeo_css 09:16:50 INFO - operating with platform_type : mac_ 09:16:50 INFO - Initialising browser for dromaeo_css test... 09:16:50 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpErDH3R/profile http://localhost:49279/getInfo.html) 09:16:53 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:16:53 INFO - PROCESS | 1646 | colorDepth:24 09:16:53 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:16:53 INFO - PROCESS | 1646 | __metrics 09:16:53 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 09:16:53 INFO - TEST-INFO | 1646: exit 0 09:16:53 INFO - Browser initialized. 09:16:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:53 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpErDH3R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:05 INFO - PROCESS | 1649 | RSS: Main: 247074816 09:17:05 INFO - PROCESS | 1649 | 09:17:55 INFO - PROCESS | 1649 | 1471969075550 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:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 09:20:07 INFO - PROCESS | 1649 | RSS: Main: 268193792 09:20:07 INFO - PROCESS | 1649 | 09:23:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 09:23:11 INFO - PROCESS | 1649 | RSS: Main: 273547264 09:23:11 INFO - PROCESS | 1649 | 09:26:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 09:26:13 INFO - PROCESS | 1649 | RSS: Main: 276897792 09:26:13 INFO - PROCESS | 1649 | 09:29:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 09:29:16 INFO - PROCESS | 1649 | RSS: Main: 272936960 09:29:16 INFO - PROCESS | 1649 | 09:32:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 09:32:19 INFO - PROCESS | 1649 | RSS: Main: 277651456 09:32:19 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 09:35:22 INFO - PROCESS | 1649 | RSS: Main: 280391680 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | __start_tp_report 09:35:22 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:35:22 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:35:22 INFO - PROCESS | 1649 | |i|pagename|runs| 09:35:22 INFO - PROCESS | 1649 | |0;dojo.html;2727.7681397081246;3230.5154226865966;3284.770066095374;3291.9506207406894;3294.2752594429226;5410.540104091152;5428.4842939920745;5436.401995780466;5477.452254774521;5522.116461366182;5329.307190065296;5365.061114304991;5419.620626556142;5473.452654734525;5498.202760599711;4176.665866730669;4342.50061242958;4906.8282610108745;4993.350864387625;5089.541941225289;4243.427137336466;4907.779149938244;5192.610554208431;5202.375714914216;5208.062548741225;4809.254565542346;4901.436334821506;5021.974890125544;5055.898882022376;5092.898142037157;4341.435613370273;4951.207806750938;5008.073506401291;5037.942032173248;5084.9745751271375;4985.152524070881;4996.775145118442;5006.4492905780335;5009.4239162496415;5066.442691303953;5274.156135018417;5302.708351040691;5358.812441564526;5426.321709786277;5435.7825686972865;5884.411558844081;5979.1629171915965;5984.9401505984815;5989.221401217857;6004.909926351108;6042.305134909498;6044.727987240584;6054.4550990410635;6058.485028772559;6064.423879731418;5330.7601157948375;5535.529479994246;5574;5585.58108141891;5603.215549823028;6175;6187.257529096447;6189.876202476015;6197.845053873689;6202.162708034358;5881.382454842265;5902.974328417516;5917.615355001911;5920.437558431942;5972.223610930638;5775.624584402;5941.55438342126;5955.672438015864;6035.185249991195;6040.7583696651645;4822.300766388854;5848.8537786554825;5858.209141765892;5918.644881307136;6035.305939816977;5880.7353669085;5969.910451343233;5977.193078934375;5980.342162362137;5999.880002400015;4711.623070154448;4757.785899634525;4824.252240902666;4831;4870.391201099863;4253.340732186454;4294.033842385501;4338.262495375794;4363.4109395231235;4409.228385032607;5400.675959442446;5431.701256430934;5484.506394424521;5485.588580856452;5520.53075488588;5851.531877449794;5981.820545383645;5983.072623743326;6015.699215039231;6044.727987240584;5424.969255841377;5452.672839629635;5492.917606235909;5500.724963751796;5548.0845660465575;5223.895522089613;5235.633505654644;5238.397584277781;5241.449647786927;5246.97376513115;6065.150878876872;6188.44304012641;6209.720562574605;6211.937880621136;6217.96891015542;5932.466078052996;5962.731677074456;6003.189569408074;6047.516198704181;6095.177151084724;6127.908081378694;6166.1983942087245;6214.098955651496;6221.844453888689;6317.652529110943;5738;5957.191738055361;5967.075103358986;5967.224260846062;6010.849728756816;5908.763649453974;5972.940270597413;5974.372690867396;5990.580659353804;6039.969800150971;5782.739776709974;5815.912761308499;5827.417258274309;5831.679257640871;5855.853603659943;5659;5827.271591051119;5840.883182336414;5848.824535263949;5862.120681897579;4714.5521175488275;5018.723970181529;5141.843085305926;5529.4470552944385;5628.915566266427;5752.079667253387;5768.711564421678;5781.1039288908605;5790.1893734877995;5794.565407594531;5573.219749235192;5656.830295091154;5660.37735849048;5671.007573674673;5695.880149812734 09:35:22 INFO - PROCESS | 1649 | |1;ext.html;455.978607918263;473.4366104335838;502.2014996156111;510.57877251267706;549.9056876815138;37182.8845134646;40424.19151616966;40504.78485645428;40540;40654.59345406545;41322.38016429756;41851.372229416585;41879;41961.79019104908;42125.7893710531;9649.421034737903;9851.901480985205;9875.60497580096;9876.753081172956;9903.653372131977;9832.705018849447;9934.950325248363;9937.006299370078;9937.956514565974;9950.104490595844;9810.215182785396;9904.50477476127;9922.503874806267;9951.303408761389;9958.352707074053;9902.504874756269;9917.900820991843;9924.255680823968;9927.702168934944;9957.601695932153;9743.074407931306;9813.80372392548;9847.359921605073;9850.556724947395;9850.950745246228;33592.66407335944;38066.80966595177;39826.20347593032;40102;40190.799046004875;2910.738033576967;3240.2061494933655;3300.290437555913;3415.299863527971;3422.8973130806116;31410;31848;31860.522092168638;32218.677813221802;32494.837525812454;2780.9432415682113;3029.53042278445;3148;3207.06994971458;3266.836658167082;398884;536941;537148;541113;547901;51833.2225016625;62180;62623.434414140014;62777.68611156915;63015;33890;34877.65122348795;54752.45247547553;64864.67567662226;64987.35012649908;16409.179541022902;16767.245473953702;16773.077480738677;16991.660166796595;17009.744853827204;11081.445927703744;12926.547570835164;12952.417141228667;12959.870401296055;12985.870141298656;19142.138603762865;19200.519987000436;19202;19210.135543900557;19210.423687289403;7157.2127066022695;7673.53958762476;7845.215478452224;8002.1997800219515;8079.272865442138;816.5345752920778;868.1535502884637;906.9410488318238;917.605429665237;1038.771470276538;25897.48205035889;28982.359300350527;29119.416772776156;29477.557836632466;30973.0708078758;1040.9167266618651;1054.4885730420892;1119.9192778968172;1153.7577108807077;1157.1899670230873;15599.922000389925;15939.123348215728;15962;16119.438793616184;16406;706421;716920.4153979301;723401;723616;724781;58823.70588147118;59503.80992380128;59583.702081490184;59801;59934.400655993755;1388;1406.9015168938076;1413.5335339337994;1426.5221150914351;1428.1787971916397;30032.249193769458;32731;33091.33817323688;33113.50329745008;33935.490967636;57976.42023579879;58608.70695646494;58656.70671646614;58658;58777;548453;551353;555902;556672;558448;43674.781626091666;43921.560784391746;44054.779726101166;44391.11217775691;44713.55286447094;47770.28344574904;48483.51516484931;48916;49450.010999779086;50190.99618007692;40101.79949100353;40198.79900600478;40680.796596018015;41232.18290648308;41668.37497437601;39958.00104997398;40241.99395015148;40551.797241013606;41394.58605414028;41630.79184604058;31159.84420077885;37439.43840842335;38652.80673596614;39341.21317573575;39592.40611390889;31013.8449307752;34004.48993265152;34572;34580.65419345774;34659.96020119401;21823.781762182174;23024.654630180896;26537.33656658599;26696.866515667298;26985 09:35:22 INFO - PROCESS | 1649 | |2;jquery.html;373.16038912446993;377.486618199249;383.6048869664245;388.98444062237513;401.99799001004993;19310.710339344903;21206.82637227356;21252.256171034016;21451.249206277764;21970.89014554929;20604.1758329667;20985.790142098613;21176.4705882353;21439.249626263085;21513.892430537864;5812.389699081577;6012.128241404994;6097.878042439149;6177.783777567786;6233.968830155843;3452.7410444216657;5696.6866822324755;6167.0749387591995;6192.28788689302;6197;6086.447955771272;6136;6207.782727604535;6223.626582405048;6224.502039836825;5898.203742494773;6104.175936248594;6174.9073763893575;6182;6210.534209934251;5924.437178468039;6004.759809607611;6014.609050411709;6016.639001659914;6022.488088512482;13139;13414.664633384145;13545.45818167272;13582.320883955861;13589.184648921097;2218.9889050554807;2291.7822806833324;2380.1669415704537;2518.9443949003303;2793.756778233687;10153.4923253837;10167.59329626814;10254.33346832461;10454.529546170364;10568.947155264252;1859.7861245956617;1941.9223231070741;1942.5629233422508;1943.319838056683;1972.0337034852819;127587;134214.32892835472;134932.999999999;136238;137757.3112134433;11393.259438136494;11478.081753459703;11611.76776464466;11620.76758464826;11695.532178712927;14254.85745142556;14472.493462728755;14482.420703171965;14484.34820433083;14500.129992200502;7074.610896400747;8343.874841877378;8512.872306915402;8565.357598180162;8641.179087986631;8387.95806020966;8505.574721263913;8513.489190648581;8538.573071346533;8687.174718401742;4499.145162419195;4504.797284122223;4520.819167233274;4543.25036369002;4568.520305367955;1540.3684489359532;1711.7319551594187;1803.4589623113013;1808.4484232309271;1911.1781933768334;597.2474681900836;758.4690716498474;768.8385439057861;797.6370751308141;891.5052146058999;10391.324563903323;11427.60003399877;12069.15515913895;19136.904315478332;20541.486462838548;413.8737685006102;416.111601730308;416.3733580960635;429.37740276599055;435.3382858055738;4867.561919427269;5984.281886173687;6157.291911430153;6280.371962803683;6348.650824204713;529047.3214508927;588144.0592797009;618931;620068;627267;60133.39866601278;77968.61015694885;79905.20094799093;84149.15850841413;140378;440.3328956630707;449.6942079386049;453.7413674205737;455.27156549521163;458.63767623577667;11342.489587968395;11496.94251528737;11588.826167607662;11888.821667675165;11924.403779811148;51290.74354628203;54789.726051369486;58549;59156.40843591509;59628.10557841549;87107;87439;88215.55892220497;88607.78480538038;89455.10544894468;8073.919260807552;8117.269445749911;8189;8400.663973440995;8489.278411335106;9454.810903782023;11335.773284534429;11664.475098620413;13577.59267221985;13870.65323366924;10495.790084198426;11651.941740291584;11838.644840654793;11928.522859085539;12070.939645301718;11101.777964440504;11287.492062857027;11519.596814111801;11584.073274138218;12030.27818330903;9999.35004224723;11657.533698652298;11848.58529951482;11897.821532676844;11916.76166476683;8256.587170641324;8270.83458330818;8336.916630833613;8448.155184481748;8668.696595619123;8491.19333663301;10430.426326552113;10880.292780969212;11626.081696520318;11921.821172682243 09:35:22 INFO - PROCESS | 1649 | |3;mootools.html;1491.6494623763417;1535.6391248056705;1593.6653302806403;1717.450415866923;1792.9820701792994;6829.385355318021;8307.37694672899;8447.619857106429;8882.067382924797;8896.733098007062;8617.827643447128;8627.568621568927;8684.218420342184;8684.696035638754;8713.477191368522;2332.3352844439337;2363.385519764865;2372.51363470489;2374.679418278536;2375.8574485530835;2348.2720356689483;2358.575456417844;2390.760923907613;2397.6283676030157;2398.70016247969;2343.95312093758;2351.905923763047;2356.2695564375013;2376.3583832365252;2382.690250267463;2344.4490544721975;2359.6460530920326;2363.444590521226;2368.9407764805846;2371.6679664846934;2343.0627748900406;2356.740758516562;2357;2363.3855197648563;2366.668666386707;8750.781230469225;8758.343124265706;8783.34124940632;8817.16236957488;8831.911680883237;5189.040027594907;5346.625736198468;5353.1434970404935;5355.30381050461;5381.246625472437;3555.2000799820094;3680.190358121236;3688.963110368889;3744.8689295874788;3746.3069332173636;1112.9610463633735;1112.9777404451866;1117.4245263689206;1131.6831287239586;1138.6868611131922;11282.661520154408;11328.49021794013;11335.43322833855;11442.942785286103;11465.885341146648;4288.785560721952;4407.801648925742;4564.543545645409;4570.88572785683;4582.106489234568;8852.336074794417;9021.368504204642;9022.909770902339;9027.052159523155;9039.63841446348;5172.974135129375;5199.29809475716;5207.947920520746;5214.348206474191;5227.163653815372;4376.759278239728;4732.148213321566;4866.829660961849;4877.244027175793;4890.926636100461;3661.2677464507206;3756.910495956197;3764.792936388525;3790.7915064671706;3791.109089363997;1175.6884425627215;1180.2682336951145;1187.1749134351542;1196.347990345264;1197.0902114393125;729.4419768876708;755.4862693368563;921.5944984206927;925.3707478914403;934.5747684803398;5425.616467800764;5734.541236701054;5886.61736987103;5963.135345374983;6176.752929882754;398.81853756541284;420.9684273679486;421.6647765026811;451.8102396993271;453.8978729785772;3611.494390785292;3800.3729384651774;3871.9032024199055;4037.192561487714;4088.386741988666;10382.740431489274;10805.891941080647;10839.891601084046;10885.401302928256;10920.726981825359;10774.515146818412;10799.298045627009;10809.78380432387;10810.945945270223;10819.404932728776;7412.369948554433;7737.381009519225;8221.342292616697;8278.627461764236;8331.416800824005;5227.973860130675;5628.296462942132;5925.674087925206;6057.666828324308;6138;9503.23974082058;9607.423554586747;9622.951885240529;9825.4104753715;9835.557399916877;9792.265580081665;9959.352642078242;9971.152452041368;10039.548220330249;10136.594536218468;7351.4118870489165;7957.681692732226;8106.675732970615;8140.511569305861;8303.211194936472;8340.332773377993;8378.958105209435;8451.704190353308;8467.66129354819;8487.915120848713;6284.622922624474;7398.186199518156;7418.406527477897;7468.5145465544565;7507.549547027196;7355.117385913724;7358.96320518394;7385.889211661722;7410.407167426701;7438.962805185939;6985.44116470671;6991.440684745106;7023.192332881579;7076.0801095857205;7086.50394472382;4261.573842615714;5003.224500202475;5034.924476132935;5041.7227052511;5128.820491282787;4467.486239082417;4839.935214252859;4965.627577931597;5049.5960323174795;5145.151050076845 09:35:22 INFO - PROCESS | 1649 | |4;prototype.html;273.4408135363182;300.9563613276075;313.99843000785;315.31733796330946;318.2029017311634;5270.314859068325;5358.196270559418;5492.704377373579;5550.583706222029;5635.436456354362;4564.771761411925;5403.189521571766;5511.779528818853;5517.613767036309;5531.225628412026;3702.2965636529107;3931;4167.916641667165;4194.245035893546;4204.8226496580855;3823.751456155341;4143.461350024492;4216.680825545953;4217.493900731916;4235.3646952957;4093.2427500912277;4104.322786740185;4118.485189351331;4119.958800412002;4129.339305711087;3374.105861946586;3447.9827600862086;3760.5111335526344;4019.7588144711412;4105.3020986432375;3389.779664321811;3563.572371315433;3565.8751943682105;3573.9821300893327;3602.2615363850346;5013.724245166514;5314.309139811839;5321.654092484015;5398.91901621476;5438.184272359178;4323.35712429278;4695.225287827504;4696.835610753641;4717.292406139072;4727.881802954919;4752.928706069411;5092.694438333675;5556.972215138899;5759.712014399305;5821.3014438267255;3795.8481660733537;3871.825767840454;3913.589073147307;3929.1159489115007;3933.3509970854775;5035.924461133086;6047.190270129834;6097.420745029215;6133.969330153321;6136.355682653302;3727.683146932541;3787.5381002348695;3991.3015222335976;3998.3202855514633;3998.3602623580086;3313;3362.4788157835574;3610.4042832932273;3894.162755007687;3959.940600890989;3774.471573979645;4110.588941105865;4193.6645068394455;4714.834980775657;4749.762511874393;5834.679092649945;5874.471297583238;5896.7641294348605;5910.202122713378;5996.850078748066;1304.5499302740538;1346;1355.2342926246836;1366.453418632555;1374.9175049497062;767.9654415551314;786.8347646994196;927.332320729074;927.5037854747812;928.215657769184;5066.746662666852;5269.130593452114;5319.627626066138;5347.491988261109;5371.301730774974;4719.764011799396;5170.560502357266;5903.114532820111;5913.521619595102;5944.256967879015;4899.755012249373;5016.297718319438;5076.416212135578;5330.280412144389;5338;2398.460346422041;2427.5751743444825;2534.0721881084246;2575.7553032461888;2625.3722691931125;4872.4883887192045;4921.434035086011;5039.042581909499;5152.4963025142515;5163.432022477525;4453.576910193527;5051.444341122474;5131.332926719577;5546.69493177871;6167.784127555604;5510.071474983723;6161.907571386433;6273.215848018997;6296.670232014184;6306.716197771111;5871.5651343964255;5978.103284507359;6039.637621742534;6071.6017173217215;6078.878422431615;5143.794248230029;5423.322084739408;5601.607887448003;5988.772301678233;6036.698165091816;5241.659292145999;5750.971245143747;6310.781501434393;6329.6518691470565;6400.807975760735;3839.7520805738804;3937.645611894943;3962.3065963456856;3970.9278494805662;3990.6009399059863;4718.693284936468;5263.710495922761;5476.2607048048;5530.972345138249;6030.7889223876955;4823.56587907076;5096.745162741774;5484.945150548443;5738.8278351649515;5897.498712609477;5864.296284445893;5868.796896636265;5896.31243161679;5908.645481271137;5964.970175149097;5483.4516548344845;5513.724313784375;5645.661260324394;5763.04056620751;5765.56948615573;4816.397950256218;5845.152452894392;5845.912311315419;5864.824055278348;5910.822675319747;4397.868063958086;4938.654294199371;5724.685142317046;5729.455701708331;5785.218995435731 09:35:22 INFO - PROCESS | 1649 | |5;yui.html;588.3880764005434;644.7002144003038;705.9858802823946;785.6229010075164;830.3938125168625;15280.235988200588;15585.29866156023;16035.278412471438;16206.594835129128;16228.350865965376;14749;14783.186924719135;14891.404343826262;14893.180875051868;14897.478588249414;856.4989481153517;874.5758307220992;875.1030194051117;875.1948207648946;875.6147295190128;677.5866721300004;846.9745907622781;873.4366333714751;883.5096521430603;890.9931777091878;897.9102089791033;905.2576886952702;918.6968300460866;921.7925966657516;922.3359181389388;911.9772005699845;913.6984795017628;914.4284821986258;915.8305713443034;918.17364372065;669.5012215899137;674.790814847394;677.0724107972061;677.4952660268083;678.281022116558;12937.964962802953;13002.414891329912;14364.92817535916;14932.70134597302;14946.40214391423;14253.857461425358;14708.926455367762;14777.852221477755;14901.552953411416;14996.850031499655;11759.35323557204;11891.702707432383;11967.820482692767;12016.939915300454;12044.457999390048;3323.5014747788027;3822.4839646647624;3867.61323867611;3876.8643097491454;3968.3849003404516;115281;118822;121220.78779212183;124030;126038.36980815037;11086.22396432242;11197.216194866282;11244.831327530093;11253.324800511831;11438.199326047263;22742.772572274396;22843.543129137324;22883.199088031837;22933.197338093087;22992.65511017336;7927.722529711433;8224.712135075244;8262.545559994138;8263.669453221924;8330.291925186306;4510;4590.495045544987;4597.4712908015335;4741.5258474153;4749.715017098985;3487.598926123528;3629.927401451956;3644.1982763791925;3648.3068217038676;3652.6895213907114;573.899567575681;639.0797251957167;648.7696867611929;663.6064265043467;674.5716470041559;457.3391449355649;526.6708307307932;651.7197605029883;675.7364627795164;697.1808125452573;9147;9282.55287688214;9435.386699864486;9566.66516671913;9605.855912161323;601.7994101767002;614.8923938310778;665.9245418041617;683.9760608378683;694.9131358580178;4445.711028783184;4726.172919739032;4731.503192164842;4731.905361892743;4748.3114948332295;100451;100876.48685269727;103126;165165;168846;119861.40069299597;121779;142493;148605;150751.24624376808;119662.40168799275;122985.38507307407;124105.37947310387;125183.74816251903;125274;8665.783355415913;8984.966728826268;9086.182243598108;9086.681966131153;9137.680181193626;51816.74091629518;62592.68703656635;67190.65618687696;67458.98811517733;68693.65653171702;65387;73216;81459;97872.51063744635;106816.86366272786;10059.346142500714;10125.987401259816;12042;12188.817167742669;12449.93775031119;13193.274369909363;13410.39653215588;13493.527726529524;13528.93235533816;14063.929680351534;12236.44935977901;12241.411844156833;12350.75298494007;12628.179168354027;12647.177933434297;12394.3183124929;12414.317212553397;12724.554640587536;12757.51594441521;13080.411381487665;11458.77082458363;11925.34410607425;12573.874261257637;12709.110362274552;12743.362831858185;10475.214358923264;10656.094231990368;10899.128069754559;10924.453777311262;11478.770424591628;10507.894921050998;10576.636090495016;10656.73358166021;10833.891661083288;10846.13230941507 09:35:22 INFO - PROCESS | 1649 | __end_tp_report 09:35:22 INFO - PROCESS | 1649 | __start_cc_report 09:35:22 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,415 09:35:22 INFO - PROCESS | 1649 | __end_cc_report 09:35:22 INFO - PROCESS | 1649 | __startTimestamp1471970122920__endTimestamp 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:35:22 INFO - PROCESS | 1649 | Number of tests: 6 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5486.30 Median:5656.83 stddev:650.97 (11.5%) stddev-sans-first:615.95 09:35:22 INFO - PROCESS | 1649 | Values: 2727.8 3230.5 3284.8 3292.0 3294.3 5410.5 5428.5 5436.4 5477.5 5522.1 5329.3 5365.1 5419.6 5473.5 5498.2 4176.7 4342.5 4906.8 4993.4 5089.5 4243.4 4907.8 5192.6 5202.4 5208.1 4809.3 4901.4 5022.0 5055.9 5092.9 4341.4 4951.2 5008.1 5037.9 5085.0 4985.2 4996.8 5006.4 5009.4 5066.4 5274.2 5302.7 5358.8 5426.3 5435.8 5884.4 5979.2 5984.9 5989.2 6004.9 6042.3 6044.7 6054.5 6058.5 6064.4 5330.8 5535.5 5574.0 5585.6 5603.2 6175.0 6187.3 6189.9 6197.8 6202.2 5881.4 5903.0 5917.6 5920.4 5972.2 5775.6 5941.6 5955.7 6035.2 6040.8 4822.3 5848.9 5858.2 5918.6 6035.3 5880.7 5969.9 5977.2 5980.3 5999.9 4711.6 4757.8 4824.3 4831.0 4870.4 4253.3 4294.0 4338.3 4363.4 4409.2 5400.7 5431.7 5484.5 5485.6 5520.5 5851.5 5981.8 5983.1 6015.7 6044.7 5425.0 5452.7 5492.9 5500.7 5548.1 5223.9 5235.6 5238.4 5241.4 5247.0 6065.2 6188.4 6209.7 6211.9 6218.0 5932.5 5962.7 6003.2 6047.5 6095.2 6127.9 6166.2 6214.1 6221.8 6317.7 5738.0 5957.2 5967.1 5967.2 6010.8 5908.8 5972.9 5974.4 5990.6 6040.0 5782.7 5815.9 5827.4 5831.7 5855.9 5659.0 5827.3 5840.9 5848.8 5862.1 4714.6 5018.7 5141.8 5529.4 5628.9 5752.1 5768.7 5781.1 5790.2 5794.6 5573.2 5656.8 5660.4 5671.0 5695.9 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:73082.17 Median:41277.28 stddev:161288.42 (390.7%) stddev-sans-first:161648.70 09:35:22 INFO - PROCESS | 1649 | Values: 456.0 473.4 502.2 510.6 549.9 37182.9 40424.2 40504.8 40540.0 40654.6 41322.4 41851.4 41879.0 41961.8 42125.8 9649.4 9851.9 9875.6 9876.8 9903.7 9832.7 9935.0 9937.0 9938.0 9950.1 9810.2 9904.5 9922.5 9951.3 9958.4 9902.5 9917.9 9924.3 9927.7 9957.6 9743.1 9813.8 9847.4 9850.6 9851.0 33592.7 38066.8 39826.2 40102.0 40190.8 2910.7 3240.2 3300.3 3415.3 3422.9 31410.0 31848.0 31860.5 32218.7 32494.8 2780.9 3029.5 3148.0 3207.1 3266.8 398884.0 536941.0 537148.0 541113.0 547901.0 51833.2 62180.0 62623.4 62777.7 63015.0 33890.0 34877.7 54752.5 64864.7 64987.4 16409.2 16767.2 16773.1 16991.7 17009.7 11081.4 12926.5 12952.4 12959.9 12985.9 19142.1 19200.5 19202.0 19210.1 19210.4 7157.2 7673.5 7845.2 8002.2 8079.3 816.5 868.2 906.9 917.6 1038.8 25897.5 28982.4 29119.4 29477.6 30973.1 1040.9 1054.5 1119.9 1153.8 1157.2 15599.9 15939.1 15962.0 16119.4 16406.0 706421.0 716920.4 723401.0 723616.0 724781.0 58823.7 59503.8 59583.7 59801.0 59934.4 1388.0 1406.9 1413.5 1426.5 1428.2 30032.2 32731.0 33091.3 33113.5 33935.5 57976.4 58608.7 58656.7 58658.0 58777.0 548453.0 551353.0 555902.0 556672.0 558448.0 43674.8 43921.6 44054.8 44391.1 44713.6 47770.3 48483.5 48916.0 49450.0 50191.0 40101.8 40198.8 40680.8 41232.2 41668.4 39958.0 40242.0 40551.8 41394.6 41630.8 31159.8 37439.4 38652.8 39341.2 39592.4 31013.8 34004.5 34572.0 34580.7 34660.0 21823.8 23024.7 26537.3 26696.9 26985.0 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:33934.10 Median:416.24 stddev:99799.96 (23976.4%) stddev-sans-first:100048.10 09:35:22 INFO - PROCESS | 1649 | Values: 373.2 377.5 383.6 389.0 402.0 19310.7 21206.8 21252.3 21451.2 21970.9 20604.2 20985.8 21176.5 21439.2 21513.9 5812.4 6012.1 6097.9 6177.8 6234.0 3452.7 5696.7 6167.1 6192.3 6197.0 6086.4 6136.0 6207.8 6223.6 6224.5 5898.2 6104.2 6174.9 6182.0 6210.5 5924.4 6004.8 6014.6 6016.6 6022.5 13139.0 13414.7 13545.5 13582.3 13589.2 2219.0 2291.8 2380.2 2518.9 2793.8 10153.5 10167.6 10254.3 10454.5 10568.9 1859.8 1941.9 1942.6 1943.3 1972.0 127587.0 134214.3 134933.0 136238.0 137757.3 11393.3 11478.1 11611.8 11620.8 11695.5 14254.9 14472.5 14482.4 14484.3 14500.1 7074.6 8343.9 8512.9 8565.4 8641.2 8388.0 8505.6 8513.5 8538.6 8687.2 4499.1 4504.8 4520.8 4543.3 4568.5 1540.4 1711.7 1803.5 1808.4 1911.2 597.2 758.5 768.8 797.6 891.5 10391.3 11427.6 12069.2 19136.9 20541.5 413.9 416.1 416.4 429.4 435.3 4867.6 5984.3 6157.3 6280.4 6348.7 529047.3 588144.1 618931.0 620068.0 627267.0 60133.4 77968.6 79905.2 84149.2 140378.0 440.3 449.7 453.7 455.3 458.6 11342.5 11496.9 11588.8 11888.8 11924.4 51290.7 54789.7 58549.0 59156.4 59628.1 87107.0 87439.0 88215.6 88607.8 89455.1 8073.9 8117.3 8189.0 8400.7 8489.3 9454.8 11335.8 11664.5 13577.6 13870.7 10495.8 11651.9 11838.6 11928.5 12070.9 11101.8 11287.5 11519.6 11584.1 12030.3 9999.4 11657.5 11848.6 11897.8 11916.8 8256.6 8270.8 8336.9 8448.2 8668.7 8491.2 10430.4 10880.3 11626.1 11921.8 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5635.90 Median:4984.43 stddev:3171.47 (63.6%) stddev-sans-first:3165.08 09:35:22 INFO - PROCESS | 1649 | Values: 1491.6 1535.6 1593.7 1717.5 1793.0 6829.4 8307.4 8447.6 8882.1 8896.7 8617.8 8627.6 8684.2 8684.7 8713.5 2332.3 2363.4 2372.5 2374.7 2375.9 2348.3 2358.6 2390.8 2397.6 2398.7 2344.0 2351.9 2356.3 2376.4 2382.7 2344.4 2359.6 2363.4 2368.9 2371.7 2343.1 2356.7 2357.0 2363.4 2366.7 8750.8 8758.3 8783.3 8817.2 8831.9 5189.0 5346.6 5353.1 5355.3 5381.2 3555.2 3680.2 3689.0 3744.9 3746.3 1113.0 1113.0 1117.4 1131.7 1138.7 11282.7 11328.5 11335.4 11442.9 11465.9 4288.8 4407.8 4564.5 4570.9 4582.1 8852.3 9021.4 9022.9 9027.1 9039.6 5173.0 5199.3 5207.9 5214.3 5227.2 4376.8 4732.1 4866.8 4877.2 4890.9 3661.3 3756.9 3764.8 3790.8 3791.1 1175.7 1180.3 1187.2 1196.3 1197.1 729.4 755.5 921.6 925.4 934.6 5425.6 5734.5 5886.6 5963.1 6176.8 398.8 421.0 421.7 451.8 453.9 3611.5 3800.4 3871.9 4037.2 4088.4 10382.7 10805.9 10839.9 10885.4 10920.7 10774.5 10799.3 10809.8 10810.9 10819.4 7412.4 7737.4 8221.3 8278.6 8331.4 5228.0 5628.3 5925.7 6057.7 6138.0 9503.2 9607.4 9623.0 9825.4 9835.6 9792.3 9959.4 9971.2 10039.5 10136.6 7351.4 7957.7 8106.7 8140.5 8303.2 8340.3 8379.0 8451.7 8467.7 8487.9 6284.6 7398.2 7418.4 7468.5 7507.5 7355.1 7359.0 7385.9 7410.4 7439.0 6985.4 6991.4 7023.2 7076.1 7086.5 4261.6 5003.2 5034.9 5041.7 5128.8 4467.5 4839.9 4965.6 5049.6 5145.2 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4596.30 Median:5114.04 stddev:1459.06 (28.5%) stddev-sans-first:1426.62 09:35:22 INFO - PROCESS | 1649 | Values: 273.4 301.0 314.0 315.3 318.2 5270.3 5358.2 5492.7 5550.6 5635.4 4564.8 5403.2 5511.8 5517.6 5531.2 3702.3 3931.0 4167.9 4194.2 4204.8 3823.8 4143.5 4216.7 4217.5 4235.4 4093.2 4104.3 4118.5 4120.0 4129.3 3374.1 3448.0 3760.5 4019.8 4105.3 3389.8 3563.6 3565.9 3574.0 3602.3 5013.7 5314.3 5321.7 5398.9 5438.2 4323.4 4695.2 4696.8 4717.3 4727.9 4752.9 5092.7 5557.0 5759.7 5821.3 3795.8 3871.8 3913.6 3929.1 3933.4 5035.9 6047.2 6097.4 6134.0 6136.4 3727.7 3787.5 3991.3 3998.3 3998.4 3313.0 3362.5 3610.4 3894.2 3959.9 3774.5 4110.6 4193.7 4714.8 4749.8 5834.7 5874.5 5896.8 5910.2 5996.9 1304.5 1346.0 1355.2 1366.5 1374.9 768.0 786.8 927.3 927.5 928.2 5066.7 5269.1 5319.6 5347.5 5371.3 4719.8 5170.6 5903.1 5913.5 5944.3 4899.8 5016.3 5076.4 5330.3 5338.0 2398.5 2427.6 2534.1 2575.8 2625.4 4872.5 4921.4 5039.0 5152.5 5163.4 4453.6 5051.4 5131.3 5546.7 6167.8 5510.1 6161.9 6273.2 6296.7 6306.7 5871.6 5978.1 6039.6 6071.6 6078.9 5143.8 5423.3 5601.6 5988.8 6036.7 5241.7 5751.0 6310.8 6329.7 6400.8 3839.8 3937.6 3962.3 3970.9 3990.6 4718.7 5263.7 5476.3 5531.0 6030.8 4823.6 5096.7 5484.9 5738.8 5897.5 5864.3 5868.8 5896.3 5908.6 5965.0 5483.5 5513.7 5645.7 5763.0 5765.6 4816.4 5845.2 5845.9 5864.8 5910.8 4397.9 4938.7 5724.7 5729.5 5785.2 09:35:22 INFO - PROCESS | 1649 | 09:35:22 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24914.45 Median:13158.08 stddev:40356.25 (306.7%) stddev-sans-first:40428.12 09:35:22 INFO - PROCESS | 1649 | Values: 588.4 644.7 706.0 785.6 830.4 15280.2 15585.3 16035.3 16206.6 16228.4 14749.0 14783.2 14891.4 14893.2 14897.5 856.5 874.6 875.1 875.2 875.6 677.6 847.0 873.4 883.5 891.0 897.9 905.3 918.7 921.8 922.3 912.0 913.7 914.4 915.8 918.2 669.5 674.8 677.1 677.5 678.3 12938.0 13002.4 14364.9 14932.7 14946.4 14253.9 14708.9 14777.9 14901.6 14996.9 11759.4 11891.7 11967.8 12016.9 12044.5 3323.5 3822.5 3867.6 3876.9 3968.4 115281.0 118822.0 121220.8 124030.0 126038.4 11086.2 11197.2 11244.8 11253.3 11438.2 22742.8 22843.5 22883.2 22933.2 22992.7 7927.7 8224.7 8262.5 8263.7 8330.3 4510.0 4590.5 4597.5 4741.5 4749.7 3487.6 3629.9 3644.2 3648.3 3652.7 573.9 639.1 648.8 663.6 674.6 457.3 526.7 651.7 675.7 697.2 9147.0 9282.6 9435.4 9566.7 9605.9 601.8 614.9 665.9 684.0 694.9 4445.7 4726.2 4731.5 4731.9 4748.3 100451.0 100876.5 103126.0 165165.0 168846.0 119861.4 121779.0 142493.0 148605.0 150751.2 119662.4 122985.4 124105.4 125183.7 125274.0 8665.8 8985.0 9086.2 9086.7 9137.7 51816.7 62592.7 67190.7 67459.0 68693.7 65387.0 73216.0 81459.0 97872.5 106816.9 10059.3 10126.0 12042.0 12188.8 12449.9 13193.3 13410.4 13493.5 13528.9 14063.9 12236.4 12241.4 12350.8 12628.2 12647.2 12394.3 12414.3 12724.6 12757.5 13080.4 11458.8 11925.3 12573.9 12709.1 12743.4 10475.2 10656.1 10899.1 10924.5 11478.8 10507.9 10576.6 10656.7 10833.9 10846.1 09:35:22 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:35:22 INFO - PROCESS | 1649 | 09:35:23 INFO - TEST-INFO | 1649: exit 0 09:35:23 INFO - TEST-OK | dromaeo_css | took 1113245ms 09:35:23 INFO - TEST-START | kraken 09:35:23 INFO - operating with platform_type : mac_ 09:35:23 INFO - Initialising browser for kraken test... 09:35:23 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpr86ROG/profile http://localhost:49279/getInfo.html) 09:35:26 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 09:35:26 INFO - PROCESS | 1659 | colorDepth:24 09:35:26 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 09:35:26 INFO - PROCESS | 1659 | __metrics 09:35:26 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 09:35:27 INFO - TEST-INFO | 1659: exit 0 09:35:27 INFO - Browser initialized. 09:35:27 INFO - Running cycle 1/1 for kraken test... 09:35:27 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpr86ROG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:39 INFO - PROCESS | 1661 | RSS: Main: 247013376 09:35:39 INFO - PROCESS | 1661 | 09:35:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:43 INFO - PROCESS | 1661 | RSS: Main: 259973120 09:35:43 INFO - PROCESS | 1661 | 09:35:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 09:35:46 INFO - PROCESS | 1661 | RSS: Main: 269340672 09:35:46 INFO - PROCESS | 1661 | 09:35:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 09:35:50 INFO - PROCESS | 1661 | RSS: Main: 267333632 09:35:50 INFO - PROCESS | 1661 | 09:35:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 09:35:53 INFO - PROCESS | 1661 | RSS: Main: 268693504 09:35:53 INFO - PROCESS | 1661 | 09:35:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:56 INFO - PROCESS | 1661 | RSS: Main: 269221888 09:35:56 INFO - PROCESS | 1661 | 09:35:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:00 INFO - PROCESS | 1661 | RSS: Main: 269287424 09:36:00 INFO - PROCESS | 1661 | 09:36:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:03 INFO - PROCESS | 1661 | RSS: Main: 270880768 09:36:03 INFO - PROCESS | 1661 | 09:36:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 09:36:07 INFO - PROCESS | 1661 | RSS: Main: 270901248 09:36:07 INFO - PROCESS | 1661 | 09:36:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:09 INFO - PROCESS | 1661 | RSS: Main: 270663680 09:36:09 INFO - PROCESS | 1661 | 09:36:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:12 INFO - PROCESS | 1661 | RSS: Main: 270753792 09:36:12 INFO - PROCESS | 1661 | 09:36:14 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:15 INFO - PROCESS | 1661 | RSS: Main: 271060992 09:36:15 INFO - PROCESS | 1661 | 09:36:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:18 INFO - PROCESS | 1661 | RSS: Main: 271224832 09:36:18 INFO - PROCESS | 1661 | 09:36:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:22 INFO - PROCESS | 1661 | RSS: Main: 271167488 09:36:22 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 09:36:24 INFO - PROCESS | 1661 | RSS: Main: 273739776 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | __start_tp_report 09:36:24 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 09:36:24 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 09:36:24 INFO - PROCESS | 1661 | |i|pagename|runs| 09:36:24 INFO - PROCESS | 1661 | |0;ai-astar;107;114;115;112;115;116;116;115;116;128 09:36:24 INFO - PROCESS | 1661 | |1;audio-beat-detection;144;110;135;148;140;163;161;134;153;95 09:36:24 INFO - PROCESS | 1661 | |2;audio-dft;194;242;213;233;245;282;199;265;272;201 09:36:24 INFO - PROCESS | 1661 | |3;audio-fft;71;81;77;141;67;67;55;86;145;67 09:36:24 INFO - PROCESS | 1661 | |4;audio-oscillator;72;76;157;71;71;83;114;72;103;100 09:36:24 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;87;81;90;78;130;115;82;131;130 09:36:24 INFO - PROCESS | 1661 | |6;imaging-darkroom;96;139;92;149;147;94;96;96;96;134 09:36:24 INFO - PROCESS | 1661 | |7;imaging-desaturate;92;94;91;90;80;148;143;91;98;89 09:36:24 INFO - PROCESS | 1661 | |8;json-parse-financial;55;57;59;58;58;59;54;65;85;58 09:36:24 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;68;70;74;71;85;111;107;103;73;71 09:36:24 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;86;86;87;105;86;77;105;106;104;108 09:36:24 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;159;162;151;138;137;163;153;154;155;147 09:36:24 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;168;175;185;231;222;167;180;230;174;169 09:36:24 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;68;65;82;72;64;86;85;85;88;68 09:36:24 INFO - PROCESS | 1661 | __end_tp_report 09:36:24 INFO - PROCESS | 1661 | __start_cc_report 09:36:24 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1104 09:36:24 INFO - PROCESS | 1661 | __end_cc_report 09:36:24 INFO - PROCESS | 1661 | __startTimestamp1471970184687__endTimestamp 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | ------- Summary: start ------- 09:36:24 INFO - PROCESS | 1661 | Number of tests: 14 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:115.40 Median:115.50 stddev:5.21 (4.5%) stddev-sans-first:4.56 09:36:24 INFO - PROCESS | 1661 | Values: 107.0 114.0 115.0 112.0 115.0 116.0 116.0 115.0 116.0 128.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:150.50 stddev:21.54 (14.3%) stddev-sans-first:22.75 09:36:24 INFO - PROCESS | 1661 | Values: 144.0 110.0 135.0 148.0 140.0 163.0 161.0 134.0 153.0 95.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:234.60 Median:243.50 stddev:32.05 (13.2%) stddev-sans-first:30.44 09:36:24 INFO - PROCESS | 1661 | Values: 194.0 242.0 213.0 233.0 245.0 282.0 199.0 265.0 272.0 201.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:85.70 Median:69.00 stddev:31.41 (45.5%) stddev-sans-first:32.86 09:36:24 INFO - PROCESS | 1661 | Values: 71.0 81.0 77.0 141.0 67.0 67.0 55.0 86.0 145.0 67.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:71.50 stddev:27.71 (38.8%) stddev-sans-first:28.44 09:36:24 INFO - PROCESS | 1661 | Values: 72.0 76.0 157.0 71.0 71.0 83.0 114.0 72.0 103.0 100.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:101.30 Median:81.50 stddev:22.43 (27.5%) stddev-sans-first:23.35 09:36:24 INFO - PROCESS | 1661 | Values: 89.0 87.0 81.0 90.0 78.0 130.0 115.0 82.0 131.0 130.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:95.00 stddev:24.76 (26.1%) stddev-sans-first:25.40 09:36:24 INFO - PROCESS | 1661 | Values: 96.0 139.0 92.0 149.0 147.0 94.0 96.0 96.0 96.0 134.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:91.00 stddev:23.60 (25.9%) stddev-sans-first:24.78 09:36:24 INFO - PROCESS | 1661 | Values: 92.0 94.0 91.0 90.0 80.0 148.0 143.0 91.0 98.0 89.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:58.50 stddev:8.99 (15.4%) stddev-sans-first:9.29 09:36:24 INFO - PROCESS | 1661 | Values: 55.0 57.0 59.0 58.0 58.0 59.0 54.0 65.0 85.0 58.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:83.30 Median:71.00 stddev:17.08 (24.1%) stddev-sans-first:17.20 09:36:24 INFO - PROCESS | 1661 | Values: 68.0 70.0 74.0 71.0 85.0 111.0 107.0 103.0 73.0 71.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:81.50 stddev:11.56 (14.2%) stddev-sans-first:11.79 09:36:24 INFO - PROCESS | 1661 | Values: 86.0 86.0 87.0 105.0 86.0 77.0 105.0 106.0 104.0 108.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:154.50 stddev:9.01 (5.8%) stddev-sans-first:9.18 09:36:24 INFO - PROCESS | 1661 | Values: 159.0 162.0 151.0 138.0 137.0 163.0 153.0 154.0 155.0 147.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.10 Median:182.50 stddev:26.59 (14.6%) stddev-sans-first:26.98 09:36:24 INFO - PROCESS | 1661 | Values: 168.0 175.0 185.0 231.0 222.0 167.0 180.0 230.0 174.0 169.0 09:36:24 INFO - PROCESS | 1661 | 09:36:24 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:83.50 stddev:9.72 (11.6%) stddev-sans-first:9.83 09:36:24 INFO - PROCESS | 1661 | Values: 68.0 65.0 82.0 72.0 64.0 86.0 85.0 85.0 88.0 68.0 09:36:24 INFO - PROCESS | 1661 | -------- Summary: end -------- 09:36:24 INFO - PROCESS | 1661 | 09:36:25 INFO - TEST-INFO | 1661: exit 0 09:36:25 INFO - TEST-OK | kraken | took 61850ms 09:36:25 INFO - SUITE-END | took 1175s 09:36:25 INFO - Completed test suite (00:19:35) 09:36:25 INFO - javascript benchmark 09:36:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7186.451953379667, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2727.7681397081246, 3230.5154226865966, 3284.770066095374, 3291.9506207406894, 3294.2752594429226, 5410.540104091152, 5428.4842939920745, 5436.401995780466, 5477.452254774521, 5522.116461366182, 5329.307190065296, 5365.061114304991, 5419.620626556142, 5473.452654734525, 5498.202760599711, 4176.665866730669, 4342.50061242958, 4906.8282610108745, 4993.350864387625, 5089.541941225289, 4243.427137336466, 4907.779149938244, 5192.610554208431, 5202.375714914216, 5208.062548741225, 4809.254565542346, 4901.436334821506, 5021.974890125544, 5055.898882022376, 5092.898142037157, 4341.435613370273, 4951.207806750938, 5008.073506401291, 5037.942032173248, 5084.9745751271375, 4985.152524070881, 4996.775145118442, 5006.4492905780335, 5009.4239162496415, 5066.442691303953, 5274.156135018417, 5302.708351040691, 5358.812441564526, 5426.321709786277, 5435.7825686972865, 5884.411558844081, 5979.1629171915965, 5984.9401505984815, 5989.221401217857, 6004.909926351108, 6042.305134909498, 6044.727987240584, 6054.4550990410635, 6058.485028772559, 6064.423879731418, 5330.7601157948375, 5535.529479994246, 5574.0, 5585.58108141891, 5603.215549823028, 6175.0, 6187.257529096447, 6189.876202476015, 6197.845053873689, 6202.162708034358, 5881.382454842265, 5902.974328417516, 5917.615355001911, 5920.437558431942, 5972.223610930638, 5775.624584402, 5941.55438342126, 5955.672438015864, 6035.185249991195, 6040.7583696651645, 4822.300766388854, 5848.8537786554825, 5858.209141765892, 5918.644881307136, 6035.305939816977, 5880.7353669085, 5969.910451343233, 5977.193078934375, 5980.342162362137, 5999.880002400015, 4711.623070154448, 4757.785899634525, 4824.252240902666, 4831.0, 4870.391201099863, 4253.340732186454, 4294.033842385501, 4338.262495375794, 4363.4109395231235, 4409.228385032607, 5400.675959442446, 5431.701256430934, 5484.506394424521, 5485.588580856452, 5520.53075488588, 5851.531877449794, 5981.820545383645, 5983.072623743326, 6015.699215039231, 6044.727987240584, 5424.969255841377, 5452.672839629635, 5492.917606235909, 5500.724963751796, 5548.0845660465575, 5223.895522089613, 5235.633505654644, 5238.397584277781, 5241.449647786927, 5246.97376513115, 6065.150878876872, 6188.44304012641, 6209.720562574605, 6211.937880621136, 6217.96891015542, 5932.466078052996, 5962.731677074456, 6003.189569408074, 6047.516198704181, 6095.177151084724, 6127.908081378694, 6166.1983942087245, 6214.098955651496, 6221.844453888689, 6317.652529110943, 5738.0, 5957.191738055361, 5967.075103358986, 5967.224260846062, 6010.849728756816, 5908.763649453974, 5972.940270597413, 5974.372690867396, 5990.580659353804, 6039.969800150971, 5782.739776709974, 5815.912761308499, 5827.417258274309, 5831.679257640871, 5855.853603659943, 5659.0, 5827.271591051119, 5840.883182336414, 5848.824535263949, 5862.120681897579, 4714.5521175488275, 5018.723970181529, 5141.843085305926, 5529.4470552944385, 5628.915566266427, 5752.079667253387, 5768.711564421678, 5781.1039288908605, 5790.1893734877995, 5794.565407594531, 5573.219749235192, 5656.830295091154, 5660.37735849048, 5671.007573674673, 5695.880149812734], "value": 5443.609116691243, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.978607918263, 473.4366104335838, 502.2014996156111, 510.57877251267706, 549.9056876815138, 37182.8845134646, 40424.19151616966, 40504.78485645428, 40540.0, 40654.59345406545, 41322.38016429756, 41851.372229416585, 41879.0, 41961.79019104908, 42125.7893710531, 9649.421034737903, 9851.901480985205, 9875.60497580096, 9876.753081172956, 9903.653372131977, 9832.705018849447, 9934.950325248363, 9937.006299370078, 9937.956514565974, 9950.104490595844, 9810.215182785396, 9904.50477476127, 9922.503874806267, 9951.303408761389, 9958.352707074053, 9902.504874756269, 9917.900820991843, 9924.255680823968, 9927.702168934944, 9957.601695932153, 9743.074407931306, 9813.80372392548, 9847.359921605073, 9850.556724947395, 9850.950745246228, 33592.66407335944, 38066.80966595177, 39826.20347593032, 40102.0, 40190.799046004875, 2910.738033576967, 3240.2061494933655, 3300.290437555913, 3415.299863527971, 3422.8973130806116, 31410.0, 31848.0, 31860.522092168638, 32218.677813221802, 32494.837525812454, 2780.9432415682113, 3029.53042278445, 3148.0, 3207.06994971458, 3266.836658167082, 398884.0, 536941.0, 537148.0, 541113.0, 547901.0, 51833.2225016625, 62180.0, 62623.434414140014, 62777.68611156915, 63015.0, 33890.0, 34877.65122348795, 54752.45247547553, 64864.67567662226, 64987.35012649908, 16409.179541022902, 16767.245473953702, 16773.077480738677, 16991.660166796595, 17009.744853827204, 11081.445927703744, 12926.547570835164, 12952.417141228667, 12959.870401296055, 12985.870141298656, 19142.138603762865, 19200.519987000436, 19202.0, 19210.135543900557, 19210.423687289403, 7157.2127066022695, 7673.53958762476, 7845.215478452224, 8002.1997800219515, 8079.272865442138, 816.5345752920778, 868.1535502884637, 906.9410488318238, 917.605429665237, 1038.771470276538, 25897.48205035889, 28982.359300350527, 29119.416772776156, 29477.557836632466, 30973.0708078758, 1040.9167266618651, 1054.4885730420892, 1119.9192778968172, 1153.7577108807077, 1157.1899670230873, 15599.922000389925, 15939.123348215728, 15962.0, 16119.438793616184, 16406.0, 706421.0, 716920.4153979301, 723401.0, 723616.0, 724781.0, 58823.70588147118, 59503.80992380128, 59583.702081490184, 59801.0, 59934.400655993755, 1388.0, 1406.9015168938076, 1413.5335339337994, 1426.5221150914351, 1428.1787971916397, 30032.249193769458, 32731.0, 33091.33817323688, 33113.50329745008, 33935.490967636, 57976.42023579879, 58608.70695646494, 58656.70671646614, 58658.0, 58777.0, 548453.0, 551353.0, 555902.0, 556672.0, 558448.0, 43674.781626091666, 43921.560784391746, 44054.779726101166, 44391.11217775691, 44713.55286447094, 47770.28344574904, 48483.51516484931, 48916.0, 49450.010999779086, 50190.99618007692, 40101.79949100353, 40198.79900600478, 40680.796596018015, 41232.18290648308, 41668.37497437601, 39958.00104997398, 40241.99395015148, 40551.797241013606, 41394.58605414028, 41630.79184604058, 31159.84420077885, 37439.43840842335, 38652.80673596614, 39341.21317573575, 39592.40611390889, 31013.8449307752, 34004.48993265152, 34572.0, 34580.65419345774, 34659.96020119401, 21823.781762182174, 23024.654630180896, 26537.33656658599, 26696.866515667298, 26985.0], "value": 20477.47942764493, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.16038912446993, 377.486618199249, 383.6048869664245, 388.98444062237513, 401.99799001004993, 19310.710339344903, 21206.82637227356, 21252.256171034016, 21451.249206277764, 21970.89014554929, 20604.1758329667, 20985.790142098613, 21176.4705882353, 21439.249626263085, 21513.892430537864, 5812.389699081577, 6012.128241404994, 6097.878042439149, 6177.783777567786, 6233.968830155843, 3452.7410444216657, 5696.6866822324755, 6167.0749387591995, 6192.28788689302, 6197.0, 6086.447955771272, 6136.0, 6207.782727604535, 6223.626582405048, 6224.502039836825, 5898.203742494773, 6104.175936248594, 6174.9073763893575, 6182.0, 6210.534209934251, 5924.437178468039, 6004.759809607611, 6014.609050411709, 6016.639001659914, 6022.488088512482, 13139.0, 13414.664633384145, 13545.45818167272, 13582.320883955861, 13589.184648921097, 2218.9889050554807, 2291.7822806833324, 2380.1669415704537, 2518.9443949003303, 2793.756778233687, 10153.4923253837, 10167.59329626814, 10254.33346832461, 10454.529546170364, 10568.947155264252, 1859.7861245956617, 1941.9223231070741, 1942.5629233422508, 1943.319838056683, 1972.0337034852819, 127587.0, 134214.32892835472, 134932.999999999, 136238.0, 137757.3112134433, 11393.259438136494, 11478.081753459703, 11611.76776464466, 11620.76758464826, 11695.532178712927, 14254.85745142556, 14472.493462728755, 14482.420703171965, 14484.34820433083, 14500.129992200502, 7074.610896400747, 8343.874841877378, 8512.872306915402, 8565.357598180162, 8641.179087986631, 8387.95806020966, 8505.574721263913, 8513.489190648581, 8538.573071346533, 8687.174718401742, 4499.145162419195, 4504.797284122223, 4520.819167233274, 4543.25036369002, 4568.520305367955, 1540.3684489359532, 1711.7319551594187, 1803.4589623113013, 1808.4484232309271, 1911.1781933768334, 597.2474681900836, 758.4690716498474, 768.8385439057861, 797.6370751308141, 891.5052146058999, 10391.324563903323, 11427.60003399877, 12069.15515913895, 19136.904315478332, 20541.486462838548, 413.8737685006102, 416.111601730308, 416.3733580960635, 429.37740276599055, 435.3382858055738, 4867.561919427269, 5984.281886173687, 6157.291911430153, 6280.371962803683, 6348.650824204713, 529047.3214508927, 588144.0592797009, 618931.0, 620068.0, 627267.0, 60133.39866601278, 77968.61015694885, 79905.20094799093, 84149.15850841413, 140378.0, 440.3328956630707, 449.6942079386049, 453.7413674205737, 455.27156549521163, 458.63767623577667, 11342.489587968395, 11496.94251528737, 11588.826167607662, 11888.821667675165, 11924.403779811148, 51290.74354628203, 54789.726051369486, 58549.0, 59156.40843591509, 59628.10557841549, 87107.0, 87439.0, 88215.55892220497, 88607.78480538038, 89455.10544894468, 8073.919260807552, 8117.269445749911, 8189.0, 8400.663973440995, 8489.278411335106, 9454.810903782023, 11335.773284534429, 11664.475098620413, 13577.59267221985, 13870.65323366924, 10495.790084198426, 11651.941740291584, 11838.644840654793, 11928.522859085539, 12070.939645301718, 11101.777964440504, 11287.492062857027, 11519.596814111801, 11584.073274138218, 12030.27818330903, 9999.35004224723, 11657.533698652298, 11848.58529951482, 11897.821532676844, 11916.76166476683, 8256.587170641324, 8270.83458330818, 8336.916630833613, 8448.155184481748, 8668.696595619123, 8491.19333663301, 10430.426326552113, 10880.292780969212, 11626.081696520318, 11921.821172682243], "value": 8661.898896974255, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.6494623763417, 1535.6391248056705, 1593.6653302806403, 1717.450415866923, 1792.9820701792994, 6829.385355318021, 8307.37694672899, 8447.619857106429, 8882.067382924797, 8896.733098007062, 8617.827643447128, 8627.568621568927, 8684.218420342184, 8684.696035638754, 8713.477191368522, 2332.3352844439337, 2363.385519764865, 2372.51363470489, 2374.679418278536, 2375.8574485530835, 2348.2720356689483, 2358.575456417844, 2390.760923907613, 2397.6283676030157, 2398.70016247969, 2343.95312093758, 2351.905923763047, 2356.2695564375013, 2376.3583832365252, 2382.690250267463, 2344.4490544721975, 2359.6460530920326, 2363.444590521226, 2368.9407764805846, 2371.6679664846934, 2343.0627748900406, 2356.740758516562, 2357.0, 2363.3855197648563, 2366.668666386707, 8750.781230469225, 8758.343124265706, 8783.34124940632, 8817.16236957488, 8831.911680883237, 5189.040027594907, 5346.625736198468, 5353.1434970404935, 5355.30381050461, 5381.246625472437, 3555.2000799820094, 3680.190358121236, 3688.963110368889, 3744.8689295874788, 3746.3069332173636, 1112.9610463633735, 1112.9777404451866, 1117.4245263689206, 1131.6831287239586, 1138.6868611131922, 11282.661520154408, 11328.49021794013, 11335.43322833855, 11442.942785286103, 11465.885341146648, 4288.785560721952, 4407.801648925742, 4564.543545645409, 4570.88572785683, 4582.106489234568, 8852.336074794417, 9021.368504204642, 9022.909770902339, 9027.052159523155, 9039.63841446348, 5172.974135129375, 5199.29809475716, 5207.947920520746, 5214.348206474191, 5227.163653815372, 4376.759278239728, 4732.148213321566, 4866.829660961849, 4877.244027175793, 4890.926636100461, 3661.2677464507206, 3756.910495956197, 3764.792936388525, 3790.7915064671706, 3791.109089363997, 1175.6884425627215, 1180.2682336951145, 1187.1749134351542, 1196.347990345264, 1197.0902114393125, 729.4419768876708, 755.4862693368563, 921.5944984206927, 925.3707478914403, 934.5747684803398, 5425.616467800764, 5734.541236701054, 5886.61736987103, 5963.135345374983, 6176.752929882754, 398.81853756541284, 420.9684273679486, 421.6647765026811, 451.8102396993271, 453.8978729785772, 3611.494390785292, 3800.3729384651774, 3871.9032024199055, 4037.192561487714, 4088.386741988666, 10382.740431489274, 10805.891941080647, 10839.891601084046, 10885.401302928256, 10920.726981825359, 10774.515146818412, 10799.298045627009, 10809.78380432387, 10810.945945270223, 10819.404932728776, 7412.369948554433, 7737.381009519225, 8221.342292616697, 8278.627461764236, 8331.416800824005, 5227.973860130675, 5628.296462942132, 5925.674087925206, 6057.666828324308, 6138.0, 9503.23974082058, 9607.423554586747, 9622.951885240529, 9825.4104753715, 9835.557399916877, 9792.265580081665, 9959.352642078242, 9971.152452041368, 10039.548220330249, 10136.594536218468, 7351.4118870489165, 7957.681692732226, 8106.675732970615, 8140.511569305861, 8303.211194936472, 8340.332773377993, 8378.958105209435, 8451.704190353308, 8467.66129354819, 8487.915120848713, 6284.622922624474, 7398.186199518156, 7418.406527477897, 7468.5145465544565, 7507.549547027196, 7355.117385913724, 7358.96320518394, 7385.889211661722, 7410.407167426701, 7438.962805185939, 6985.44116470671, 6991.440684745106, 7023.192332881579, 7076.0801095857205, 7086.50394472382, 4261.573842615714, 5003.224500202475, 5034.924476132935, 5041.7227052511, 5128.820491282787, 4467.486239082417, 4839.935214252859, 4965.627577931597, 5049.5960323174795, 5145.151050076845], "value": 4450.69073085478, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.4408135363182, 300.9563613276075, 313.99843000785, 315.31733796330946, 318.2029017311634, 5270.314859068325, 5358.196270559418, 5492.704377373579, 5550.583706222029, 5635.436456354362, 4564.771761411925, 5403.189521571766, 5511.779528818853, 5517.613767036309, 5531.225628412026, 3702.2965636529107, 3931.0, 4167.916641667165, 4194.245035893546, 4204.8226496580855, 3823.751456155341, 4143.461350024492, 4216.680825545953, 4217.493900731916, 4235.3646952957, 4093.2427500912277, 4104.322786740185, 4118.485189351331, 4119.958800412002, 4129.339305711087, 3374.105861946586, 3447.9827600862086, 3760.5111335526344, 4019.7588144711412, 4105.3020986432375, 3389.779664321811, 3563.572371315433, 3565.8751943682105, 3573.9821300893327, 3602.2615363850346, 5013.724245166514, 5314.309139811839, 5321.654092484015, 5398.91901621476, 5438.184272359178, 4323.35712429278, 4695.225287827504, 4696.835610753641, 4717.292406139072, 4727.881802954919, 4752.928706069411, 5092.694438333675, 5556.972215138899, 5759.712014399305, 5821.3014438267255, 3795.8481660733537, 3871.825767840454, 3913.589073147307, 3929.1159489115007, 3933.3509970854775, 5035.924461133086, 6047.190270129834, 6097.420745029215, 6133.969330153321, 6136.355682653302, 3727.683146932541, 3787.5381002348695, 3991.3015222335976, 3998.3202855514633, 3998.3602623580086, 3313.0, 3362.4788157835574, 3610.4042832932273, 3894.162755007687, 3959.940600890989, 3774.471573979645, 4110.588941105865, 4193.6645068394455, 4714.834980775657, 4749.762511874393, 5834.679092649945, 5874.471297583238, 5896.7641294348605, 5910.202122713378, 5996.850078748066, 1304.5499302740538, 1346.0, 1355.2342926246836, 1366.453418632555, 1374.9175049497062, 767.9654415551314, 786.8347646994196, 927.332320729074, 927.5037854747812, 928.215657769184, 5066.746662666852, 5269.130593452114, 5319.627626066138, 5347.491988261109, 5371.301730774974, 4719.764011799396, 5170.560502357266, 5903.114532820111, 5913.521619595102, 5944.256967879015, 4899.755012249373, 5016.297718319438, 5076.416212135578, 5330.280412144389, 5338.0, 2398.460346422041, 2427.5751743444825, 2534.0721881084246, 2575.7553032461888, 2625.3722691931125, 4872.4883887192045, 4921.434035086011, 5039.042581909499, 5152.4963025142515, 5163.432022477525, 4453.576910193527, 5051.444341122474, 5131.332926719577, 5546.69493177871, 6167.784127555604, 5510.071474983723, 6161.907571386433, 6273.215848018997, 6296.670232014184, 6306.716197771111, 5871.5651343964255, 5978.103284507359, 6039.637621742534, 6071.6017173217215, 6078.878422431615, 5143.794248230029, 5423.322084739408, 5601.607887448003, 5988.772301678233, 6036.698165091816, 5241.659292145999, 5750.971245143747, 6310.781501434393, 6329.6518691470565, 6400.807975760735, 3839.7520805738804, 3937.645611894943, 3962.3065963456856, 3970.9278494805662, 3990.6009399059863, 4718.693284936468, 5263.710495922761, 5476.2607048048, 5530.972345138249, 6030.7889223876955, 4823.56587907076, 5096.745162741774, 5484.945150548443, 5738.8278351649515, 5897.498712609477, 5864.296284445893, 5868.796896636265, 5896.31243161679, 5908.645481271137, 5964.970175149097, 5483.4516548344845, 5513.724313784375, 5645.661260324394, 5763.04056620751, 5765.56948615573, 4816.397950256218, 5845.152452894392, 5845.912311315419, 5864.824055278348, 5910.822675319747, 4397.868063958086, 4938.654294199371, 5724.685142317046, 5729.455701708331, 5785.218995435731], "value": 4127.8500182970665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.3880764005434, 644.7002144003038, 705.9858802823946, 785.6229010075164, 830.3938125168625, 15280.235988200588, 15585.29866156023, 16035.278412471438, 16206.594835129128, 16228.350865965376, 14749.0, 14783.186924719135, 14891.404343826262, 14893.180875051868, 14897.478588249414, 856.4989481153517, 874.5758307220992, 875.1030194051117, 875.1948207648946, 875.6147295190128, 677.5866721300004, 846.9745907622781, 873.4366333714751, 883.5096521430603, 890.9931777091878, 897.9102089791033, 905.2576886952702, 918.6968300460866, 921.7925966657516, 922.3359181389388, 911.9772005699845, 913.6984795017628, 914.4284821986258, 915.8305713443034, 918.17364372065, 669.5012215899137, 674.790814847394, 677.0724107972061, 677.4952660268083, 678.281022116558, 12937.964962802953, 13002.414891329912, 14364.92817535916, 14932.70134597302, 14946.40214391423, 14253.857461425358, 14708.926455367762, 14777.852221477755, 14901.552953411416, 14996.850031499655, 11759.35323557204, 11891.702707432383, 11967.820482692767, 12016.939915300454, 12044.457999390048, 3323.5014747788027, 3822.4839646647624, 3867.61323867611, 3876.8643097491454, 3968.3849003404516, 115281.0, 118822.0, 121220.78779212183, 124030.0, 126038.36980815037, 11086.22396432242, 11197.216194866282, 11244.831327530093, 11253.324800511831, 11438.199326047263, 22742.772572274396, 22843.543129137324, 22883.199088031837, 22933.197338093087, 22992.65511017336, 7927.722529711433, 8224.712135075244, 8262.545559994138, 8263.669453221924, 8330.291925186306, 4510.0, 4590.495045544987, 4597.4712908015335, 4741.5258474153, 4749.715017098985, 3487.598926123528, 3629.927401451956, 3644.1982763791925, 3648.3068217038676, 3652.6895213907114, 573.899567575681, 639.0797251957167, 648.7696867611929, 663.6064265043467, 674.5716470041559, 457.3391449355649, 526.6708307307932, 651.7197605029883, 675.7364627795164, 697.1808125452573, 9147.0, 9282.55287688214, 9435.386699864486, 9566.66516671913, 9605.855912161323, 601.7994101767002, 614.8923938310778, 665.9245418041617, 683.9760608378683, 694.9131358580178, 4445.711028783184, 4726.172919739032, 4731.503192164842, 4731.905361892743, 4748.3114948332295, 100451.0, 100876.48685269727, 103126.0, 165165.0, 168846.0, 119861.40069299597, 121779.0, 142493.0, 148605.0, 150751.24624376808, 119662.40168799275, 122985.38507307407, 124105.37947310387, 125183.74816251903, 125274.0, 8665.783355415913, 8984.966728826268, 9086.182243598108, 9086.681966131153, 9137.680181193626, 51816.74091629518, 62592.68703656635, 67190.65618687696, 67458.98811517733, 68693.65653171702, 65387.0, 73216.0, 81459.0, 97872.51063744635, 106816.86366272786, 10059.346142500714, 10125.987401259816, 12042.0, 12188.817167742669, 12449.93775031119, 13193.274369909363, 13410.39653215588, 13493.527726529524, 13528.93235533816, 14063.929680351534, 12236.44935977901, 12241.411844156833, 12350.75298494007, 12628.179168354027, 12647.177933434297, 12394.3183124929, 12414.317212553397, 12724.554640587536, 12757.51594441521, 13080.411381487665, 11458.77082458363, 11925.34410607425, 12573.874261257637, 12709.110362274552, 12743.362831858185, 10475.214358923264, 10656.094231990368, 10899.128069754559, 10924.453777311262, 11478.770424591628, 10507.894921050998, 10576.636090495016, 10656.73358166021, 10833.891661083288, 10846.13230941507], "value": 7764.474118915679, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1640.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 115.0, 112.0, 115.0, 116.0, 116.0, 115.0, 116.0, 128.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 135.0, 148.0, 140.0, 163.0, 161.0, 134.0, 153.0, 95.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 242.0, 213.0, 233.0, 245.0, 282.0, 199.0, 265.0, 272.0, 201.0], "value": 234.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 81.0, 77.0, 141.0, 67.0, 67.0, 55.0, 86.0, 145.0, 67.0], "value": 85.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 157.0, 71.0, 71.0, 83.0, 114.0, 72.0, 103.0, 100.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 81.0, 90.0, 78.0, 130.0, 115.0, 82.0, 131.0, 130.0], "value": 101.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 139.0, 92.0, 149.0, 147.0, 94.0, 96.0, 96.0, 96.0, 134.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 90.0, 80.0, 148.0, 143.0, 91.0, 98.0, 89.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 59.0, 58.0, 58.0, 59.0, 54.0, 65.0, 85.0, 58.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 74.0, 71.0, 85.0, 111.0, 107.0, 103.0, 73.0, 71.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 105.0, 86.0, 77.0, 105.0, 106.0, 104.0, 108.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 151.0, 138.0, 137.0, 163.0, 153.0, 154.0, 155.0, 147.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 175.0, 185.0, 231.0, 222.0, 167.0, 180.0, 230.0, 174.0, 169.0], "value": 190.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 82.0, 72.0, 64.0, 86.0, 85.0, 85.0, 88.0, 68.0], "value": 76.3, "unit": "score"}]}]} 09:36:25 INFO - Return code: 0 09:36:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:26 ERROR - # TBPL SUCCESS # 09:36:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:26 INFO - 2016-08-23 09:36:26.031 screenresolution[1666:10552] starting screenresolution argv=screenresolution get 09:36:26 INFO - 2016-08-23 09:36:26.033 screenresolution[1666:10552] Display 0: 1600x1200x32@60 09:36:26 INFO - 2016-08-23 09:36:26.039 screenresolution[1667:10555] starting screenresolution argv=screenresolution list 09:36:26 INFO - Available Modes on Display 0 09:36:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:26 INFO - Intel Iris: 09:36:26 INFO - Chipset Model: Intel Iris 09:36:26 INFO - Type: GPU 09:36:26 INFO - Bus: Built-In 09:36:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:26 INFO - Vendor: Intel (0x8086) 09:36:26 INFO - Device ID: 0x0a2e 09:36:26 INFO - Revision ID: 0x0009 09:36:26 INFO - Displays: 09:36:26 INFO - Display: 09:36:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:26 INFO - Display Serial Number: 1600x1200 60 09:36:26 INFO - Main Display: Yes 09:36:26 INFO - Mirror: Off 09:36:26 INFO - Online: Yes 09:36:26 INFO - Rotation: Supported 09:36:26 INFO - Return code: 0 09:36:26 INFO - Running post-action listener: _resource_record_post_action 09:36:26 INFO - [mozharness: 2016-08-23 16:36:26.275069Z] Finished run-tests step (success) 09:36:26 INFO - Running post-run listener: _resource_record_post_run 09:36:26 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38351872; Write bytes: 708768768; Read time: 387; Write time: 2449 09:36:26 INFO - TinderboxPrint: CPU usage
54.4% 09:36:26 INFO - TinderboxPrint: I/O read bytes / time
38,351,872 / 387 09:36:26 INFO - TinderboxPrint: I/O write bytes / time
708,768,768 / 2,449 09:36:26 INFO - TinderboxPrint: CPU idle
2,207.7 (45.6%) 09:36:26 INFO - TinderboxPrint: CPU system
981.4 (20.3%) 09:36:26 INFO - TinderboxPrint: CPU user
1,656.5 (34.2%) 09:36:26 INFO - TinderboxPrint: Swap in / out
443,461,632 / 0 09:36:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402217472; Write bytes: 391816704; Read time: 31199; Write time: 775 09:36:26 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31124480; Write bytes: 298950144; Read time: 289; Write time: 1634 09:36:26 INFO - Running post-run listener: _upload_blobber_files 09:36:26 INFO - Blob upload gear active. 09:36:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:26 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:36:26 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:36:26 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:36:27 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:28 INFO - (blobuploader) - INFO - Done attempting. 09:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:36:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:29 INFO - (blobuploader) - INFO - Done attempting. 09:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:31 INFO - (blobuploader) - INFO - Done attempting. 09:36:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:31 INFO - Return code: 0 09:36:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/209b9eedf7517baaf15f2c993f9fa6b538b8ceaf6f3218b50f3de11703cf2339ebfd5ccfb01103407aa87cc52b6afc7434c9a2c8397feb082c3a72faea6f6754", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6954294beb4cec4f324b1b304dad76d27c2b9b96fff6838e4b2c7e40463d34bae49d6640ac63084bf3dd010fea7cb36609f7314e3d04f6ae6feade487f4f2dfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/565b6491376d785fc536227e558800573ddd7ca31fbb8c7572e6b1e3ffaee18923cf4d9284a12d333c1a36a322efb0f4037924638f36c6628594ca143b8f3999"} 09:36:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:31 INFO - Contents: 09:36:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/209b9eedf7517baaf15f2c993f9fa6b538b8ceaf6f3218b50f3de11703cf2339ebfd5ccfb01103407aa87cc52b6afc7434c9a2c8397feb082c3a72faea6f6754", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6954294beb4cec4f324b1b304dad76d27c2b9b96fff6838e4b2c7e40463d34bae49d6640ac63084bf3dd010fea7cb36609f7314e3d04f6ae6feade487f4f2dfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/565b6491376d785fc536227e558800573ddd7ca31fbb8c7572e6b1e3ffaee18923cf4d9284a12d333c1a36a322efb0f4037924638f36c6628594ca143b8f3999"} 09:36:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:31 INFO - Copying logs to upload dir... 09:36:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.557917 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-23 09:36:31.907465) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 09:36:31.911057) ========= 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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/209b9eedf7517baaf15f2c993f9fa6b538b8ceaf6f3218b50f3de11703cf2339ebfd5ccfb01103407aa87cc52b6afc7434c9a2c8397feb082c3a72faea6f6754", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6954294beb4cec4f324b1b304dad76d27c2b9b96fff6838e4b2c7e40463d34bae49d6640ac63084bf3dd010fea7cb36609f7314e3d04f6ae6feade487f4f2dfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/565b6491376d785fc536227e558800573ddd7ca31fbb8c7572e6b1e3ffaee18923cf4d9284a12d333c1a36a322efb0f4037924638f36c6628594ca143b8f3999"} 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.011041 build_url: 'https://queue.taskcluster.net/v1/task/bDJME_OATcam26c3AjIENw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/209b9eedf7517baaf15f2c993f9fa6b538b8ceaf6f3218b50f3de11703cf2339ebfd5ccfb01103407aa87cc52b6afc7434c9a2c8397feb082c3a72faea6f6754", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6954294beb4cec4f324b1b304dad76d27c2b9b96fff6838e4b2c7e40463d34bae49d6640ac63084bf3dd010fea7cb36609f7314e3d04f6ae6feade487f4f2dfd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/565b6491376d785fc536227e558800573ddd7ca31fbb8c7572e6b1e3ffaee18923cf4d9284a12d333c1a36a322efb0f4037924638f36c6628594ca143b8f3999"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 09:36:31.946234) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:36:31.946563) ========= 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.Vr5Z0vOCdc/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.fIatAnMIty/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005319 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:36:31.995388) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 09:36:31.995707) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 09:36:31.996036) ========= ========= Total master_lag: 0.19 =========