builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0269 starttime: 1472149672.8 results: success (0) buildid: 20160825083725 builduid: 5876b2afebff4200956dae41b76d7be8 revision: 18ae102fbf67c2be43b3b2b15b56e90821ef11fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.805194) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.805741) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.806072) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.831593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.832008) ========= 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.jki35UXnJu/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.4CXCNpijb5/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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-25 11:27:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.90M=0.001s 2016-08-25 11:27:52 (7.90 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132944 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.988435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:52.988821) ========= 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.jki35UXnJu/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.4CXCNpijb5/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.021089 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.037843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.038251) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 18ae102fbf67c2be43b3b2b15b56e90821ef11fd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 18ae102fbf67c2be43b3b2b15b56e90821ef11fd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jki35UXnJu/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.4CXCNpijb5/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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-25 11:27:53,108 truncating revision to first 12 chars 2016-08-25 11:27:53,108 Setting DEBUG logging. 2016-08-25 11:27:53,108 attempt 1/10 2016-08-25 11:27:53,108 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/18ae102fbf67?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 11:27:53,445 unpacking tar archive at: autoland-18ae102fbf67/testing/mozharness/ program finished with exit code 0 elapsedTime=0.582769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.643905) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.644263) ========= script_repo_revision: 18ae102fbf67c2be43b3b2b15b56e90821ef11fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.644734) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.645048) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 11:27:53.663657) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-25 11:27:53.664054) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jki35UXnJu/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.4CXCNpijb5/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:27:53 INFO - MultiFileLogger online at 20160825 11:27:53 in /builds/slave/test 11:27:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:27:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:27:53 INFO - {'append_to_log': False, 11:27:53 INFO - 'base_work_dir': '/builds/slave/test', 11:27:53 INFO - 'blob_upload_branch': 'Autoland', 11:27:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:27:53 INFO - 'branch': 'Autoland', 11:27:53 INFO - 'buildbot_json_path': 'buildprops.json', 11:27:53 INFO - 'config_files': ('talos/mac_config.py',), 11:27:53 INFO - 'default_actions': ('clobber', 11:27:53 INFO - 'read-buildbot-config', 11:27:53 INFO - 'download-and-extract', 11:27:53 INFO - 'populate-webroot', 11:27:53 INFO - 'create-virtualenv', 11:27:53 INFO - 'install', 11:27:53 INFO - 'run-tests'), 11:27:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:27:53 INFO - 'download_minidump_stackwalk': True, 11:27:53 INFO - 'download_symbols': 'ondemand', 11:27:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:27:53 INFO - 'tooltool.py': '/tools/tooltool.py', 11:27:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:27:53 INFO - '/tools/misc-python/virtualenv.py')}, 11:27:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:27:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:27:53 INFO - 'installer_path': 'installer.exe', 11:27:53 INFO - 'log_level': 'info', 11:27:53 INFO - 'log_name': 'talos', 11:27:53 INFO - 'log_to_console': True, 11:27:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:27:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:27:53 INFO - 'opt_config_files': (), 11:27:53 INFO - 'pip_index': False, 11:27:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:53 INFO - 'cmd': ('bash', 11:27:53 INFO - '-c', 11:27:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:53 INFO - 'enabled': True, 11:27:53 INFO - 'halt_on_failure': False, 11:27:53 INFO - 'name': 'check_screen_resolution'},), 11:27:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:53 INFO - 'cmd': ('bash', 11:27:53 INFO - '-c', 11:27:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:53 INFO - 'enabled': True, 11:27:53 INFO - 'halt_on_failure': False, 11:27:53 INFO - 'name': 'check_screen_resolution'},), 11:27:53 INFO - 'run_cmd_checks_enabled': True, 11:27:53 INFO - 'sps_profile': False, 11:27:53 INFO - 'sps_profile_interval': 0, 11:27:53 INFO - 'suite': 'dromaeojs-e10s', 11:27:53 INFO - 'system_bits': '32', 11:27:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:27:53 INFO - 'title': 't-yosemite-r7-0269', 11:27:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:27:53 INFO - 'use_talos_json': True, 11:27:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:27:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:27:53 INFO - 'work_dir': 'build'} 11:27:53 INFO - [mozharness: 2016-08-25 18:27:53.853388Z] Running clobber step. 11:27:53 INFO - Running pre-action listener: _resource_record_pre_action 11:27:53 INFO - Running main action method: clobber 11:27:53 INFO - rmtree: /builds/slave/test/build 11:27:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:27:55 INFO - Running post-action listener: _resource_record_post_action 11:27:55 INFO - [mozharness: 2016-08-25 18:27:55.427488Z] Finished clobber step (success) 11:27:55 INFO - [mozharness: 2016-08-25 18:27:55.427670Z] Running read-buildbot-config step. 11:27:55 INFO - Running pre-action listener: _resource_record_pre_action 11:27:55 INFO - Running main action method: read_buildbot_config 11:27:55 INFO - Using buildbot properties: 11:27:55 INFO - { 11:27:55 INFO - "project": "", 11:27:55 INFO - "product": "firefox", 11:27:55 INFO - "script_repo_revision": "production", 11:27:55 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 11:27:55 INFO - "repository": "", 11:27:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 11:27:55 INFO - "buildid": "20160825083725", 11:27:55 INFO - "pgo_build": "False", 11:27:55 INFO - "basedir": "/builds/slave/test", 11:27:55 INFO - "buildnumber": 161, 11:27:55 INFO - "slavename": "t-yosemite-r7-0269", 11:27:55 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:27:55 INFO - "platform": "yosemite_r7", 11:27:55 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 11:27:55 INFO - "branch": "autoland", 11:27:55 INFO - "repo_path": "integration/autoland", 11:27:55 INFO - "revision": "18ae102fbf67c2be43b3b2b15b56e90821ef11fd", 11:27:55 INFO - "stage_platform": "macosx64", 11:27:55 INFO - "builduid": "5876b2afebff4200956dae41b76d7be8", 11:27:55 INFO - "slavebuilddir": "test" 11:27:55 INFO - } 11:27:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:27:55 INFO - Running post-action listener: _resource_record_post_action 11:27:55 INFO - [mozharness: 2016-08-25 18:27:55.431122Z] Finished read-buildbot-config step (success) 11:27:55 INFO - [mozharness: 2016-08-25 18:27:55.431261Z] Running download-and-extract step. 11:27:55 INFO - Running pre-action listener: _resource_record_pre_action 11:27:55 INFO - Running main action method: download_and_extract 11:27:55 INFO - mkdir: /builds/slave/test/build/tests 11:27:55 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')]} 11:27:55 INFO - trying https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:27:55 INFO - Downloading https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:27:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:27:56 INFO - Downloaded 1199 bytes. 11:27:56 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:27:56 INFO - Using the following test package requirements: 11:27:56 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:27:56 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:27:56 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:27:56 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'jsshell-mac.zip'], 11:27:56 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:27:56 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:27:56 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:27:56 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:27:56 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:27:56 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:27:56 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:27:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:56 INFO - trying https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:27:56 INFO - Downloading https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:27:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:27:57 INFO - Downloaded 16346906 bytes. 11:27:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:28:04 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 11:28:04 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')]} 11:28:04 INFO - trying https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:28:04 INFO - Downloading https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:28:05 INFO - Downloaded 16346906 bytes. 11:28:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:28:12 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')]} 11:28:12 INFO - trying https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:28:12 INFO - Downloading https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:28:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:28:13 INFO - Downloaded 14413998 bytes. 11:28:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:28: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')]} 11:28:13 INFO - trying https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:28:13 INFO - Downloading https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:28:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/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 11:28:16 INFO - Downloaded 111517420 bytes. 11:28:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:28:16 INFO - mkdir: /builds/slave/test/properties 11:28:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:28:16 INFO - Writing to file /builds/slave/test/properties/build_url 11:28:16 INFO - Contents: 11:28:16 INFO - build_url:https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:28:16 INFO - Running post-action listener: _resource_record_post_action 11:28:16 INFO - Running post-action listener: set_extra_try_arguments 11:28:16 INFO - [mozharness: 2016-08-25 18:28:16.925536Z] Finished download-and-extract step (success) 11:28:16 INFO - [mozharness: 2016-08-25 18:28:16.925657Z] Running populate-webroot step. 11:28:16 INFO - Running pre-action listener: _resource_record_pre_action 11:28:16 INFO - Running main action method: populate_webroot 11:28:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:28:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:28:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:28:16 INFO - '--tppagecycles', 11:28:16 INFO - '7'], 11:28:16 INFO - 'tests': ['tsvgm']}}, 11:28:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:28:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:28:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:28:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:28:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['damp', 'tps']}, 11:28:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'tests': ['damp', 'tps']}, 11:28:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['dromaeo_dom']}, 11:28:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:28:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['basic_compositor_video']}, 11:28:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:28:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['a11yr', 11:28:16 INFO - 'ts_paint', 11:28:16 INFO - 'tpaint', 11:28:16 INFO - 'sessionrestore', 11:28:16 INFO - 'sessionrestore_no_auto_restore', 11:28:16 INFO - 'tabpaint']}, 11:28:16 INFO - 'other-e10s': {'tests': ['a11yr', 11:28:16 INFO - 'ts_paint', 11:28:16 INFO - 'tpaint', 11:28:16 INFO - 'sessionrestore', 11:28:16 INFO - 'sessionrestore_no_auto_restore', 11:28:16 INFO - 'tabpaint']}, 11:28:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:28:16 INFO - 'ts_paint', 11:28:16 INFO - 'tpaint', 11:28:16 INFO - 'sessionrestore', 11:28:16 INFO - 'sessionrestore_no_auto_restore', 11:28:16 INFO - 'tabpaint']}, 11:28:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:28:16 INFO - 'ts_paint', 11:28:16 INFO - 'tpaint', 11:28:16 INFO - 'sessionrestore', 11:28:16 INFO - 'sessionrestore_no_auto_restore', 11:28:16 INFO - 'tabpaint']}, 11:28:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['a11yr', 11:28:16 INFO - 'ts_paint', 11:28:16 INFO - 'tpaint', 11:28:16 INFO - 'sessionrestore', 11:28:16 INFO - 'sessionrestore_no_auto_restore', 11:28:16 INFO - 'tabpaint']}, 11:28:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['a11yr', 11:28:16 INFO - 'ts_paint', 11:28:16 INFO - 'tpaint', 11:28:16 INFO - 'sessionrestore', 11:28:16 INFO - 'sessionrestore_no_auto_restore', 11:28:16 INFO - 'tabpaint']}, 11:28:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['tsvgx', 11:28:16 INFO - 'tsvgr_opacity', 11:28:16 INFO - 'tart', 11:28:16 INFO - 'tscrollx', 11:28:16 INFO - 'cart']}, 11:28:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:28:16 INFO - 'tsvgr_opacity', 11:28:16 INFO - 'tart', 11:28:16 INFO - 'tscrollx', 11:28:16 INFO - 'cart']}, 11:28:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'talos_options': ['--disable-e10s'], 11:28:16 INFO - 'tests': ['tp5o']}, 11:28:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'tests': ['tp5o']}, 11:28:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'talos_options': ['--disable-e10s', 11:28:16 INFO - '--xperf_path', 11:28:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:16 INFO - 'tests': ['tp5n']}, 11:28:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:28:16 INFO - 'talos_options': ['--xperf_path', 11:28:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:16 INFO - 'tests': ['tp5n']}}, 11:28:16 INFO - 'talos.zip': {'path': '', 11:28:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:28:16 INFO - Running post-action listener: _resource_record_post_action 11:28:16 INFO - [mozharness: 2016-08-25 18:28:16.944785Z] Finished populate-webroot step (success) 11:28:16 INFO - [mozharness: 2016-08-25 18:28:16.944928Z] Running create-virtualenv step. 11:28:16 INFO - Running pre-action listener: _resource_record_pre_action 11:28:16 INFO - Running main action method: create_virtualenv 11:28:16 INFO - Creating virtualenv /builds/slave/test/build/venv 11:28:16 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 11:28:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:28:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:28:17 INFO - Using real prefix '/tools/python27' 11:28:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:28:17 INFO - Installing distribute.............................................................................................................................................................................................done. 11:28:20 INFO - Installing pip.................done. 11:28:20 INFO - Return code: 0 11:28:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:20 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:20 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 11:28:20 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 11:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:20 INFO - 'HOME': '/Users/cltbld', 11:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:20 INFO - 'LOGNAME': 'cltbld', 11:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:20 INFO - 'MOZ_NO_REMOTE': '1', 11:28:20 INFO - 'NO_EM_RESTART': '1', 11:28:20 INFO - 'PAGER': '/bin/cat', 11:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:20 INFO - 'PWD': '/builds/slave/test', 11:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:20 INFO - 'SHELL': '/bin/bash', 11:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:20 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:20 INFO - 'USER': 'cltbld', 11:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:20 INFO - 'XPC_FLAGS': '0x0', 11:28:20 INFO - 'XPC_SERVICE_NAME': '0', 11:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:20 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 11:28:20 INFO - Cleaning up... 11:28:20 INFO - Return code: 0 11:28:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:20 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:20 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 11:28:20 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 11:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:20 INFO - 'HOME': '/Users/cltbld', 11:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:20 INFO - 'LOGNAME': 'cltbld', 11:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:20 INFO - 'MOZ_NO_REMOTE': '1', 11:28:20 INFO - 'NO_EM_RESTART': '1', 11:28:20 INFO - 'PAGER': '/bin/cat', 11:28:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:20 INFO - 'PWD': '/builds/slave/test', 11:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:20 INFO - 'SHELL': '/bin/bash', 11:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:20 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:20 INFO - 'USER': 'cltbld', 11:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:20 INFO - 'XPC_FLAGS': '0x0', 11:28:20 INFO - 'XPC_SERVICE_NAME': '0', 11:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:21 INFO - Downloading/unpacking psutil>=3.1.1 11:28:21 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 11:28:21 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 11:28:21 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 11:28:21 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 11:28:21 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 11:28:21 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 11:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:28:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:24 INFO - Installing collected packages: psutil 11:28:24 INFO - Running setup.py install for psutil 11:28:24 INFO - building 'psutil._psutil_osx' extension 11:28:24 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 11:28:24 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 11:28:24 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 11:28:25 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 11:28:25 INFO - building 'psutil._psutil_posix' extension 11:28:25 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 11:28:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:28:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:28:25 INFO - ^ 11:28:25 INFO - 1 warning generated. 11:28:25 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 11:28:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:25 INFO - Successfully installed psutil 11:28:25 INFO - Cleaning up... 11:28:25 INFO - Return code: 0 11:28:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:25 INFO - 'HOME': '/Users/cltbld', 11:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:25 INFO - 'LOGNAME': 'cltbld', 11:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:25 INFO - 'MOZ_NO_REMOTE': '1', 11:28:25 INFO - 'NO_EM_RESTART': '1', 11:28:25 INFO - 'PAGER': '/bin/cat', 11:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:25 INFO - 'PWD': '/builds/slave/test', 11:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:25 INFO - 'SHELL': '/bin/bash', 11:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:25 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:25 INFO - 'USER': 'cltbld', 11:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:25 INFO - 'XPC_FLAGS': '0x0', 11:28:25 INFO - 'XPC_SERVICE_NAME': '0', 11:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:28:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:28:28 INFO - Installing collected packages: mozsystemmonitor 11:28:28 INFO - Running setup.py install for mozsystemmonitor 11:28:28 INFO - Successfully installed mozsystemmonitor 11:28:28 INFO - Cleaning up... 11:28:28 INFO - Return code: 0 11:28:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:28 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:28 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 11:28:28 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 11:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:28 INFO - 'HOME': '/Users/cltbld', 11:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:28 INFO - 'LOGNAME': 'cltbld', 11:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:28 INFO - 'MOZ_NO_REMOTE': '1', 11:28:28 INFO - 'NO_EM_RESTART': '1', 11:28:28 INFO - 'PAGER': '/bin/cat', 11:28:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:28 INFO - 'PWD': '/builds/slave/test', 11:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:28 INFO - 'SHELL': '/bin/bash', 11:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:28 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:28 INFO - 'USER': 'cltbld', 11:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:28 INFO - 'XPC_FLAGS': '0x0', 11:28:28 INFO - 'XPC_SERVICE_NAME': '0', 11:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:28 INFO - Downloading/unpacking jsonschema==2.5.1 11:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:28:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:28:31 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 11:28:31 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 11:28:31 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 11:28:31 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 11:28:31 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 11:28:31 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 11:28:31 INFO - Downloading functools32-3.2.3-2.tar.gz 11:28:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:28:31 INFO - warning: no files found matching '*.txt' 11:28:31 INFO - no previously-included directories found matching 'build' 11:28:31 INFO - no previously-included directories found matching 'dist' 11:28:31 INFO - no previously-included directories found matching '.git*' 11:28:31 INFO - Installing collected packages: jsonschema, functools32 11:28:31 INFO - Running setup.py install for functools32 11:28:31 INFO - warning: no files found matching '*.txt' 11:28:31 INFO - no previously-included directories found matching 'build' 11:28:31 INFO - no previously-included directories found matching 'dist' 11:28:31 INFO - no previously-included directories found matching '.git*' 11:28:31 INFO - Successfully installed jsonschema functools32 11:28:31 INFO - Cleaning up... 11:28:31 INFO - Return code: 0 11:28:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:31 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:31 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 11:28:31 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 11:28:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:31 INFO - 'HOME': '/Users/cltbld', 11:28:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:31 INFO - 'LOGNAME': 'cltbld', 11:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:31 INFO - 'MOZ_NO_REMOTE': '1', 11:28:31 INFO - 'NO_EM_RESTART': '1', 11:28:31 INFO - 'PAGER': '/bin/cat', 11:28:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:31 INFO - 'PWD': '/builds/slave/test', 11:28:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:31 INFO - 'SHELL': '/bin/bash', 11:28:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:31 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:31 INFO - 'USER': 'cltbld', 11:28:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:31 INFO - 'XPC_FLAGS': '0x0', 11:28:31 INFO - 'XPC_SERVICE_NAME': '0', 11:28:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:28:32 INFO - Cleaning up... 11:28:32 INFO - Return code: 0 11:28:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:28:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:32 INFO - 'HOME': '/Users/cltbld', 11:28:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:32 INFO - 'LOGNAME': 'cltbld', 11:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:32 INFO - 'MOZ_NO_REMOTE': '1', 11:28:32 INFO - 'NO_EM_RESTART': '1', 11:28:32 INFO - 'PAGER': '/bin/cat', 11:28:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:32 INFO - 'PWD': '/builds/slave/test', 11:28:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:32 INFO - 'SHELL': '/bin/bash', 11:28:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:32 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:32 INFO - 'USER': 'cltbld', 11:28:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:32 INFO - 'XPC_FLAGS': '0x0', 11:28:32 INFO - 'XPC_SERVICE_NAME': '0', 11:28:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:32 INFO - Downloading/unpacking blobuploader==1.2.4 11:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:34 INFO - Downloading blobuploader-1.2.4.tar.gz 11:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:28:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:28:34 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 11:28:34 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 11:28:34 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 11:28:34 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 11:28:34 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 11:28:34 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 11:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:28:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:35 INFO - Downloading docopt-0.6.1.tar.gz 11:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:28:35 INFO - Installing collected packages: blobuploader, requests, docopt 11:28:35 INFO - Running setup.py install for blobuploader 11:28:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:28:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:28:36 INFO - Running setup.py install for requests 11:28:36 INFO - Running setup.py install for docopt 11:28:36 INFO - Successfully installed blobuploader requests docopt 11:28:36 INFO - Cleaning up... 11:28:36 INFO - Return code: 0 11:28:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:36 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:36 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 11:28:36 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 11:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:36 INFO - 'HOME': '/Users/cltbld', 11:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:36 INFO - 'LOGNAME': 'cltbld', 11:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:36 INFO - 'MOZ_NO_REMOTE': '1', 11:28:36 INFO - 'NO_EM_RESTART': '1', 11:28:36 INFO - 'PAGER': '/bin/cat', 11:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:36 INFO - 'PWD': '/builds/slave/test', 11:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:36 INFO - 'SHELL': '/bin/bash', 11:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:36 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:36 INFO - 'USER': 'cltbld', 11:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:36 INFO - 'XPC_FLAGS': '0x0', 11:28:36 INFO - 'XPC_SERVICE_NAME': '0', 11:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-5ErtGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-S69PY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-OBXMUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-NcmIxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-6j1Cw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-OlsNge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-PItsPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-BRneLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:37 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-PDfKuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-LZ2IFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-vyqkUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-sCSfgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-XKc9LO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-0HZbRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Z7itoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-qc691u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:38 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-CNbQKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:28:38 INFO - Running setup.py install for manifestparser 11:28:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:28:38 INFO - Running setup.py install for mozcrash 11:28:39 INFO - Running setup.py install for mozdebug 11:28:39 INFO - Running setup.py install for mozdevice 11:28:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Running setup.py install for mozfile 11:28:39 INFO - Running setup.py install for mozhttpd 11:28:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Running setup.py install for mozinfo 11:28:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Running setup.py install for mozInstall 11:28:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:28:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Running setup.py install for mozleak 11:28:40 INFO - Running setup.py install for mozlog 11:28:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Running setup.py install for moznetwork 11:28:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Running setup.py install for mozprocess 11:28:40 INFO - Running setup.py install for mozprofile 11:28:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Running setup.py install for mozrunner 11:28:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:28:40 INFO - Running setup.py install for mozscreenshot 11:28:41 INFO - Running setup.py install for moztest 11:28:41 INFO - Running setup.py install for mozversion 11:28:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:28:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:28:41 INFO - Cleaning up... 11:28:41 INFO - Return code: 0 11:28:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:41 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:41 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 11:28:41 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 11:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:41 INFO - 'HOME': '/Users/cltbld', 11:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:41 INFO - 'LOGNAME': 'cltbld', 11:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:41 INFO - 'MOZ_NO_REMOTE': '1', 11:28:41 INFO - 'NO_EM_RESTART': '1', 11:28:41 INFO - 'PAGER': '/bin/cat', 11:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:41 INFO - 'PWD': '/builds/slave/test', 11:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:41 INFO - 'SHELL': '/bin/bash', 11:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:41 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:41 INFO - 'USER': 'cltbld', 11:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:41 INFO - 'XPC_FLAGS': '0x0', 11:28:41 INFO - 'XPC_SERVICE_NAME': '0', 11:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Sv5u9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:41 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)) 11:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-7Cm9Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:41 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)) 11:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:41 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-QA2aju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-w8nsKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-NnGP7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-vwqBxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-78F934-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-oklCo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-Piru90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-1n2raC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-HEusjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-KKZdAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:42 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)) 11:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:42 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-6NT9I2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:43 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)) 11:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-mjG8vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:43 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)) 11:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-ndpaN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:43 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)) 11:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-F6qUMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:43 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)) 11:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:43 INFO - Running setup.py (path:/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/pip-P3JCry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:43 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)) 11:28:43 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)) 11:28:43 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)) 11:28:43 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)) 11:28:43 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)) 11:28:43 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)) 11:28:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:28:43 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 11:28:43 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 11:28:43 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 11:28:43 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 11:28:43 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 11:28:43 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 11:28:45 INFO - Downloading blessings-1.6.tar.gz 11:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:28:45 INFO - Installing collected packages: blessings 11:28:45 INFO - Running setup.py install for blessings 11:28:46 INFO - Successfully installed blessings 11:28:46 INFO - Cleaning up... 11:28:46 INFO - Return code: 0 11:28:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:28:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:28:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:28:46 INFO - Reading from file tmpfile_stdout 11:28:46 INFO - Current package versions: 11:28:46 INFO - blessings == 1.6 11:28:46 INFO - blobuploader == 1.2.4 11:28:46 INFO - docopt == 0.6.1 11:28:46 INFO - functools32 == 3.2.3-2 11:28:46 INFO - jsonschema == 2.5.1 11:28:46 INFO - manifestparser == 1.1 11:28:46 INFO - mozInstall == 1.12 11:28:46 INFO - mozcrash == 0.17 11:28:46 INFO - mozdebug == 0.1 11:28:46 INFO - mozdevice == 0.48 11:28:46 INFO - mozfile == 1.2 11:28:46 INFO - mozhttpd == 0.7 11:28:46 INFO - mozinfo == 0.9 11:28:46 INFO - mozleak == 0.1 11:28:46 INFO - mozlog == 3.3 11:28:46 INFO - moznetwork == 0.27 11:28:46 INFO - mozprocess == 0.23 11:28:46 INFO - mozprofile == 0.28 11:28:46 INFO - mozrunner == 6.12 11:28:46 INFO - mozscreenshot == 0.1 11:28:46 INFO - mozsystemmonitor == 0.3 11:28:46 INFO - moztest == 0.7 11:28:46 INFO - mozversion == 1.4 11:28:46 INFO - psutil == 3.1.1 11:28:46 INFO - requests == 1.2.3 11:28:46 INFO - wsgiref == 0.1.2 11:28:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:46 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 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28:46 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 11:28:46 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 11:28:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:46 INFO - 'HOME': '/Users/cltbld', 11:28:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:46 INFO - 'LOGNAME': 'cltbld', 11:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:46 INFO - 'MOZ_NO_REMOTE': '1', 11:28:46 INFO - 'NO_EM_RESTART': '1', 11:28:46 INFO - 'PAGER': '/bin/cat', 11:28:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:46 INFO - 'PWD': '/builds/slave/test', 11:28:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:46 INFO - 'SHELL': '/bin/bash', 11:28:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:46 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:46 INFO - 'USER': 'cltbld', 11:28:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:46 INFO - 'XPC_FLAGS': '0x0', 11:28:46 INFO - 'XPC_SERVICE_NAME': '0', 11:28:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 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)) 11:28:46 INFO - Cleaning up... 11:28:46 INFO - Return code: 0 11:28:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107851618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0d9d60520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, '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.jki35UXnJu/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.4CXCNpijb5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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 11:28: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', 'jsonschema'] in /builds/slave/test/build 11:28: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 jsonschema 11:28:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:28:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:46 INFO - 'HOME': '/Users/cltbld', 11:28:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:46 INFO - 'LOGNAME': 'cltbld', 11:28:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:46 INFO - 'MOZ_NO_REMOTE': '1', 11:28:46 INFO - 'NO_EM_RESTART': '1', 11:28:46 INFO - 'PAGER': '/bin/cat', 11:28:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:46 INFO - 'PWD': '/builds/slave/test', 11:28:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:46 INFO - 'SHELL': '/bin/bash', 11:28:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:28:46 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:28:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:46 INFO - 'USER': 'cltbld', 11:28:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:46 INFO - 'XPC_FLAGS': '0x0', 11:28:46 INFO - 'XPC_SERVICE_NAME': '0', 11:28:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:28:47 INFO - Cleaning up... 11:28:47 INFO - Return code: 0 11:28:47 INFO - Running post-action listener: _resource_record_post_action 11:28:47 INFO - Running post-action listener: _start_resource_monitoring 11:28:47 INFO - Starting resource monitoring. 11:28:47 INFO - [mozharness: 2016-08-25 18:28:47.089518Z] Finished create-virtualenv step (success) 11:28:47 INFO - [mozharness: 2016-08-25 18:28:47.090560Z] Running install step. 11:28:47 INFO - Running pre-action listener: _resource_record_pre_action 11:28:47 INFO - Running main action method: install 11:28:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:28:47 INFO - Reading from file tmpfile_stdout 11:28:47 INFO - Detecting whether we're running mozinstall >=1.0... 11:28:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:28:47 INFO - Reading from file tmpfile_stdout 11:28:47 INFO - Output received: 11:28:47 INFO - Usage: mozinstall [options] installer 11:28:47 INFO - Options: 11:28:47 INFO - -h, --help show this help message and exit 11:28:47 INFO - -d DEST, --destination=DEST 11:28:47 INFO - Directory to install application into. [default: 11:28:47 INFO - "/builds/slave/test"] 11:28:47 INFO - --app=APP Application being installed. [default: firefox] 11:28:47 INFO - mkdir: /builds/slave/test/build/application 11:28:47 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'] 11:28:47 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 11:29:21 INFO - Reading from file tmpfile_stdout 11:29:21 INFO - Output received: 11:29:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:29:21 INFO - Running post-action listener: _resource_record_post_action 11:29:21 INFO - [mozharness: 2016-08-25 18:29:21.597686Z] Finished install step (success) 11:29:21 INFO - [mozharness: 2016-08-25 18:29:21.597910Z] Running run-tests step. 11:29:21 INFO - Running pre-action listener: _resource_record_pre_action 11:29:21 INFO - Running main action method: run_tests 11:29:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:21 INFO - 2016-08-25 11:29:21.612 screenresolution[1931:16355] starting screenresolution argv=screenresolution get 11:29:21 INFO - 2016-08-25 11:29:21.614 screenresolution[1931:16355] Display 0: 1600x1200x32@60 11:29:21 INFO - 2016-08-25 11:29:21.620 screenresolution[1932:16357] starting screenresolution argv=screenresolution list 11:29:21 INFO - Available Modes on Display 0 11:29:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:21 INFO - Intel Iris: 11:29:21 INFO - Chipset Model: Intel Iris 11:29:21 INFO - Type: GPU 11:29:21 INFO - Bus: Built-In 11:29:21 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:21 INFO - Vendor: Intel (0x8086) 11:29:21 INFO - Device ID: 0x0a2e 11:29:21 INFO - Revision ID: 0x0009 11:29:21 INFO - Displays: 11:29:21 INFO - Display: 11:29:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:21 INFO - Display Serial Number: 1600x1200 60 11:29:21 INFO - Main Display: Yes 11:29:21 INFO - Mirror: Off 11:29:21 INFO - Online: Yes 11:29:21 INFO - Rotation: Supported 11:29:21 INFO - Return code: 0 11:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:29:21 INFO - Python 2.7.3 11:29:21 INFO - Return code: 0 11:29:21 INFO - grabbing minidump binary from tooltool 11:29:21 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')]} 11:29:21 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 0x107720d30>, '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 0x107485db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107718b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:29:21 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 11:29:21 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 11:29:21 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 11:29:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:29:21 INFO - Return code: 0 11:29:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:29:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:29:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:29:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:29:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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 11:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0269 --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 11:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jki35UXnJu/Render', 11:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:21 INFO - 'HOME': '/Users/cltbld', 11:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:21 INFO - 'LOGNAME': 'cltbld', 11:29:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:21 INFO - 'MOZ_NO_REMOTE': '1', 11:29:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:21 INFO - 'NO_EM_RESTART': '1', 11:29:21 INFO - 'PAGER': '/bin/cat', 11:29:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:21 INFO - 'PWD': '/builds/slave/test', 11:29:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:21 INFO - 'SHELL': '/bin/bash', 11:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4CXCNpijb5/Listeners', 11:29:21 INFO - 'TMPDIR': '/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/', 11:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:21 INFO - 'USER': 'cltbld', 11:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:21 INFO - 'XPC_FLAGS': '0x0', 11:29:21 INFO - 'XPC_SERVICE_NAME': '0', 11:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0269', '--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 11:29:22 INFO - mozversion application_buildid: 20160825083725 11:29:22 INFO - mozversion application_changeset: 18ae102fbf67c2be43b3b2b15b56e90821ef11fd 11:29:22 INFO - mozversion application_display_name: Nightly 11:29:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:29:22 INFO - mozversion application_name: Firefox 11:29:22 INFO - mozversion application_remotingname: firefox 11:29:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:29:22 INFO - mozversion application_vendor: Mozilla 11:29:22 INFO - mozversion application_version: 51.0a1 11:29:22 INFO - mozversion platform_buildid: 20160825083725 11:29:22 INFO - mozversion platform_changeset: 18ae102fbf67c2be43b3b2b15b56e90821ef11fd 11:29:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:29:22 INFO - mozversion platform_version: 51.0a1 11:29:22 INFO - using testdate: 1472149762 11:29:22 INFO - actual date: 1472149762 11:29:22 INFO - starting webserver on 'localhost:51681' 11:29:22 INFO - SUITE-START | Running 2 tests 11:29:22 INFO - TEST-START | dromaeo_css 11:29:22 INFO - operating with platform_type : mac_ 11:29:22 INFO - Initialising browser for dromaeo_css test... 11:29:22 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpazMlrV/profile http://localhost:51681/getInfo.html) 11:29:24 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 11:29:24 INFO - PROCESS | 1942 | colorDepth:24 11:29:24 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 11:29:24 INFO - PROCESS | 1942 | __metrics 11:29:24 ERROR - PROCESS | 1942 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 11:29:25 INFO - TEST-INFO | 1942: exit 0 11:29:25 INFO - Browser initialized. 11:29:25 INFO - Running cycle 1/1 for dromaeo_css test... 11:29:25 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmpazMlrV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:37 INFO - PROCESS | 1945 | RSS: Main: 242589696 11:29:37 INFO - PROCESS | 1945 | 11:30:27 INFO - PROCESS | 1945 | 1472149827230 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] 11:32:39 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51681/tests/dromaeo/cssquery-ext.html) 11:32:40 INFO - PROCESS | 1945 | RSS: Main: 265625600 11:32:40 INFO - PROCESS | 1945 | 11:35:42 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-ext.html (next: http://localhost:51681/tests/dromaeo/cssquery-jquery.html) 11:35:43 INFO - PROCESS | 1945 | RSS: Main: 274399232 11:35:43 INFO - PROCESS | 1945 | 11:38:45 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51681/tests/dromaeo/cssquery-mootools.html) 11:38:45 INFO - PROCESS | 1945 | RSS: Main: 277143552 11:38:45 INFO - PROCESS | 1945 | 11:41:47 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51681/tests/dromaeo/cssquery-prototype.html) 11:41:48 INFO - PROCESS | 1945 | RSS: Main: 271314944 11:41:48 INFO - PROCESS | 1945 | 11:44:51 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51681/tests/dromaeo/cssquery-yui.html) 11:44:51 INFO - PROCESS | 1945 | RSS: Main: 276852736 11:44:51 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:51681/tests/dromaeo/cssquery-yui.html (next: http://localhost:51681/tests/dromaeo/cssquery-dojo.html) 11:47:54 INFO - PROCESS | 1945 | RSS: Main: 278994944 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | __start_tp_report 11:47:54 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 11:47:54 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 11:47:54 INFO - PROCESS | 1945 | |i|pagename|runs| 11:47:54 INFO - PROCESS | 1945 | |0;dojo.html;2868.3976364963355;3403.8978830635087;3418.675225853545;3433.107392078059;3460.515527826103;5628;5790.942090579093;5818.505427038702;5911.083782013784;5926.436988486098;5740.339860915996;5806.158107074482;5815.040518314473;5843.152742852284;5870.794522191724;4025.9798701006453;5124.846909445382;5209.531142197202;5294.867628309285;5383.273258110164;5373.623846330758;5393.406725260218;5411.512963833255;5443.346798384179;5457.454254574531;5203.739813009334;5221.869453263699;5304.814331498417;5324.973375133119;5343.957928203982;5301.549368303698;5310.60170487215;5313.256144139824;5340.973295133538;5353.1434970404935;5269.077911365495;5277.129273669878;5302.946970530284;5365.651232669903;5380.865478363033;5560.471755183251;5765.942340576625;5831.825045248607;5877.382874798127;5933.3473317934995;6131.47882429994;6168.907466388007;6214.223222097238;6219.471344935686;6223.190985171898;6215.502759779207;6216.751329946796;6225.657588832612;6228.657423841687;6241.313455519889;4830.2030165022725;5812.3025236971835;5870.941290587125;5882.49998750111;5945.910811337833;6297.149884765589;6298.1812364392335;6299;6300.558960872695;6310.873782524415;6172.969135154385;6183.84540386485;6244.125822384779;6269.780557680459;6279.96860015697;6128.442013630915;6143.815685529441;6167.229096362955;6220.7511699532415;6256.343083976117;5924.437178468039;6221.96079995207;6236.376362363727;6254.499640028786;6268.937310626927;6215.378462153839;6250.249970003538;6263.217097862767;6283.403076707706;6285.120083188357;4893.437254715754;4956.256561515802;4964.702117872939;4994.600431965434;5007.524285192973;4459.554044595579;4483.887902802456;4496.460424749051;4524.298733696282;4546.272596384562;5761.222234998221;5826.038703613856;5854.268216472941;5858.648481091148;5884.941150588525;6131.315499378962;6185;6188.44304012641;6229.563930524911;6230.7819226326865;5803.216565763652;5808.215890854765;5816.156657284755;5836.299644042742;5860.501857342088;5432.429594892558;5434.728263586884;5437.537809286255;5444.482774136451;5450.645708028966;6189.04069869171;6249.281332646804;6267.373262673696;6291.905621415683;6292.8426789328805;6260.311365749855;6268.686565671789;6275.52933529978;6286.874262514815;6290.937090629035;5659.349719431027;5962.658401859547;6345.4923606112325;6378.2983871774995;6394.968025159845;6097.512199024156;6163.3836616338;6181.53638477125;6195.597286176384;6241;6253.155823963707;6255.56211065221;6255.56211065221;6278.372162783685;6288.811335659938;6109.6639684817765;6259.092431597484;6262.2172228471445;6274.1843560336865;6275.843103922256;5119.436861945109;5998.940010600013;6048.60684055535;6104.389561044038;6107.144999699956;5878.265216847894;5900.675462849585;6005.36943620931;6021;6026.517878569792;4995.900081998267;6111.960966635772;6159.19930409044;6166.845828854314;6176.9073463897175;5870.002099642986;5965.463108320272;6000.669963151894;6009.909851352146;6041.335453100068 11:47:54 INFO - PROCESS | 1945 | |1;ext.html;486.04007086005134;541.6181591977656;542.7286356821588;555.5472290083579;560.4759549820915;32051.839740801293;36194.276114477696;42406;42551;42616.786916065415;43465;43745.562544374545;44013.11973760531;44141.7792911035;44144.779276103574;9623.759406014835;9811;9822.607095716163;9861.506924653775;9880.209583233323;8333.541655208992;9732.65935692251;9738.07488288615;9767.609295628166;9787.804243915118;9663;9721.416714997124;9749.830020397561;9753.756156096084;9761.365511241782;9706.61173553057;9759;9764.902350976507;9768;9786.41281523111;9694.545818127266;9771.267154963336;9795.461249631267;9800.509974501247;9803.803923921481;41284.38073428901;41485.1702965939;41664.58335416668;41706;41811.581884181374;3410.8635654573786;3538.3630946429635;3690.538682664667;3990.4213888986224;4208.494980602347;30804.537931931038;32363.352732945445;32569.860054897967;32632.510512342335;32744.508832367534;3090.428270769915;3333.683300086488;3346.832658367072;3385.086026772782;3399.388110140174;361746.1912690446;363272;367336.1633191844;367428;370417;62360.68819655872;63019;63038.68480657567;63064.68467657724;63100;37310.44034339487;38074;65930;66406;66461.6676916622;15494.690106197813;16937.153142342835;16939.407120750715;16939.830601693826;16978.91510542464;12938.482460701467;13036.826685598153;13073.477060917647;13084.214947103204;13120.934395327962;17652.11739413024;19711.80288197128;19835;19867.701984470244;19983.400497984792;6756.662166891636;7619.123800762873;8025.9598702006115;8126.796830079177;8151.714689985941;766.306492624176;893.7274131389988;1022.9130523905402;1053.2311412668641;1094.7044298039484;30856.691433085383;31394.21514462157;31421.685783142333;31620.525692114636;31647.367052658818;1052.736815796051;1080.7622323088908;1116.6315116011697;1124;1152.8962393384636;15648.765268520981;16377.59056023585;16377.83622163787;16445.67108657844;16507.422240221535;655348;673984.6300768465;683258;687481;724860;53778.73110634422;62379.6881015592;63401.68299158567;63425.511596861696;63723.36276637267;1334.4462048250061;1354.7425989062046;1392.540461647654;1394.6861956060009;1395.3372148229712;29950.70049299479;30978.0706578803;30997.53503697401;31029.534556981213;31578;49754.50245497499;54357.91284174215;55208.72395637996;55578.109532332346;56436;504318;505309;506244;510174;510634.44682776346;27743.890244389997;28141.577876331463;28165.873365065167;28250.71749282481;28492.002779902603;49728;50351;50476.49523504718;51174.976500469034;51622;40613.59386406098;41076;42184.57815421929;42246.78876605597;42866.35700464558;40387.798061009504;40679.38980915229;40722.9819254521;40876.79561602292;42182.578174217866;34310.82844585761;39622.80188599038;39796.40305395363;39867;40140.59859401369;35308.64691353054;35707.28585428329;35800.64199358077;36484.45273320955;36631.81684091563;23447.882760586086;26189;26289.07988220403;26360.736392636594;26382.472350553264 11:47:54 INFO - PROCESS | 1945 | |2;jquery.html;328.46132342957554;331.55240425425677;379.9107209805695;390.4904100149304;412.05227975656004;17559.47321580353;18352.81647183528;20188.29340973066;21161.57676846462;21775.6733648995;20474.283400081;20924.476888077766;20973.16107355708;21454.785452145476;21670.783292167074;5565.775529383529;6024.819255422344;6163.599366041215;6200.875982480347;6228.470580000705;6188.752449901998;6248.156498872662;6251.062340648894;6255.999040153576;6262.2798378186635;5981;6091.604045737013;6093.299270583895;6102.0541816018585;6162.84592885177;5881.735321910482;5997.962729941899;6127.295361033472;6133.938660613381;6150.784722534712;5240.502152295526;5259.3951695555115;5279.5512381447625;5282.498162674539;5294.867628309285;13655.931720341434;23494.530109397718;23844.688542130178;24677;25794.871025644938;2282.9553244121157;2360.055977608953;2416;2457.262821153665;2968.3172870239964;10432.895671043345;11229.55081796727;13351.198928064347;15666.981646193075;16104.75842862358;1865.1699993502896;1932.1595106128739;1948.0584218134632;1965.9508512287164;1999.9000049997587;117909.82090179074;123883.38058309742;125501.489970201;125592;125861.37069314686;11246.550137994389;11356.48895799691;11493.885061149449;11597.826032609517;11808.350540720172;14528.820676898338;14549.636259093608;14555.417783288762;14568.271586420637;14598.635034124232;8298.958505207434;8552.358573107042;8597.613107410181;8703.91296087031;8708.998465176459;8528.317734581218;8540.041108738591;8594.699185528476;8601.61292741828;8717.956410217908;4433.512313645497;4490.483594386622;4529.932051019237;4592.609628181575;4624.213883639789;1648.8433598808094;1931.6329897319465;1975.2099160335692;1985.7219989201524;1994.1425187169366;846.627483907079;912.539167720297;925.6019911438146;940.6378544260509;999.2954966748425;21001.894990525256;21393;21563.78436215649;21577.02903369352;21620.783792161877;929.0894922975522;964.8552717092352;968.8885778135464;981.4160574458186;1000.3597697473623;10016.899831001743;10880.510377033053;11281.943590281997;11325;11511.942440287912;552432;553081;556973;580282;610926;109970.91774017843;115306;115670.8432915677;118359.40820295844;121741.96286681597;894.2711689972651;940.9576569054409;1022.8619136416637;1024.1243736605095;1028.3675539543126;14482.420703171754;15198.46805361807;15203.391864325304;17273.740893886352;19983.500412489804;51564;52461;54218.45781542292;55411.060612878355;55800.441995579524;76047;76410;80242.79635805351;81075;86457.1354286449;6521.739130434919;7045.330693584101;7180;7900.288973992138;8291.295239904435;12763.936180319039;13385.732285354434;13531.323433828466;13780;13814.930925345308;11680.649580512598;11827.053835693296;11923.701907452383;11962.521499140285;11965.282083075042;10293.43386113741;10556.472176390997;11537.653870383901;11643.941780291045;11795.410229488663;10165;11457.854214578809;12063.819042714542;12130.393480326125;12184.57353992606;7340.192578816219;7491.513051651626;7555.357794587521;7562.882392929989;7628.084629844454;11281.21031527785;11707.707307317385;11826.704332391759;11881.168318217642;11915.940420297842 11:47:54 INFO - PROCESS | 1945 | |3;mootools.html;1514.8863835212358;1555.7510798272276;1764.9470515884527;1854.1563588567203;1884.5100273928783;7983.042034955799;8460.873086903694;8643.956780216091;9029.187373136416;9053.230475409599;8638.697645582406;8812.647494100243;8852.5573721314;8852.867206991885;8885.2447541959;2375.8455700379463;2426.2236084452984;2428.174420696963;2430.8663023533786;2432.19737486629;2067.648499755045;2372.019363831203;2397.29279862441;2432.160904487958;2451.6077427611585;2166.764943981931;2383.9761602384015;2422.563938491071;2433.75662433757;2441.9987804999955;2388.952220955571;2407.518496300738;2420.419099416145;2421.2494126820743;2429.441228517442;2386.6300723387903;2392.509535545209;2404.446977195255;2409.265174121892;2412.819038572114;8011.439199256054;8877.556122193864;8978.147076027766;9019.413738107001;9043.050479699603;5288.709120998344;5354.41101478837;5422.972885135549;5428.75570599324;5464.0711079116645;3578.58846232684;3704.1665625234377;3720.6279372062577;3760.8683696070507;3797.240551889633;1122.859642544682;1145.5360578965533;1146.8681101673249;1158.652404278713;1795.1203910084096;11376.203665743402;11495.080393568578;11655.650330490098;11662.183647144704;11694.41527923609;4361.978190109029;4635.675502714845;4686.390769199982;4699.483056863743;4757.833475828329;9050.502222377701;9119.908800912039;9130.771730706652;9139.680111196076;9179.265658747285;5216.869578260574;5261.500157485033;5263.578913686896;5265.420803711589;5268.2361057647195;4400.141972315432;4773.95226047742;4888.364512613338;4894.119058569491;4933.383327084114;3381.8332675227007;3507.5440192774777;3687.575928768226;3752.249550089993;3767.208886133888;1009.7223263602422;1141.1612464838338;1193.462941676249;1200.7958647029852;1222.0468034932767;896.7758060484879;912.9908700913039;917.5733284022828;951.5402684061778;956.4070276428658;5686.60193786431;5932.6058376281535;5958.553108516879;6125.295591007002;6180.196574445384;440.16368899091344;443.18011678394714;443.58967954641827;458.26677316293666;458.5597826086994;3970.1662650843646;3998.3802510610903;4074.490688663917;4077.2049450356894;4101.958980410217;10164.542595583216;10879.673609791718;10933.671989840317;10936.343819370863;10970.396628185526;10216.08055275014;10857.837132443019;10893.782124357469;10905.781884362268;10905.890941090645;7914.525128492309;8305;8326.375521835887;8331.416800824005;8398.580070996426;5955.583109182489;6081.087836824512;6120.173776540289;6129.785457509144;6174.722137503913;9534.761630959281;9906.157976572073;10179.134773544332;10182.338148020355;10219.642312519027;10152.03555662211;10215.846762298423;10226.386416815016;10271.845922311022;10284.537195826355;5475.863103422287;6930.757423490154;7508.849823003618;7534.434917381109;7684.500507467221;8415;8508.489490630582;8515.998740295901;8528.957355213184;8562.143785621389;7422.777316680508;7448.217937116741;7604.923950760422;7684.077910650758;7727;7388.261173882569;7526.811829704301;7534.284242996907;7660.3488703460835;7710.92289077102;7019.54372965776;7162.928370716226;7185.604791736505;7192.065031545865;7200.747973820891;4985.925211121908;5069.340516811393;5124.871878203075;5155.819546315861;5160.225966105114;5001.449840517613;5033.597312215087;5145.45972672864;5177.197534382177;5235.9056377447805 11:47:54 INFO - PROCESS | 1945 | |4;prototype.html;306.5019343566704;309.7166093024882;315.6086452798529;319.98240096794666;321.2114259492945;4397.44864065692;4698.506656801038;5439.564834813215;5443.087947947849;5612.747426365813;4403.361512580674;5056.8482945511605;5519.199716041182;5533.012173426355;5557.694326812024;3548.662877026691;3750.1562148516637;3893.442622950814;4106.137711080662;4256.97871510642;3948.743331683446;4217.746935183899;4257.020485474254;4279.293916503789;4288.678349123828;3487.6337984511642;3683.502727131843;3847.576766555677;4038.434619153321;4191.685623578229;3275.2630658101734;3297.7856439331485;3451.1027132945364;3626.274745050974;3912.784796836173;3052.3895220955674;3339.782914110575;3645.1798345372345;3646.361886669822;3656.670899619047;5256;5445.346558412976;5483;5483.7806487740445;5547.611667183299;3687.6128006559193;4431.379606855043;4629.629629629621;4763.737994410306;4776.73105274995;4905.484924082955;5857.384974577651;5873.706314684291;5908.497777688902;5924.4667979882015;3332.933461292363;3451.6548345165534;3489.284696637183;3750.306193354238;3781.959961010744;5732.82801515951;6190.938090619081;6284.7486100555925;6299.740051989575;6352.555321127523;4038.496132397873;4050.886006348278;4051.6150965658217;4068.7997040873015;4089.44792453021;3761.0597350662333;3762.7554208976876;3905.7892053462947;4018.598140185958;4038.4749982502085;4532.592066714012;4762.452317983407;4795.400574928134;4816.662833601615;4904.337914381513;4862;5943.821685349447;5962.5826192167;5978.790742323998;6002.849928751816;1309.888659463937;1348.3325753751956;1349.3455673998103;1364.7884577890443;1371.6845125621162;819.7253271163398;867.319154463743;882.9911700883038;890.8196639452751;923.6443969071955;5309.2036194570355;5390.946090539122;5414.945850541523;5425.4697327731;5435.239066530688;5326.937011218565;5952.285725712942;5955;5967.910481342783;6011.158437818709;4491.708038977456;5121.61587880903;5226.163813789775;5398.703071331036;5425.701586412785;2525.89088111429;2571.8683779137496;2624.9106620752555;2659.6276521287036;2679.4775018871132;5160.8967820643375;5183.637145399785;5194.169177798109;5208.453112423217;5222.3733152022005;4608.354830323758;5216.504432078947;5835.328937172279;6368.5860419072615;6397.912354899679;6107.297660769068;6262.405071518198;6313.684315784284;6441.806745797634;6459.580127291711;5861.208736820645;6190.938090619036;6195.628262304276;6239.532035097477;6278.811635650938;5603.439656034364;6041.640630858022;6107.8849786610035;6110.388961103854;6160.9383906160365;5456;6385.361463853763;6402.93597064042;6443.452306553995;6467.805965821033;3476.0440878758545;3666.2850744104644;3822.7706337619834;4019.799010049427;4037.596240375939;5184.377874655065;5575.080111781673;5834.679092649775;6172.722227499863;6176.969115154396;4933.9013219736125;5793.449622285876;5918.585699001029;5919.763209471573;5946.226990491208;5924.851878703067;5932.911006334994;5954.183973888005;5962.55280853929;6020.307664618625;5600.859978500407;5615.185798059177;5765.3946335634155;5806.893312078018;5834.474897259267;5173;5913.645181289136;5953.404659534012;5961.956657584992;5999.460048595647;5603.551715862803;5827.24245848037;5849;5860.241927421671;5893.882122357614 11:47:54 INFO - PROCESS | 1945 | |5;yui.html;657.3426573426574;753.5064532731056;765.6975494679598;799.1808396393695;848.7692845374207;14911.179885106314;15220.315085821165;15464.226788660564;15671.921640391782;15746.21268936553;12598.559050433236;13652.522161724342;13799.034067615246;14693.77959330611;14802.481913133042;859.7893516088567;873.9038705742395;874.9518776467291;875.8729984152326;877.271864352589;699.9475039372068;862.180928118287;882.6248844241204;890.8040231149137;897.9102089791033;749.3181205103357;918.0360621347592;919.641339877445;920.484528663947;923.9168474837263;909.849874770662;916.9083091690844;920.8066306075665;923.501309292981;923.5059243304834;674.2381109346456;691.3086913086913;692.0829900381964;692.394155114275;693.7086423702058;11964.222325548812;14217.715645687029;14221.14673119616;14395.928020359936;14435.639109022253;14550;14667.706645867129;14729.926350368287;14980.850191498164;15019.549413517612;12161.331126788024;12201.694957626041;12410.069244806675;12448.439820208021;12608.085652593583;3862.671672907806;3873.8644147454984;4063.573324800883;4121.052158003676;4123.60825721556;113970.15074623119;114360;114533;115808.42095789468;120798;10780.946095269474;11136.554537818396;11163.497642606102;11167.553297867995;11242.7189320266;19458.80541194598;22833.31500054968;22917.968691408925;22961.196358127385;23008.53982920332;8212.794680133044;8311.16888311176;8375.958120209481;8490.320774338039;8596.527191004554;4553.795079221443;4611.33135695329;4620.561046700558;4621.976890115528;4634.95365046352;3553.7157027437743;3651.2514934438377;3686.6129056448685;3694.242680250542;3705.870294539732;663.2207156590986;669.210331808461;683.4942142814282;686.2005763285816;692.8025512728848;639.6929473852576;669.4811521071209;678.9236334803528;699.2692636195188;699.7788858442051;8799.252063574668;8880.777980550409;9375.343725939256;9448.95275523618;9491.620335186515;560.1849309255024;594.7472324262258;614.213806327901;631.66837410359;671.318611609217;4401;4580.519045500241;4662.813487460464;4680.221515823965;4717.952820471752;93399.0660093404;156016.21991890194;158273.1295152845;161687;161940;121171;134819;140570;140690;143490;96800.51599741956;102415.48792255991;105105;105163;105691.4715426418;8786.341024423065;8830.558472076242;8832.646694132163;8865;9017.2786177107;58325.70837145787;58871.41128588659;62777.977761778035;66470.66764666146;66727.66636166789;70505.6474717623;83235;94842.57736134101;100054;101779.49110254401;11470.139739519402;11619.825702614637;11628.244164129304;11631.709207269885;13671.453141874612;14722.542458315378;14844.035137716013;14867.40530378816;15196.392144314104;15345.539633811004;13797.586072417844;13823.930880345533;14021.719565608426;14079.859201408264;14113.012089154072;13815.585532434043;13859.376328065462;13906.235157066458;14183.290835458392;14201.857981420468;13499.932500337436;13580.388882500509;13690.04169708111;14094.859051409354;14229.786553201504;11159.163062770162;11289.153313501356;11359.148063895405;11481.311121332747;11701.707457313636;11255.268407553483;11565.479553420284;11708.531658733555;11721.355325457182;11845.23006004607 11:47:54 INFO - PROCESS | 1945 | __end_tp_report 11:47:54 INFO - PROCESS | 1945 | __start_cc_report 11:47:54 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,382 11:47:54 INFO - PROCESS | 1945 | __end_cc_report 11:47:54 INFO - PROCESS | 1945 | __startTimestamp1472150874864__endTimestamp 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | ------- Summary: start ------- 11:47:54 INFO - PROCESS | 1945 | Number of tests: 6 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | [#0] dojo.html Cycles:165 Average:5763.36 Median:5962.66 stddev:648.10 (10.9%) stddev-sans-first:609.00 11:47:54 INFO - PROCESS | 1945 | Values: 2868.4 3403.9 3418.7 3433.1 3460.5 5628.0 5790.9 5818.5 5911.1 5926.4 5740.3 5806.2 5815.0 5843.2 5870.8 4026.0 5124.8 5209.5 5294.9 5383.3 5373.6 5393.4 5411.5 5443.3 5457.5 5203.7 5221.9 5304.8 5325.0 5344.0 5301.5 5310.6 5313.3 5341.0 5353.1 5269.1 5277.1 5302.9 5365.7 5380.9 5560.5 5765.9 5831.8 5877.4 5933.3 6131.5 6168.9 6214.2 6219.5 6223.2 6215.5 6216.8 6225.7 6228.7 6241.3 4830.2 5812.3 5870.9 5882.5 5945.9 6297.1 6298.2 6299.0 6300.6 6310.9 6173.0 6183.8 6244.1 6269.8 6280.0 6128.4 6143.8 6167.2 6220.8 6256.3 5924.4 6222.0 6236.4 6254.5 6268.9 6215.4 6250.2 6263.2 6283.4 6285.1 4893.4 4956.3 4964.7 4994.6 5007.5 4459.6 4483.9 4496.5 4524.3 4546.3 5761.2 5826.0 5854.3 5858.6 5884.9 6131.3 6185.0 6188.4 6229.6 6230.8 5803.2 5808.2 5816.2 5836.3 5860.5 5432.4 5434.7 5437.5 5444.5 5450.6 6189.0 6249.3 6267.4 6291.9 6292.8 6260.3 6268.7 6275.5 6286.9 6290.9 5659.3 5962.7 6345.5 6378.3 6395.0 6097.5 6163.4 6181.5 6195.6 6241.0 6253.2 6255.6 6255.6 6278.4 6288.8 6109.7 6259.1 6262.2 6274.2 6275.8 5119.4 5998.9 6048.6 6104.4 6107.1 5878.3 5900.7 6005.4 6021.0 6026.5 4995.9 6112.0 6159.2 6166.8 6176.9 5870.0 5965.5 6000.7 6009.9 6041.3 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | [#1] ext.html Cycles:180 Average:66824.52 Median:40976.40 stddev:143221.31 (349.5%) stddev-sans-first:143536.48 11:47:54 INFO - PROCESS | 1945 | Values: 486.0 541.6 542.7 555.5 560.5 32051.8 36194.3 42406.0 42551.0 42616.8 43465.0 43745.6 44013.1 44141.8 44144.8 9623.8 9811.0 9822.6 9861.5 9880.2 8333.5 9732.7 9738.1 9767.6 9787.8 9663.0 9721.4 9749.8 9753.8 9761.4 9706.6 9759.0 9764.9 9768.0 9786.4 9694.5 9771.3 9795.5 9800.5 9803.8 41284.4 41485.2 41664.6 41706.0 41811.6 3410.9 3538.4 3690.5 3990.4 4208.5 30804.5 32363.4 32569.9 32632.5 32744.5 3090.4 3333.7 3346.8 3385.1 3399.4 361746.2 363272.0 367336.2 367428.0 370417.0 62360.7 63019.0 63038.7 63064.7 63100.0 37310.4 38074.0 65930.0 66406.0 66461.7 15494.7 16937.2 16939.4 16939.8 16978.9 12938.5 13036.8 13073.5 13084.2 13120.9 17652.1 19711.8 19835.0 19867.7 19983.4 6756.7 7619.1 8026.0 8126.8 8151.7 766.3 893.7 1022.9 1053.2 1094.7 30856.7 31394.2 31421.7 31620.5 31647.4 1052.7 1080.8 1116.6 1124.0 1152.9 15648.8 16377.6 16377.8 16445.7 16507.4 655348.0 673984.6 683258.0 687481.0 724860.0 53778.7 62379.7 63401.7 63425.5 63723.4 1334.4 1354.7 1392.5 1394.7 1395.3 29950.7 30978.1 30997.5 31029.5 31578.0 49754.5 54357.9 55208.7 55578.1 56436.0 504318.0 505309.0 506244.0 510174.0 510634.4 27743.9 28141.6 28165.9 28250.7 28492.0 49728.0 50351.0 50476.5 51175.0 51622.0 40613.6 41076.0 42184.6 42246.8 42866.4 40387.8 40679.4 40723.0 40876.8 42182.6 34310.8 39622.8 39796.4 39867.0 40140.6 35308.6 35707.3 35800.6 36484.5 36631.8 23447.9 26189.0 26289.1 26360.7 26382.5 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | [#2] jquery.html Cycles:180 Average:34217.31 Median:12888.74 stddev:95456.13 (740.6%) stddev-sans-first:95689.99 11:47:54 INFO - PROCESS | 1945 | Values: 328.5 331.6 379.9 390.5 412.1 17559.5 18352.8 20188.3 21161.6 21775.7 20474.3 20924.5 20973.2 21454.8 21670.8 5565.8 6024.8 6163.6 6200.9 6228.5 6188.8 6248.2 6251.1 6256.0 6262.3 5981.0 6091.6 6093.3 6102.1 6162.8 5881.7 5998.0 6127.3 6133.9 6150.8 5240.5 5259.4 5279.6 5282.5 5294.9 13655.9 23494.5 23844.7 24677.0 25794.9 2283.0 2360.1 2416.0 2457.3 2968.3 10432.9 11229.6 13351.2 15667.0 16104.8 1865.2 1932.2 1948.1 1966.0 1999.9 117909.8 123883.4 125501.5 125592.0 125861.4 11246.6 11356.5 11493.9 11597.8 11808.4 14528.8 14549.6 14555.4 14568.3 14598.6 8299.0 8552.4 8597.6 8703.9 8709.0 8528.3 8540.0 8594.7 8601.6 8718.0 4433.5 4490.5 4529.9 4592.6 4624.2 1648.8 1931.6 1975.2 1985.7 1994.1 846.6 912.5 925.6 940.6 999.3 21001.9 21393.0 21563.8 21577.0 21620.8 929.1 964.9 968.9 981.4 1000.4 10016.9 10880.5 11281.9 11325.0 11511.9 552432.0 553081.0 556973.0 580282.0 610926.0 109970.9 115306.0 115670.8 118359.4 121742.0 894.3 941.0 1022.9 1024.1 1028.4 14482.4 15198.5 15203.4 17273.7 19983.5 51564.0 52461.0 54218.5 55411.1 55800.4 76047.0 76410.0 80242.8 81075.0 86457.1 6521.7 7045.3 7180.0 7900.3 8291.3 12763.9 13385.7 13531.3 13780.0 13814.9 11680.6 11827.1 11923.7 11962.5 11965.3 10293.4 10556.5 11537.7 11643.9 11795.4 10165.0 11457.9 12063.8 12130.4 12184.6 7340.2 7491.5 7555.4 7562.9 7628.1 11281.2 11707.7 11826.7 11881.2 11915.9 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | [#3] mootools.html Cycles:180 Average:5721.10 Median:4891.24 stddev:3200.98 (65.4%) stddev-sans-first:3194.35 11:47:54 INFO - PROCESS | 1945 | Values: 1514.9 1555.8 1764.9 1854.2 1884.5 7983.0 8460.9 8644.0 9029.2 9053.2 8638.7 8812.6 8852.6 8852.9 8885.2 2375.8 2426.2 2428.2 2430.9 2432.2 2067.6 2372.0 2397.3 2432.2 2451.6 2166.8 2384.0 2422.6 2433.8 2442.0 2389.0 2407.5 2420.4 2421.2 2429.4 2386.6 2392.5 2404.4 2409.3 2412.8 8011.4 8877.6 8978.1 9019.4 9043.1 5288.7 5354.4 5423.0 5428.8 5464.1 3578.6 3704.2 3720.6 3760.9 3797.2 1122.9 1145.5 1146.9 1158.7 1795.1 11376.2 11495.1 11655.7 11662.2 11694.4 4362.0 4635.7 4686.4 4699.5 4757.8 9050.5 9119.9 9130.8 9139.7 9179.3 5216.9 5261.5 5263.6 5265.4 5268.2 4400.1 4774.0 4888.4 4894.1 4933.4 3381.8 3507.5 3687.6 3752.2 3767.2 1009.7 1141.2 1193.5 1200.8 1222.0 896.8 913.0 917.6 951.5 956.4 5686.6 5932.6 5958.6 6125.3 6180.2 440.2 443.2 443.6 458.3 458.6 3970.2 3998.4 4074.5 4077.2 4102.0 10164.5 10879.7 10933.7 10936.3 10970.4 10216.1 10857.8 10893.8 10905.8 10905.9 7914.5 8305.0 8326.4 8331.4 8398.6 5955.6 6081.1 6120.2 6129.8 6174.7 9534.8 9906.2 10179.1 10182.3 10219.6 10152.0 10215.8 10226.4 10271.8 10284.5 5475.9 6930.8 7508.8 7534.4 7684.5 8415.0 8508.5 8516.0 8529.0 8562.1 7422.8 7448.2 7604.9 7684.1 7727.0 7388.3 7526.8 7534.3 7660.3 7710.9 7019.5 7162.9 7185.6 7192.1 7200.7 4985.9 5069.3 5124.9 5155.8 5160.2 5001.4 5033.6 5145.5 5177.2 5235.9 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | [#4] prototype.html Cycles:180 Average:4687.91 Median:5318.07 stddev:1534.73 (28.9%) stddev-sans-first:1503.39 11:47:54 INFO - PROCESS | 1945 | Values: 306.5 309.7 315.6 320.0 321.2 4397.4 4698.5 5439.6 5443.1 5612.7 4403.4 5056.8 5519.2 5533.0 5557.7 3548.7 3750.2 3893.4 4106.1 4257.0 3948.7 4217.7 4257.0 4279.3 4288.7 3487.6 3683.5 3847.6 4038.4 4191.7 3275.3 3297.8 3451.1 3626.3 3912.8 3052.4 3339.8 3645.2 3646.4 3656.7 5256.0 5445.3 5483.0 5483.8 5547.6 3687.6 4431.4 4629.6 4763.7 4776.7 4905.5 5857.4 5873.7 5908.5 5924.5 3332.9 3451.7 3489.3 3750.3 3782.0 5732.8 6190.9 6284.7 6299.7 6352.6 4038.5 4050.9 4051.6 4068.8 4089.4 3761.1 3762.8 3905.8 4018.6 4038.5 4532.6 4762.5 4795.4 4816.7 4904.3 4862.0 5943.8 5962.6 5978.8 6002.8 1309.9 1348.3 1349.3 1364.8 1371.7 819.7 867.3 883.0 890.8 923.6 5309.2 5390.9 5414.9 5425.5 5435.2 5326.9 5952.3 5955.0 5967.9 6011.2 4491.7 5121.6 5226.2 5398.7 5425.7 2525.9 2571.9 2624.9 2659.6 2679.5 5160.9 5183.6 5194.2 5208.5 5222.4 4608.4 5216.5 5835.3 6368.6 6397.9 6107.3 6262.4 6313.7 6441.8 6459.6 5861.2 6190.9 6195.6 6239.5 6278.8 5603.4 6041.6 6107.9 6110.4 6160.9 5456.0 6385.4 6402.9 6443.5 6467.8 3476.0 3666.3 3822.8 4019.8 4037.6 5184.4 5575.1 5834.7 6172.7 6177.0 4933.9 5793.4 5918.6 5919.8 5946.2 5924.9 5932.9 5954.2 5962.6 6020.3 5600.9 5615.2 5765.4 5806.9 5834.5 5173.0 5913.6 5953.4 5962.0 5999.5 5603.6 5827.2 5849.0 5860.2 5893.9 11:47:54 INFO - PROCESS | 1945 | 11:47:54 INFO - PROCESS | 1945 | [#5] yui.html Cycles:180 Average:24994.42 Median:3602.48 stddev:40112.95 (1113.5%) stddev-sans-first:40183.86 11:47:54 INFO - PROCESS | 1945 | Values: 657.3 753.5 765.7 799.2 848.8 14911.2 15220.3 15464.2 15671.9 15746.2 12598.6 13652.5 13799.0 14693.8 14802.5 859.8 873.9 875.0 875.9 877.3 699.9 862.2 882.6 890.8 897.9 749.3 918.0 919.6 920.5 923.9 909.8 916.9 920.8 923.5 923.5 674.2 691.3 692.1 692.4 693.7 11964.2 14217.7 14221.1 14395.9 14435.6 14550.0 14667.7 14729.9 14980.9 15019.5 12161.3 12201.7 12410.1 12448.4 12608.1 3862.7 3873.9 4063.6 4121.1 4123.6 113970.2 114360.0 114533.0 115808.4 120798.0 10780.9 11136.6 11163.5 11167.6 11242.7 19458.8 22833.3 22918.0 22961.2 23008.5 8212.8 8311.2 8376.0 8490.3 8596.5 4553.8 4611.3 4620.6 4622.0 4635.0 3553.7 3651.3 3686.6 3694.2 3705.9 663.2 669.2 683.5 686.2 692.8 639.7 669.5 678.9 699.3 699.8 8799.3 8880.8 9375.3 9449.0 9491.6 560.2 594.7 614.2 631.7 671.3 4401.0 4580.5 4662.8 4680.2 4718.0 93399.1 156016.2 158273.1 161687.0 161940.0 121171.0 134819.0 140570.0 140690.0 143490.0 96800.5 102415.5 105105.0 105163.0 105691.5 8786.3 8830.6 8832.6 8865.0 9017.3 58325.7 58871.4 62778.0 66470.7 66727.7 70505.6 83235.0 94842.6 100054.0 101779.5 11470.1 11619.8 11628.2 11631.7 13671.5 14722.5 14844.0 14867.4 15196.4 15345.5 13797.6 13823.9 14021.7 14079.9 14113.0 13815.6 13859.4 13906.2 14183.3 14201.9 13499.9 13580.4 13690.0 14094.9 14229.8 11159.2 11289.2 11359.1 11481.3 11701.7 11255.3 11565.5 11708.5 11721.4 11845.2 11:47:54 INFO - PROCESS | 1945 | -------- Summary: end -------- 11:47:54 INFO - PROCESS | 1945 | 11:47:55 INFO - TEST-INFO | 1945: exit 0 11:47:55 INFO - TEST-OK | dromaeo_css | took 1113287ms 11:47:55 INFO - TEST-START | kraken 11:47:55 INFO - operating with platform_type : mac_ 11:47:55 INFO - Initialising browser for kraken test... 11:47:55 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp5yuYAn/profile http://localhost:51681/getInfo.html) 11:47:58 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 11:47:58 INFO - PROCESS | 1951 | colorDepth:24 11:47:58 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 11:47:58 INFO - PROCESS | 1951 | __metrics 11:47:58 ERROR - PROCESS | 1951 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 11:47:59 INFO - TEST-INFO | 1951: exit 0 11:47:59 INFO - Browser initialized. 11:47:59 INFO - Running cycle 1/1 for kraken test... 11:47:59 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/T/tmp5yuYAn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:11 INFO - PROCESS | 1953 | RSS: Main: 242982912 11:48:11 INFO - PROCESS | 1953 | 11:48:13 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-beat-detection) 11:48:14 INFO - PROCESS | 1953 | RSS: Main: 255926272 11:48:14 INFO - PROCESS | 1953 | 11:48:17 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-dft) 11:48:18 INFO - PROCESS | 1953 | RSS: Main: 264691712 11:48:18 INFO - PROCESS | 1953 | 11:48:21 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-fft) 11:48:22 INFO - PROCESS | 1953 | RSS: Main: 263262208 11:48:22 INFO - PROCESS | 1953 | 11:48:24 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51681/tests/kraken/driver.html?testName=audio-oscillator) 11:48:24 INFO - PROCESS | 1953 | RSS: Main: 264761344 11:48:24 INFO - PROCESS | 1953 | 11:48:26 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51681/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:27 INFO - PROCESS | 1953 | RSS: Main: 266334208 11:48:27 INFO - PROCESS | 1953 | 11:48:30 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51681/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:30 INFO - PROCESS | 1953 | RSS: Main: 266428416 11:48:30 INFO - PROCESS | 1953 | 11:48:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51681/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:34 INFO - PROCESS | 1953 | RSS: Main: 266637312 11:48:34 INFO - PROCESS | 1953 | 11:48:37 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51681/tests/kraken/driver.html?testName=json-parse-financial) 11:48:38 INFO - PROCESS | 1953 | RSS: Main: 266993664 11:48:38 INFO - PROCESS | 1953 | 11:48:39 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51681/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:48:40 INFO - PROCESS | 1953 | RSS: Main: 266371072 11:48:40 INFO - PROCESS | 1953 | 11:48:42 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:48:42 INFO - PROCESS | 1953 | RSS: Main: 266534912 11:48:42 INFO - PROCESS | 1953 | 11:48:45 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:48:45 INFO - PROCESS | 1953 | RSS: Main: 266829824 11:48:45 INFO - PROCESS | 1953 | 11:48:48 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:48:49 INFO - PROCESS | 1953 | RSS: Main: 267137024 11:48:49 INFO - PROCESS | 1953 | 11:48:52 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:48:53 INFO - PROCESS | 1953 | RSS: Main: 267153408 11:48:53 INFO - PROCESS | 1953 | 11:48:54 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:51681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51681/tests/kraken/driver.html?testName=ai-astar) 11:48:55 INFO - PROCESS | 1953 | RSS: Main: 269144064 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | __start_tp_report 11:48:55 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 11:48:55 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 11:48:55 INFO - PROCESS | 1953 | |i|pagename|runs| 11:48:55 INFO - PROCESS | 1953 | |0;ai-astar;123;159;120;113;117;101;117;116;116;117 11:48:55 INFO - PROCESS | 1953 | |1;audio-beat-detection;143;93;124;192;137;135;142;140;172;109 11:48:55 INFO - PROCESS | 1953 | |2;audio-dft;195;198;202;213;206;270;271;201;196;200 11:48:55 INFO - PROCESS | 1953 | |3;audio-fft;68;83;75;147;58;60;58;80;159;58 11:48:55 INFO - PROCESS | 1953 | |4;audio-oscillator;70;82;115;70;67;70;120;87;102;112 11:48:55 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;90;86;87;86;90;85;89;86;121;85 11:48:55 INFO - PROCESS | 1953 | |6;imaging-darkroom;95;143;126;151;135;148;133;151;132;94 11:48:55 INFO - PROCESS | 1953 | |7;imaging-desaturate;107;144;121;91;91;91;94;90;93;90 11:48:55 INFO - PROCESS | 1953 | |8;json-parse-financial;58;59;58;57;57;58;58;58;58;58 11:48:55 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;66;67;77;69;77;77;73;68;69;68 11:48:55 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;88;89;80;88;108;87;85;87;93;87 11:48:55 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;140;143;150;149;130;160;152;160;223;151 11:48:55 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;169;206;195;169;174;174;173;172;170;168 11:48:55 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;63;92;87;86;77;65;69;69;70;67 11:48:55 INFO - PROCESS | 1953 | __end_tp_report 11:48:55 INFO - PROCESS | 1953 | __start_cc_report 11:48:55 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,961 11:48:55 INFO - PROCESS | 1953 | __end_cc_report 11:48:55 INFO - PROCESS | 1953 | __startTimestamp1472150935064__endTimestamp 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | ------- Summary: start ------- 11:48:55 INFO - PROCESS | 1953 | Number of tests: 14 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:119.90 Median:117.00 stddev:14.90 (12.7%) stddev-sans-first:15.76 11:48:55 INFO - PROCESS | 1953 | Values: 123.0 159.0 120.0 113.0 117.0 101.0 117.0 116.0 116.0 117.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:142.50 stddev:28.25 (19.8%) stddev-sans-first:29.92 11:48:55 INFO - PROCESS | 1953 | Values: 143.0 93.0 124.0 192.0 137.0 135.0 142.0 140.0 172.0 109.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:215.20 Median:204.00 stddev:29.60 (14.5%) stddev-sans-first:30.48 11:48:55 INFO - PROCESS | 1953 | Values: 195.0 198.0 202.0 213.0 206.0 270.0 271.0 201.0 196.0 200.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:84.60 Median:64.00 stddev:37.34 (58.3%) stddev-sans-first:39.12 11:48:55 INFO - PROCESS | 1953 | Values: 68.0 83.0 75.0 147.0 58.0 60.0 58.0 80.0 159.0 58.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:70.00 stddev:20.96 (29.9%) stddev-sans-first:21.01 11:48:55 INFO - PROCESS | 1953 | Values: 70.0 82.0 115.0 70.0 67.0 70.0 120.0 87.0 102.0 112.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:86.50 stddev:10.89 (12.6%) stddev-sans-first:11.54 11:48:55 INFO - PROCESS | 1953 | Values: 90.0 86.0 87.0 86.0 90.0 85.0 89.0 86.0 121.0 85.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:130.80 Median:149.50 stddev:20.93 (14.0%) stddev-sans-first:17.75 11:48:55 INFO - PROCESS | 1953 | Values: 95.0 143.0 126.0 151.0 135.0 148.0 133.0 151.0 132.0 94.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:101.20 Median:91.00 stddev:18.07 (19.9%) stddev-sans-first:19.05 11:48:55 INFO - PROCESS | 1953 | Values: 107.0 144.0 121.0 91.0 91.0 91.0 94.0 90.0 93.0 90.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 11:48:55 INFO - PROCESS | 1953 | Values: 58.0 59.0 58.0 57.0 57.0 58.0 58.0 58.0 58.0 58.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:4.46 (6.3%) stddev-sans-first:4.33 11:48:55 INFO - PROCESS | 1953 | Values: 66.0 67.0 77.0 69.0 77.0 77.0 73.0 68.0 69.0 68.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:87.50 stddev:7.36 (8.4%) stddev-sans-first:7.79 11:48:55 INFO - PROCESS | 1953 | Values: 88.0 89.0 80.0 88.0 108.0 87.0 85.0 87.0 93.0 87.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:155.80 Median:151.50 stddev:25.27 (16.7%) stddev-sans-first:26.15 11:48:55 INFO - PROCESS | 1953 | Values: 140.0 143.0 150.0 149.0 130.0 160.0 152.0 160.0 223.0 151.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:173.50 stddev:12.83 (7.4%) stddev-sans-first:13.28 11:48:55 INFO - PROCESS | 1953 | Values: 169.0 206.0 195.0 169.0 174.0 174.0 173.0 172.0 170.0 168.0 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:73.50 stddev:10.33 (14.1%) stddev-sans-first:10.08 11:48:55 INFO - PROCESS | 1953 | Values: 63.0 92.0 87.0 86.0 77.0 65.0 69.0 69.0 70.0 67.0 11:48:55 INFO - PROCESS | 1953 | -------- Summary: end -------- 11:48:55 INFO - PROCESS | 1953 | 11:48:55 INFO - TEST-INFO | 1953: exit 0 11:48:55 INFO - TEST-OK | kraken | took 60357ms 11:48:55 INFO - SUITE-END | took 1173s 11:48:56 INFO - Completed test suite (00:19:34) 11:48:56 INFO - javascript benchmark 11:48:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7440.330117761828, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2868.3976364963355, 3403.8978830635087, 3418.675225853545, 3433.107392078059, 3460.515527826103, 5628.0, 5790.942090579093, 5818.505427038702, 5911.083782013784, 5926.436988486098, 5740.339860915996, 5806.158107074482, 5815.040518314473, 5843.152742852284, 5870.794522191724, 4025.9798701006453, 5124.846909445382, 5209.531142197202, 5294.867628309285, 5383.273258110164, 5373.623846330758, 5393.406725260218, 5411.512963833255, 5443.346798384179, 5457.454254574531, 5203.739813009334, 5221.869453263699, 5304.814331498417, 5324.973375133119, 5343.957928203982, 5301.549368303698, 5310.60170487215, 5313.256144139824, 5340.973295133538, 5353.1434970404935, 5269.077911365495, 5277.129273669878, 5302.946970530284, 5365.651232669903, 5380.865478363033, 5560.471755183251, 5765.942340576625, 5831.825045248607, 5877.382874798127, 5933.3473317934995, 6131.47882429994, 6168.907466388007, 6214.223222097238, 6219.471344935686, 6223.190985171898, 6215.502759779207, 6216.751329946796, 6225.657588832612, 6228.657423841687, 6241.313455519889, 4830.2030165022725, 5812.3025236971835, 5870.941290587125, 5882.49998750111, 5945.910811337833, 6297.149884765589, 6298.1812364392335, 6299.0, 6300.558960872695, 6310.873782524415, 6172.969135154385, 6183.84540386485, 6244.125822384779, 6269.780557680459, 6279.96860015697, 6128.442013630915, 6143.815685529441, 6167.229096362955, 6220.7511699532415, 6256.343083976117, 5924.437178468039, 6221.96079995207, 6236.376362363727, 6254.499640028786, 6268.937310626927, 6215.378462153839, 6250.249970003538, 6263.217097862767, 6283.403076707706, 6285.120083188357, 4893.437254715754, 4956.256561515802, 4964.702117872939, 4994.600431965434, 5007.524285192973, 4459.554044595579, 4483.887902802456, 4496.460424749051, 4524.298733696282, 4546.272596384562, 5761.222234998221, 5826.038703613856, 5854.268216472941, 5858.648481091148, 5884.941150588525, 6131.315499378962, 6185.0, 6188.44304012641, 6229.563930524911, 6230.7819226326865, 5803.216565763652, 5808.215890854765, 5816.156657284755, 5836.299644042742, 5860.501857342088, 5432.429594892558, 5434.728263586884, 5437.537809286255, 5444.482774136451, 5450.645708028966, 6189.04069869171, 6249.281332646804, 6267.373262673696, 6291.905621415683, 6292.8426789328805, 6260.311365749855, 6268.686565671789, 6275.52933529978, 6286.874262514815, 6290.937090629035, 5659.349719431027, 5962.658401859547, 6345.4923606112325, 6378.2983871774995, 6394.968025159845, 6097.512199024156, 6163.3836616338, 6181.53638477125, 6195.597286176384, 6241.0, 6253.155823963707, 6255.56211065221, 6255.56211065221, 6278.372162783685, 6288.811335659938, 6109.6639684817765, 6259.092431597484, 6262.2172228471445, 6274.1843560336865, 6275.843103922256, 5119.436861945109, 5998.940010600013, 6048.60684055535, 6104.389561044038, 6107.144999699956, 5878.265216847894, 5900.675462849585, 6005.36943620931, 6021.0, 6026.517878569792, 4995.900081998267, 6111.960966635772, 6159.19930409044, 6166.845828854314, 6176.9073463897175, 5870.002099642986, 5965.463108320272, 6000.669963151894, 6009.909851352146, 6041.335453100068], "value": 5722.554172372286, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.04007086005134, 541.6181591977656, 542.7286356821588, 555.5472290083579, 560.4759549820915, 32051.839740801293, 36194.276114477696, 42406.0, 42551.0, 42616.786916065415, 43465.0, 43745.562544374545, 44013.11973760531, 44141.7792911035, 44144.779276103574, 9623.759406014835, 9811.0, 9822.607095716163, 9861.506924653775, 9880.209583233323, 8333.541655208992, 9732.65935692251, 9738.07488288615, 9767.609295628166, 9787.804243915118, 9663.0, 9721.416714997124, 9749.830020397561, 9753.756156096084, 9761.365511241782, 9706.61173553057, 9759.0, 9764.902350976507, 9768.0, 9786.41281523111, 9694.545818127266, 9771.267154963336, 9795.461249631267, 9800.509974501247, 9803.803923921481, 41284.38073428901, 41485.1702965939, 41664.58335416668, 41706.0, 41811.581884181374, 3410.8635654573786, 3538.3630946429635, 3690.538682664667, 3990.4213888986224, 4208.494980602347, 30804.537931931038, 32363.352732945445, 32569.860054897967, 32632.510512342335, 32744.508832367534, 3090.428270769915, 3333.683300086488, 3346.832658367072, 3385.086026772782, 3399.388110140174, 361746.1912690446, 363272.0, 367336.1633191844, 367428.0, 370417.0, 62360.68819655872, 63019.0, 63038.68480657567, 63064.68467657724, 63100.0, 37310.44034339487, 38074.0, 65930.0, 66406.0, 66461.6676916622, 15494.690106197813, 16937.153142342835, 16939.407120750715, 16939.830601693826, 16978.91510542464, 12938.482460701467, 13036.826685598153, 13073.477060917647, 13084.214947103204, 13120.934395327962, 17652.11739413024, 19711.80288197128, 19835.0, 19867.701984470244, 19983.400497984792, 6756.662166891636, 7619.123800762873, 8025.9598702006115, 8126.796830079177, 8151.714689985941, 766.306492624176, 893.7274131389988, 1022.9130523905402, 1053.2311412668641, 1094.7044298039484, 30856.691433085383, 31394.21514462157, 31421.685783142333, 31620.525692114636, 31647.367052658818, 1052.736815796051, 1080.7622323088908, 1116.6315116011697, 1124.0, 1152.8962393384636, 15648.765268520981, 16377.59056023585, 16377.83622163787, 16445.67108657844, 16507.422240221535, 655348.0, 673984.6300768465, 683258.0, 687481.0, 724860.0, 53778.73110634422, 62379.6881015592, 63401.68299158567, 63425.511596861696, 63723.36276637267, 1334.4462048250061, 1354.7425989062046, 1392.540461647654, 1394.6861956060009, 1395.3372148229712, 29950.70049299479, 30978.0706578803, 30997.53503697401, 31029.534556981213, 31578.0, 49754.50245497499, 54357.91284174215, 55208.72395637996, 55578.109532332346, 56436.0, 504318.0, 505309.0, 506244.0, 510174.0, 510634.44682776346, 27743.890244389997, 28141.577876331463, 28165.873365065167, 28250.71749282481, 28492.002779902603, 49728.0, 50351.0, 50476.49523504718, 51174.976500469034, 51622.0, 40613.59386406098, 41076.0, 42184.57815421929, 42246.78876605597, 42866.35700464558, 40387.798061009504, 40679.38980915229, 40722.9819254521, 40876.79561602292, 42182.578174217866, 34310.82844585761, 39622.80188599038, 39796.40305395363, 39867.0, 40140.59859401369, 35308.64691353054, 35707.28585428329, 35800.64199358077, 36484.45273320955, 36631.81684091563, 23447.882760586086, 26189.0, 26289.07988220403, 26360.736392636594, 26382.472350553264], "value": 20354.98610228419, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.46132342957554, 331.55240425425677, 379.9107209805695, 390.4904100149304, 412.05227975656004, 17559.47321580353, 18352.81647183528, 20188.29340973066, 21161.57676846462, 21775.6733648995, 20474.283400081, 20924.476888077766, 20973.16107355708, 21454.785452145476, 21670.783292167074, 5565.775529383529, 6024.819255422344, 6163.599366041215, 6200.875982480347, 6228.470580000705, 6188.752449901998, 6248.156498872662, 6251.062340648894, 6255.999040153576, 6262.2798378186635, 5981.0, 6091.604045737013, 6093.299270583895, 6102.0541816018585, 6162.84592885177, 5881.735321910482, 5997.962729941899, 6127.295361033472, 6133.938660613381, 6150.784722534712, 5240.502152295526, 5259.3951695555115, 5279.5512381447625, 5282.498162674539, 5294.867628309285, 13655.931720341434, 23494.530109397718, 23844.688542130178, 24677.0, 25794.871025644938, 2282.9553244121157, 2360.055977608953, 2416.0, 2457.262821153665, 2968.3172870239964, 10432.895671043345, 11229.55081796727, 13351.198928064347, 15666.981646193075, 16104.75842862358, 1865.1699993502896, 1932.1595106128739, 1948.0584218134632, 1965.9508512287164, 1999.9000049997587, 117909.82090179074, 123883.38058309742, 125501.489970201, 125592.0, 125861.37069314686, 11246.550137994389, 11356.48895799691, 11493.885061149449, 11597.826032609517, 11808.350540720172, 14528.820676898338, 14549.636259093608, 14555.417783288762, 14568.271586420637, 14598.635034124232, 8298.958505207434, 8552.358573107042, 8597.613107410181, 8703.91296087031, 8708.998465176459, 8528.317734581218, 8540.041108738591, 8594.699185528476, 8601.61292741828, 8717.956410217908, 4433.512313645497, 4490.483594386622, 4529.932051019237, 4592.609628181575, 4624.213883639789, 1648.8433598808094, 1931.6329897319465, 1975.2099160335692, 1985.7219989201524, 1994.1425187169366, 846.627483907079, 912.539167720297, 925.6019911438146, 940.6378544260509, 999.2954966748425, 21001.894990525256, 21393.0, 21563.78436215649, 21577.02903369352, 21620.783792161877, 929.0894922975522, 964.8552717092352, 968.8885778135464, 981.4160574458186, 1000.3597697473623, 10016.899831001743, 10880.510377033053, 11281.943590281997, 11325.0, 11511.942440287912, 552432.0, 553081.0, 556973.0, 580282.0, 610926.0, 109970.91774017843, 115306.0, 115670.8432915677, 118359.40820295844, 121741.96286681597, 894.2711689972651, 940.9576569054409, 1022.8619136416637, 1024.1243736605095, 1028.3675539543126, 14482.420703171754, 15198.46805361807, 15203.391864325304, 17273.740893886352, 19983.500412489804, 51564.0, 52461.0, 54218.45781542292, 55411.060612878355, 55800.441995579524, 76047.0, 76410.0, 80242.79635805351, 81075.0, 86457.1354286449, 6521.739130434919, 7045.330693584101, 7180.0, 7900.288973992138, 8291.295239904435, 12763.936180319039, 13385.732285354434, 13531.323433828466, 13780.0, 13814.930925345308, 11680.649580512598, 11827.053835693296, 11923.701907452383, 11962.521499140285, 11965.282083075042, 10293.43386113741, 10556.472176390997, 11537.653870383901, 11643.941780291045, 11795.410229488663, 10165.0, 11457.854214578809, 12063.819042714542, 12130.393480326125, 12184.57353992606, 7340.192578816219, 7491.513051651626, 7555.357794587521, 7562.882392929989, 7628.084629844454, 11281.21031527785, 11707.707307317385, 11826.704332391759, 11881.168318217642, 11915.940420297842], "value": 9656.640346961298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.8863835212358, 1555.7510798272276, 1764.9470515884527, 1854.1563588567203, 1884.5100273928783, 7983.042034955799, 8460.873086903694, 8643.956780216091, 9029.187373136416, 9053.230475409599, 8638.697645582406, 8812.647494100243, 8852.5573721314, 8852.867206991885, 8885.2447541959, 2375.8455700379463, 2426.2236084452984, 2428.174420696963, 2430.8663023533786, 2432.19737486629, 2067.648499755045, 2372.019363831203, 2397.29279862441, 2432.160904487958, 2451.6077427611585, 2166.764943981931, 2383.9761602384015, 2422.563938491071, 2433.75662433757, 2441.9987804999955, 2388.952220955571, 2407.518496300738, 2420.419099416145, 2421.2494126820743, 2429.441228517442, 2386.6300723387903, 2392.509535545209, 2404.446977195255, 2409.265174121892, 2412.819038572114, 8011.439199256054, 8877.556122193864, 8978.147076027766, 9019.413738107001, 9043.050479699603, 5288.709120998344, 5354.41101478837, 5422.972885135549, 5428.75570599324, 5464.0711079116645, 3578.58846232684, 3704.1665625234377, 3720.6279372062577, 3760.8683696070507, 3797.240551889633, 1122.859642544682, 1145.5360578965533, 1146.8681101673249, 1158.652404278713, 1795.1203910084096, 11376.203665743402, 11495.080393568578, 11655.650330490098, 11662.183647144704, 11694.41527923609, 4361.978190109029, 4635.675502714845, 4686.390769199982, 4699.483056863743, 4757.833475828329, 9050.502222377701, 9119.908800912039, 9130.771730706652, 9139.680111196076, 9179.265658747285, 5216.869578260574, 5261.500157485033, 5263.578913686896, 5265.420803711589, 5268.2361057647195, 4400.141972315432, 4773.95226047742, 4888.364512613338, 4894.119058569491, 4933.383327084114, 3381.8332675227007, 3507.5440192774777, 3687.575928768226, 3752.249550089993, 3767.208886133888, 1009.7223263602422, 1141.1612464838338, 1193.462941676249, 1200.7958647029852, 1222.0468034932767, 896.7758060484879, 912.9908700913039, 917.5733284022828, 951.5402684061778, 956.4070276428658, 5686.60193786431, 5932.6058376281535, 5958.553108516879, 6125.295591007002, 6180.196574445384, 440.16368899091344, 443.18011678394714, 443.58967954641827, 458.26677316293666, 458.5597826086994, 3970.1662650843646, 3998.3802510610903, 4074.490688663917, 4077.2049450356894, 4101.958980410217, 10164.542595583216, 10879.673609791718, 10933.671989840317, 10936.343819370863, 10970.396628185526, 10216.08055275014, 10857.837132443019, 10893.782124357469, 10905.781884362268, 10905.890941090645, 7914.525128492309, 8305.0, 8326.375521835887, 8331.416800824005, 8398.580070996426, 5955.583109182489, 6081.087836824512, 6120.173776540289, 6129.785457509144, 6174.722137503913, 9534.761630959281, 9906.157976572073, 10179.134773544332, 10182.338148020355, 10219.642312519027, 10152.03555662211, 10215.846762298423, 10226.386416815016, 10271.845922311022, 10284.537195826355, 5475.863103422287, 6930.757423490154, 7508.849823003618, 7534.434917381109, 7684.500507467221, 8415.0, 8508.489490630582, 8515.998740295901, 8528.957355213184, 8562.143785621389, 7422.777316680508, 7448.217937116741, 7604.923950760422, 7684.077910650758, 7727.0, 7388.261173882569, 7526.811829704301, 7534.284242996907, 7660.3488703460835, 7710.92289077102, 7019.54372965776, 7162.928370716226, 7185.604791736505, 7192.065031545865, 7200.747973820891, 4985.925211121908, 5069.340516811393, 5124.871878203075, 5155.819546315861, 5160.225966105114, 5001.449840517613, 5033.597312215087, 5145.45972672864, 5177.197534382177, 5235.9056377447805], "value": 4539.481284004216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.5019343566704, 309.7166093024882, 315.6086452798529, 319.98240096794666, 321.2114259492945, 4397.44864065692, 4698.506656801038, 5439.564834813215, 5443.087947947849, 5612.747426365813, 4403.361512580674, 5056.8482945511605, 5519.199716041182, 5533.012173426355, 5557.694326812024, 3548.662877026691, 3750.1562148516637, 3893.442622950814, 4106.137711080662, 4256.97871510642, 3948.743331683446, 4217.746935183899, 4257.020485474254, 4279.293916503789, 4288.678349123828, 3487.6337984511642, 3683.502727131843, 3847.576766555677, 4038.434619153321, 4191.685623578229, 3275.2630658101734, 3297.7856439331485, 3451.1027132945364, 3626.274745050974, 3912.784796836173, 3052.3895220955674, 3339.782914110575, 3645.1798345372345, 3646.361886669822, 3656.670899619047, 5256.0, 5445.346558412976, 5483.0, 5483.7806487740445, 5547.611667183299, 3687.6128006559193, 4431.379606855043, 4629.629629629621, 4763.737994410306, 4776.73105274995, 4905.484924082955, 5857.384974577651, 5873.706314684291, 5908.497777688902, 5924.4667979882015, 3332.933461292363, 3451.6548345165534, 3489.284696637183, 3750.306193354238, 3781.959961010744, 5732.82801515951, 6190.938090619081, 6284.7486100555925, 6299.740051989575, 6352.555321127523, 4038.496132397873, 4050.886006348278, 4051.6150965658217, 4068.7997040873015, 4089.44792453021, 3761.0597350662333, 3762.7554208976876, 3905.7892053462947, 4018.598140185958, 4038.4749982502085, 4532.592066714012, 4762.452317983407, 4795.400574928134, 4816.662833601615, 4904.337914381513, 4862.0, 5943.821685349447, 5962.5826192167, 5978.790742323998, 6002.849928751816, 1309.888659463937, 1348.3325753751956, 1349.3455673998103, 1364.7884577890443, 1371.6845125621162, 819.7253271163398, 867.319154463743, 882.9911700883038, 890.8196639452751, 923.6443969071955, 5309.2036194570355, 5390.946090539122, 5414.945850541523, 5425.4697327731, 5435.239066530688, 5326.937011218565, 5952.285725712942, 5955.0, 5967.910481342783, 6011.158437818709, 4491.708038977456, 5121.61587880903, 5226.163813789775, 5398.703071331036, 5425.701586412785, 2525.89088111429, 2571.8683779137496, 2624.9106620752555, 2659.6276521287036, 2679.4775018871132, 5160.8967820643375, 5183.637145399785, 5194.169177798109, 5208.453112423217, 5222.3733152022005, 4608.354830323758, 5216.504432078947, 5835.328937172279, 6368.5860419072615, 6397.912354899679, 6107.297660769068, 6262.405071518198, 6313.684315784284, 6441.806745797634, 6459.580127291711, 5861.208736820645, 6190.938090619036, 6195.628262304276, 6239.532035097477, 6278.811635650938, 5603.439656034364, 6041.640630858022, 6107.8849786610035, 6110.388961103854, 6160.9383906160365, 5456.0, 6385.361463853763, 6402.93597064042, 6443.452306553995, 6467.805965821033, 3476.0440878758545, 3666.2850744104644, 3822.7706337619834, 4019.799010049427, 4037.596240375939, 5184.377874655065, 5575.080111781673, 5834.679092649775, 6172.722227499863, 6176.969115154396, 4933.9013219736125, 5793.449622285876, 5918.585699001029, 5919.763209471573, 5946.226990491208, 5924.851878703067, 5932.911006334994, 5954.183973888005, 5962.55280853929, 6020.307664618625, 5600.859978500407, 5615.185798059177, 5765.3946335634155, 5806.893312078018, 5834.474897259267, 5173.0, 5913.645181289136, 5953.404659534012, 5961.956657584992, 5999.460048595647, 5603.551715862803, 5827.24245848037, 5849.0, 5860.241927421671, 5893.882122357614], "value": 4196.057527402154, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.3426573426574, 753.5064532731056, 765.6975494679598, 799.1808396393695, 848.7692845374207, 14911.179885106314, 15220.315085821165, 15464.226788660564, 15671.921640391782, 15746.21268936553, 12598.559050433236, 13652.522161724342, 13799.034067615246, 14693.77959330611, 14802.481913133042, 859.7893516088567, 873.9038705742395, 874.9518776467291, 875.8729984152326, 877.271864352589, 699.9475039372068, 862.180928118287, 882.6248844241204, 890.8040231149137, 897.9102089791033, 749.3181205103357, 918.0360621347592, 919.641339877445, 920.484528663947, 923.9168474837263, 909.849874770662, 916.9083091690844, 920.8066306075665, 923.501309292981, 923.5059243304834, 674.2381109346456, 691.3086913086913, 692.0829900381964, 692.394155114275, 693.7086423702058, 11964.222325548812, 14217.715645687029, 14221.14673119616, 14395.928020359936, 14435.639109022253, 14550.0, 14667.706645867129, 14729.926350368287, 14980.850191498164, 15019.549413517612, 12161.331126788024, 12201.694957626041, 12410.069244806675, 12448.439820208021, 12608.085652593583, 3862.671672907806, 3873.8644147454984, 4063.573324800883, 4121.052158003676, 4123.60825721556, 113970.15074623119, 114360.0, 114533.0, 115808.42095789468, 120798.0, 10780.946095269474, 11136.554537818396, 11163.497642606102, 11167.553297867995, 11242.7189320266, 19458.80541194598, 22833.31500054968, 22917.968691408925, 22961.196358127385, 23008.53982920332, 8212.794680133044, 8311.16888311176, 8375.958120209481, 8490.320774338039, 8596.527191004554, 4553.795079221443, 4611.33135695329, 4620.561046700558, 4621.976890115528, 4634.95365046352, 3553.7157027437743, 3651.2514934438377, 3686.6129056448685, 3694.242680250542, 3705.870294539732, 663.2207156590986, 669.210331808461, 683.4942142814282, 686.2005763285816, 692.8025512728848, 639.6929473852576, 669.4811521071209, 678.9236334803528, 699.2692636195188, 699.7788858442051, 8799.252063574668, 8880.777980550409, 9375.343725939256, 9448.95275523618, 9491.620335186515, 560.1849309255024, 594.7472324262258, 614.213806327901, 631.66837410359, 671.318611609217, 4401.0, 4580.519045500241, 4662.813487460464, 4680.221515823965, 4717.952820471752, 93399.0660093404, 156016.21991890194, 158273.1295152845, 161687.0, 161940.0, 121171.0, 134819.0, 140570.0, 140690.0, 143490.0, 96800.51599741956, 102415.48792255991, 105105.0, 105163.0, 105691.4715426418, 8786.341024423065, 8830.558472076242, 8832.646694132163, 8865.0, 9017.2786177107, 58325.70837145787, 58871.41128588659, 62777.977761778035, 66470.66764666146, 66727.66636166789, 70505.6474717623, 83235.0, 94842.57736134101, 100054.0, 101779.49110254401, 11470.139739519402, 11619.825702614637, 11628.244164129304, 11631.709207269885, 13671.453141874612, 14722.542458315378, 14844.035137716013, 14867.40530378816, 15196.392144314104, 15345.539633811004, 13797.586072417844, 13823.930880345533, 14021.719565608426, 14079.859201408264, 14113.012089154072, 13815.585532434043, 13859.376328065462, 13906.235157066458, 14183.290835458392, 14201.857981420468, 13499.932500337436, 13580.388882500509, 13690.04169708111, 14094.859051409354, 14229.786553201504, 11159.163062770162, 11289.153313501356, 11359.148063895405, 11481.311121332747, 11701.707457313636, 11255.268407553483, 11565.479553420284, 11708.531658733555, 11721.355325457182, 11845.23006004607], "value": 7917.221484107522, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 159.0, 120.0, 113.0, 117.0, 101.0, 117.0, 116.0, 116.0, 117.0], "value": 119.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 93.0, 124.0, 192.0, 137.0, 135.0, 142.0, 140.0, 172.0, 109.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 202.0, 213.0, 206.0, 270.0, 271.0, 201.0, 196.0, 200.0], "value": 215.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 75.0, 147.0, 58.0, 60.0, 58.0, 80.0, 159.0, 58.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 115.0, 70.0, 67.0, 70.0, 120.0, 87.0, 102.0, 112.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 87.0, 86.0, 90.0, 85.0, 89.0, 86.0, 121.0, 85.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 143.0, 126.0, 151.0, 135.0, 148.0, 133.0, 151.0, 132.0, 94.0], "value": 130.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 144.0, 121.0, 91.0, 91.0, 91.0, 94.0, 90.0, 93.0, 90.0], "value": 101.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 57.0, 57.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 77.0, 69.0, 77.0, 77.0, 73.0, 68.0, 69.0, 68.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 80.0, 88.0, 108.0, 87.0, 85.0, 87.0, 93.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 143.0, 150.0, 149.0, 130.0, 160.0, 152.0, 160.0, 223.0, 151.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 206.0, 195.0, 169.0, 174.0, 174.0, 173.0, 172.0, 170.0, 168.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 92.0, 87.0, 86.0, 77.0, 65.0, 69.0, 69.0, 70.0, 67.0], "value": 74.5, "unit": "score"}]}]} 11:48:56 INFO - Return code: 0 11:48:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:48:56 ERROR - # TBPL SUCCESS # 11:48:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:48:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:48:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:48:56 INFO - 2016-08-25 11:48:56.370 screenresolution[1958:19009] starting screenresolution argv=screenresolution get 11:48:56 INFO - 2016-08-25 11:48:56.372 screenresolution[1958:19009] Display 0: 1600x1200x32@60 11:48:56 INFO - 2016-08-25 11:48:56.378 screenresolution[1959:19012] starting screenresolution argv=screenresolution list 11:48:56 INFO - Available Modes on Display 0 11:48:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:48:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:48:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:48:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:48:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:48:56 INFO - Intel Iris: 11:48:56 INFO - Chipset Model: Intel Iris 11:48:56 INFO - Type: GPU 11:48:56 INFO - Bus: Built-In 11:48:56 INFO - VRAM (Dynamic, Max): 1536 MB 11:48:56 INFO - Vendor: Intel (0x8086) 11:48:56 INFO - Device ID: 0x0a2e 11:48:56 INFO - Revision ID: 0x0009 11:48:56 INFO - Displays: 11:48:56 INFO - Display: 11:48:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:48:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:48:56 INFO - Display Serial Number: 1600x1200 60 11:48:56 INFO - Main Display: Yes 11:48:56 INFO - Mirror: Off 11:48:56 INFO - Online: Yes 11:48:56 INFO - Rotation: Supported 11:48:56 INFO - Return code: 0 11:48:56 INFO - Running post-action listener: _resource_record_post_action 11:48:56 INFO - [mozharness: 2016-08-25 18:48:56.614071Z] Finished run-tests step (success) 11:48:56 INFO - Running post-run listener: _resource_record_post_run 11:48:56 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 835584; Write bytes: 708641280; Read time: 8; Write time: 2073 11:48:56 INFO - TinderboxPrint: CPU usage
54.8% 11:48:56 INFO - TinderboxPrint: I/O read bytes / time
835,584 / 8 11:48:56 INFO - TinderboxPrint: I/O write bytes / time
708,641,280 / 2,073 11:48:56 INFO - TinderboxPrint: CPU idle
2,185.3 (45.2%) 11:48:56 INFO - TinderboxPrint: CPU system
986.1 (20.4%) 11:48:56 INFO - TinderboxPrint: CPU user
1,666.3 (34.4%) 11:48:56 INFO - TinderboxPrint: Swap in / out
406,224,896 / 0 11:48:56 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397109760; Write bytes: 392140800; Read time: 29994; Write time: 849 11:48:56 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 827392; Write bytes: 298686976; Read time: 7; Write time: 1176 11:48:57 INFO - Running post-run listener: _upload_blobber_files 11:48:57 INFO - Blob upload gear active. 11:48:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:48:57 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 11:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:48:57 INFO - (blobuploader) - INFO - Open directory for files ... 11:48:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:48:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:48:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:58 INFO - (blobuploader) - INFO - Done attempting. 11:48:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:48:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:48:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:59 INFO - (blobuploader) - INFO - Done attempting. 11:48:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:48:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:49:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:02 INFO - (blobuploader) - INFO - Done attempting. 11:49:02 INFO - (blobuploader) - INFO - Iteration through files over. 11:49:02 INFO - Return code: 0 11:49:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:49:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:49:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b73eb005d11a23837b6f04ae563cf3f79244d5af64c6895ee61f1a59beb3616d6d7522d00e701c1f74d0b9ed9df2873fc8756baaa0265da36dd0273acf01b5fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d85c70e9dbd5a043f6fd0fc19bcea357c169de02b3511fd685eb289f5848060f90a70b09f00d3a49bea3abfb75c7948a134f4ec7879446d871801864303d0377", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/368b5da53411cbc78fc8ae2603694f3ad93f4237e11b0820568b606428a9ca8bdbd3b02e34adf29bd110e0d57f71bb3d3b1ff9125b6d5be17bff7d9af9188f9a"} 11:49:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:49:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:49:02 INFO - Contents: 11:49:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b73eb005d11a23837b6f04ae563cf3f79244d5af64c6895ee61f1a59beb3616d6d7522d00e701c1f74d0b9ed9df2873fc8756baaa0265da36dd0273acf01b5fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d85c70e9dbd5a043f6fd0fc19bcea357c169de02b3511fd685eb289f5848060f90a70b09f00d3a49bea3abfb75c7948a134f4ec7879446d871801864303d0377", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/368b5da53411cbc78fc8ae2603694f3ad93f4237e11b0820568b606428a9ca8bdbd3b02e34adf29bd110e0d57f71bb3d3b1ff9125b6d5be17bff7d9af9188f9a"} 11:49:02 INFO - Running post-run listener: copy_logs_to_upload_dir 11:49:02 INFO - Copying logs to upload dir... 11:49:02 INFO - mkdir: /builds/slave/test/build/upload/logs 11:49:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.432745 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-25 11:49:02.135184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 11:49:02.139119) ========= 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.jki35UXnJu/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.4CXCNpijb5/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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/Autoland/sha512/b73eb005d11a23837b6f04ae563cf3f79244d5af64c6895ee61f1a59beb3616d6d7522d00e701c1f74d0b9ed9df2873fc8756baaa0265da36dd0273acf01b5fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d85c70e9dbd5a043f6fd0fc19bcea357c169de02b3511fd685eb289f5848060f90a70b09f00d3a49bea3abfb75c7948a134f4ec7879446d871801864303d0377", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/368b5da53411cbc78fc8ae2603694f3ad93f4237e11b0820568b606428a9ca8bdbd3b02e34adf29bd110e0d57f71bb3d3b1ff9125b6d5be17bff7d9af9188f9a"} build_url:https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/c0YDSD2wRUaiyHOCsH6H2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b73eb005d11a23837b6f04ae563cf3f79244d5af64c6895ee61f1a59beb3616d6d7522d00e701c1f74d0b9ed9df2873fc8756baaa0265da36dd0273acf01b5fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d85c70e9dbd5a043f6fd0fc19bcea357c169de02b3511fd685eb289f5848060f90a70b09f00d3a49bea3abfb75c7948a134f4ec7879446d871801864303d0377", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/368b5da53411cbc78fc8ae2603694f3ad93f4237e11b0820568b606428a9ca8bdbd3b02e34adf29bd110e0d57f71bb3d3b1ff9125b6d5be17bff7d9af9188f9a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 11:49:02.173707) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:49:02.174062) ========= 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.jki35UXnJu/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.4CXCNpijb5/Listeners TMPDIR=/var/folders/zz/4qkjslm927v9zkk_2tfqzwtm00000w/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.004508 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:49:02.223233) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 11:49:02.223576) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 11:49:02.223924) ========= ========= Total master_lag: 0.18 =========