builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0047 starttime: 1470956988.32 results: success (0) buildid: 20160811135022 builduid: f979b05a50f2425ab25770ea7b2ec8b9 revision: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.321002) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.321431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.321725) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.347865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.348251) ========= 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.InMsYG25wK/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.kbKgMxfOK6/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-11 16:09:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.35M=0.001s 2016-08-11 16:09:48 (8.35 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.479665) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.479988) ========= 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.InMsYG25wK/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.kbKgMxfOK6/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.021406 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:48.528072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 16:09:48.528439) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.InMsYG25wK/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.kbKgMxfOK6/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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-11 16:09:48,605 truncating revision to first 12 chars 2016-08-11 16:09:48,605 Setting DEBUG logging. 2016-08-11 16:09:48,605 attempt 1/10 2016-08-11 16:09:48,605 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/233ab21b64b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 16:09:49,711 unpacking tar archive at: mozilla-central-233ab21b64b5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384602 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 16:09:49.937959) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:49.938286) ========= script_repo_revision: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:49.938698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:49.938959) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 16:09:49.957136) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-08-11 16:09:49.957495) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.InMsYG25wK/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.kbKgMxfOK6/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:09:50 INFO - MultiFileLogger online at 20160811 16:09:50 in /builds/slave/test 16:09:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:09:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:50 INFO - {'append_to_log': False, 16:09:50 INFO - 'base_work_dir': '/builds/slave/test', 16:09:50 INFO - 'blob_upload_branch': 'Firefox', 16:09:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:50 INFO - 'branch': 'Firefox', 16:09:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:50 INFO - 'config_files': ('talos/mac_config.py',), 16:09:50 INFO - 'default_actions': ('clobber', 16:09:50 INFO - 'read-buildbot-config', 16:09:50 INFO - 'download-and-extract', 16:09:50 INFO - 'populate-webroot', 16:09:50 INFO - 'create-virtualenv', 16:09:50 INFO - 'install', 16:09:50 INFO - 'run-tests'), 16:09:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:50 INFO - 'download_minidump_stackwalk': True, 16:09:50 INFO - 'download_symbols': 'ondemand', 16:09:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:50 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:50 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:50 INFO - 'installer_path': 'installer.exe', 16:09:50 INFO - 'log_level': 'info', 16:09:50 INFO - 'log_name': 'talos', 16:09:50 INFO - 'log_to_console': True, 16:09:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:09:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:09:50 INFO - 'opt_config_files': (), 16:09:50 INFO - 'pip_index': False, 16:09:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:50 INFO - 'cmd': ('bash', 16:09:50 INFO - '-c', 16:09:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:50 INFO - 'enabled': True, 16:09:50 INFO - 'halt_on_failure': False, 16:09:50 INFO - 'name': 'check_screen_resolution'},), 16:09:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:50 INFO - 'cmd': ('bash', 16:09:50 INFO - '-c', 16:09:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:50 INFO - 'enabled': True, 16:09:50 INFO - 'halt_on_failure': False, 16:09:50 INFO - 'name': 'check_screen_resolution'},), 16:09:50 INFO - 'run_cmd_checks_enabled': True, 16:09:50 INFO - 'sps_profile': False, 16:09:50 INFO - 'sps_profile_interval': 0, 16:09:50 INFO - 'suite': 'dromaeojs-e10s', 16:09:50 INFO - 'system_bits': '32', 16:09:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:50 INFO - 'title': 't-yosemite-r7-0047', 16:09:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:50 INFO - 'use_talos_json': True, 16:09:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:50 INFO - 'work_dir': 'build'} 16:09:50 INFO - [mozharness: 2016-08-11 23:09:50.148340Z] Running clobber step. 16:09:50 INFO - Running pre-action listener: _resource_record_pre_action 16:09:50 INFO - Running main action method: clobber 16:09:50 INFO - rmtree: /builds/slave/test/build 16:09:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:50 INFO - Running post-action listener: _resource_record_post_action 16:09:50 INFO - [mozharness: 2016-08-11 23:09:50.371075Z] Finished clobber step (success) 16:09:50 INFO - [mozharness: 2016-08-11 23:09:50.371188Z] Running read-buildbot-config step. 16:09:50 INFO - Running pre-action listener: _resource_record_pre_action 16:09:50 INFO - Running main action method: read_buildbot_config 16:09:50 INFO - Using buildbot properties: 16:09:50 INFO - { 16:09:50 INFO - "project": "", 16:09:50 INFO - "product": "firefox", 16:09:50 INFO - "script_repo_revision": "production", 16:09:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:09:50 INFO - "repository": "", 16:09:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:09:50 INFO - "buildid": "20160811135022", 16:09:50 INFO - "pgo_build": "False", 16:09:50 INFO - "basedir": "/builds/slave/test", 16:09:50 INFO - "buildnumber": 38, 16:09:50 INFO - "slavename": "t-yosemite-r7-0047", 16:09:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:09:50 INFO - "platform": "yosemite_r7", 16:09:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:09:50 INFO - "branch": "mozilla-central", 16:09:50 INFO - "repo_path": "mozilla-central", 16:09:50 INFO - "revision": "233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4", 16:09:50 INFO - "stage_platform": "macosx64", 16:09:50 INFO - "builduid": "f979b05a50f2425ab25770ea7b2ec8b9", 16:09:50 INFO - "slavebuilddir": "test" 16:09:50 INFO - } 16:09:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:09:50 INFO - Running post-action listener: _resource_record_post_action 16:09:50 INFO - [mozharness: 2016-08-11 23:09:50.374282Z] Finished read-buildbot-config step (success) 16:09:50 INFO - [mozharness: 2016-08-11 23:09:50.374421Z] Running download-and-extract step. 16:09:50 INFO - Running pre-action listener: _resource_record_pre_action 16:09:50 INFO - Running main action method: download_and_extract 16:09:50 INFO - mkdir: /builds/slave/test/build/tests 16:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:50 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:09:50 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:09:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 16:09:51 INFO - Downloaded 1199 bytes. 16:09:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:09:51 INFO - Using the following test package requirements: 16:09:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:09:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:09:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:09:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'jsshell-mac.zip'], 16:09:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:09:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:09:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:09:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:09:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:09:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:09:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:09:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:09:51 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:09:51 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:09:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:09:52 INFO - Downloaded 16328348 bytes. 16:09:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:09:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:09:55 INFO - Return code: 0 16:09:55 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:09: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')]} 16:09:55 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:09:55 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:09:56 INFO - Downloaded 16328348 bytes. 16:09:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:09:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:10:00 INFO - Return code: 0 16:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:00 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:10:00 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:10:01 INFO - Downloaded 14413719 bytes. 16:10:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:10:02 INFO - Return code: 0 16:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:02 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:10:02 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:10:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 16:10:06 INFO - Downloaded 110920211 bytes. 16:10:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:10:06 INFO - mkdir: /builds/slave/test/properties 16:10:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:06 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:06 INFO - Contents: 16:10:06 INFO - build_url:https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:10:06 INFO - Running post-action listener: _resource_record_post_action 16:10:06 INFO - Running post-action listener: set_extra_try_arguments 16:10:06 INFO - [mozharness: 2016-08-11 23:10:06.933533Z] Finished download-and-extract step (success) 16:10:06 INFO - [mozharness: 2016-08-11 23:10:06.933695Z] Running populate-webroot step. 16:10:06 INFO - Running pre-action listener: _resource_record_pre_action 16:10:06 INFO - Running main action method: populate_webroot 16:10:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:06 INFO - '--tppagecycles', 16:10:06 INFO - '7'], 16:10:06 INFO - 'tests': ['tsvgm']}}, 16:10:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:10:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:10:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['damp', 'tps']}, 16:10:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'tests': ['damp', 'tps']}, 16:10:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['dromaeo_dom']}, 16:10:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['basic_compositor_video']}, 16:10:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:10:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['a11yr', 16:10:06 INFO - 'ts_paint', 16:10:06 INFO - 'tpaint', 16:10:06 INFO - 'sessionrestore', 16:10:06 INFO - 'sessionrestore_no_auto_restore', 16:10:06 INFO - 'tabpaint']}, 16:10:06 INFO - 'other-e10s': {'tests': ['a11yr', 16:10:06 INFO - 'ts_paint', 16:10:06 INFO - 'tpaint', 16:10:06 INFO - 'sessionrestore', 16:10:06 INFO - 'sessionrestore_no_auto_restore', 16:10:06 INFO - 'tabpaint']}, 16:10:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:10:06 INFO - 'ts_paint', 16:10:06 INFO - 'tpaint', 16:10:06 INFO - 'sessionrestore', 16:10:06 INFO - 'sessionrestore_no_auto_restore', 16:10:06 INFO - 'tabpaint']}, 16:10:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:10:06 INFO - 'ts_paint', 16:10:06 INFO - 'tpaint', 16:10:06 INFO - 'sessionrestore', 16:10:06 INFO - 'sessionrestore_no_auto_restore', 16:10:06 INFO - 'tabpaint']}, 16:10:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['a11yr', 16:10:06 INFO - 'ts_paint', 16:10:06 INFO - 'tpaint', 16:10:06 INFO - 'sessionrestore', 16:10:06 INFO - 'sessionrestore_no_auto_restore', 16:10:06 INFO - 'tabpaint']}, 16:10:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['a11yr', 16:10:06 INFO - 'ts_paint', 16:10:06 INFO - 'tpaint', 16:10:06 INFO - 'sessionrestore', 16:10:06 INFO - 'sessionrestore_no_auto_restore', 16:10:06 INFO - 'tabpaint']}, 16:10:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['tsvgx', 16:10:06 INFO - 'tsvgr_opacity', 16:10:06 INFO - 'tart', 16:10:06 INFO - 'tscrollx', 16:10:06 INFO - 'cart']}, 16:10:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:10:06 INFO - 'tsvgr_opacity', 16:10:06 INFO - 'tart', 16:10:06 INFO - 'tscrollx', 16:10:06 INFO - 'cart']}, 16:10:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'talos_options': ['--disable-e10s'], 16:10:06 INFO - 'tests': ['tp5o']}, 16:10:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'tests': ['tp5o']}, 16:10:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'talos_options': ['--disable-e10s', 16:10:06 INFO - '--xperf_path', 16:10:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:06 INFO - 'tests': ['tp5n']}, 16:10:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:06 INFO - 'talos_options': ['--xperf_path', 16:10:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:06 INFO - 'tests': ['tp5n']}}, 16:10:06 INFO - 'talos.zip': {'path': '', 16:10:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:06 INFO - Running post-action listener: _resource_record_post_action 16:10:06 INFO - [mozharness: 2016-08-11 23:10:06.952439Z] Finished populate-webroot step (success) 16:10:06 INFO - [mozharness: 2016-08-11 23:10:06.952545Z] Running create-virtualenv step. 16:10:06 INFO - Running pre-action listener: _resource_record_pre_action 16:10:06 INFO - Running main action method: create_virtualenv 16:10:06 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:10:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:07 INFO - Using real prefix '/tools/python27' 16:10:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:07 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:10 INFO - Installing pip.................done. 16:10:10 INFO - Return code: 0 16:10:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:10:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:10 INFO - 'HOME': '/Users/cltbld', 16:10:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:10 INFO - 'LOGNAME': 'cltbld', 16:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:10 INFO - 'MOZ_NO_REMOTE': '1', 16:10:10 INFO - 'NO_EM_RESTART': '1', 16:10:10 INFO - 'PAGER': '/bin/cat', 16:10:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:10 INFO - 'PWD': '/builds/slave/test', 16:10:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:10 INFO - 'SHELL': '/bin/bash', 16:10:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:10 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:10 INFO - 'USER': 'cltbld', 16:10:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:10 INFO - 'XPC_FLAGS': '0x0', 16:10:10 INFO - 'XPC_SERVICE_NAME': '0', 16:10:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:10:10 INFO - Cleaning up... 16:10:10 INFO - Return code: 0 16:10:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:10:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:10 INFO - 'HOME': '/Users/cltbld', 16:10:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:10 INFO - 'LOGNAME': 'cltbld', 16:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:10 INFO - 'MOZ_NO_REMOTE': '1', 16:10:10 INFO - 'NO_EM_RESTART': '1', 16:10:10 INFO - 'PAGER': '/bin/cat', 16:10:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:10 INFO - 'PWD': '/builds/slave/test', 16:10:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:10 INFO - 'SHELL': '/bin/bash', 16:10:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:10 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:10 INFO - 'USER': 'cltbld', 16:10:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:10 INFO - 'XPC_FLAGS': '0x0', 16:10:10 INFO - 'XPC_SERVICE_NAME': '0', 16:10:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:11 INFO - Downloading/unpacking psutil>=3.1.1 16:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:14 INFO - Installing collected packages: psutil 16:10:14 INFO - Running setup.py install for psutil 16:10:14 INFO - building 'psutil._psutil_osx' extension 16:10:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:10:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:10:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:10:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:10:14 INFO - building 'psutil._psutil_posix' extension 16:10:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:10:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:10:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:10:14 INFO - ^ 16:10:14 INFO - 1 warning generated. 16:10:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:10:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:14 INFO - Successfully installed psutil 16:10:14 INFO - Cleaning up... 16:10:15 INFO - Return code: 0 16:10:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:15 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:15 INFO - 'HOME': '/Users/cltbld', 16:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:15 INFO - 'LOGNAME': 'cltbld', 16:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:15 INFO - 'MOZ_NO_REMOTE': '1', 16:10:15 INFO - 'NO_EM_RESTART': '1', 16:10:15 INFO - 'PAGER': '/bin/cat', 16:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:15 INFO - 'PWD': '/builds/slave/test', 16:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:15 INFO - 'SHELL': '/bin/bash', 16:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:15 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:15 INFO - 'USER': 'cltbld', 16:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:15 INFO - 'XPC_FLAGS': '0x0', 16:10:15 INFO - 'XPC_SERVICE_NAME': '0', 16:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:10:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:10:17 INFO - Installing collected packages: mozsystemmonitor 16:10:17 INFO - Running setup.py install for mozsystemmonitor 16:10:18 INFO - Successfully installed mozsystemmonitor 16:10:18 INFO - Cleaning up... 16:10:18 INFO - Return code: 0 16:10:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:18 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:18 INFO - 'HOME': '/Users/cltbld', 16:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:18 INFO - 'LOGNAME': 'cltbld', 16:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:18 INFO - 'MOZ_NO_REMOTE': '1', 16:10:18 INFO - 'NO_EM_RESTART': '1', 16:10:18 INFO - 'PAGER': '/bin/cat', 16:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:18 INFO - 'PWD': '/builds/slave/test', 16:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:18 INFO - 'SHELL': '/bin/bash', 16:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:18 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:18 INFO - 'USER': 'cltbld', 16:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:18 INFO - 'XPC_FLAGS': '0x0', 16:10:18 INFO - 'XPC_SERVICE_NAME': '0', 16:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:18 INFO - Downloading/unpacking jsonschema==2.5.1 16:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:10:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:21 INFO - Downloading functools32-3.2.3-2.tar.gz 16:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:10:21 INFO - warning: no files found matching '*.txt' 16:10:21 INFO - no previously-included directories found matching 'build' 16:10:21 INFO - no previously-included directories found matching 'dist' 16:10:21 INFO - no previously-included directories found matching '.git*' 16:10:21 INFO - Installing collected packages: jsonschema, functools32 16:10:21 INFO - Running setup.py install for functools32 16:10:21 INFO - warning: no files found matching '*.txt' 16:10:21 INFO - no previously-included directories found matching 'build' 16:10:21 INFO - no previously-included directories found matching 'dist' 16:10:21 INFO - no previously-included directories found matching '.git*' 16:10:21 INFO - Successfully installed jsonschema functools32 16:10:21 INFO - Cleaning up... 16:10:21 INFO - Return code: 0 16:10:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:21 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:21 INFO - 'HOME': '/Users/cltbld', 16:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:21 INFO - 'LOGNAME': 'cltbld', 16:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:21 INFO - 'MOZ_NO_REMOTE': '1', 16:10:21 INFO - 'NO_EM_RESTART': '1', 16:10:21 INFO - 'PAGER': '/bin/cat', 16:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:21 INFO - 'PWD': '/builds/slave/test', 16:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:21 INFO - 'SHELL': '/bin/bash', 16:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:21 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:21 INFO - 'USER': 'cltbld', 16:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:21 INFO - 'XPC_FLAGS': '0x0', 16:10:21 INFO - 'XPC_SERVICE_NAME': '0', 16:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:10:21 INFO - Cleaning up... 16:10:21 INFO - Return code: 0 16:10:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:21 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:10:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:21 INFO - 'HOME': '/Users/cltbld', 16:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:21 INFO - 'LOGNAME': 'cltbld', 16:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:21 INFO - 'MOZ_NO_REMOTE': '1', 16:10:21 INFO - 'NO_EM_RESTART': '1', 16:10:21 INFO - 'PAGER': '/bin/cat', 16:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:21 INFO - 'PWD': '/builds/slave/test', 16:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:21 INFO - 'SHELL': '/bin/bash', 16:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:21 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:21 INFO - 'USER': 'cltbld', 16:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:21 INFO - 'XPC_FLAGS': '0x0', 16:10:21 INFO - 'XPC_SERVICE_NAME': '0', 16:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:22 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:24 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:10:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10:25 INFO - Downloading docopt-0.6.1.tar.gz 16:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:25 INFO - Running setup.py install for blobuploader 16:10:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:10:25 INFO - Running setup.py install for requests 16:10:26 INFO - Running setup.py install for docopt 16:10:26 INFO - Successfully installed blobuploader requests docopt 16:10:26 INFO - Cleaning up... 16:10:26 INFO - Return code: 0 16:10:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:26 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:26 INFO - 'HOME': '/Users/cltbld', 16:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:26 INFO - 'LOGNAME': 'cltbld', 16:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:26 INFO - 'MOZ_NO_REMOTE': '1', 16:10:26 INFO - 'NO_EM_RESTART': '1', 16:10:26 INFO - 'PAGER': '/bin/cat', 16:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:26 INFO - 'PWD': '/builds/slave/test', 16:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:26 INFO - 'SHELL': '/bin/bash', 16:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:26 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:26 INFO - 'USER': 'cltbld', 16:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:26 INFO - 'XPC_FLAGS': '0x0', 16:10:26 INFO - 'XPC_SERVICE_NAME': '0', 16:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:26 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-yHg69i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-9SNFmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Bb7qLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Vkc8HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-1kehpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-V58kym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-eKcglj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-YXnd7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-ebloSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-7hBRDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:27 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Ip0yP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:28 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-j9i3CW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:28 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-OafFT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:28 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-Incdqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:28 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-2Ym5Av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:28 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-PNOWNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:28 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-jaNSmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:28 INFO - Running setup.py install for manifestparser 16:10:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:10:28 INFO - Running setup.py install for mozcrash 16:10:28 INFO - Running setup.py install for mozdebug 16:10:29 INFO - Running setup.py install for mozdevice 16:10:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Running setup.py install for mozfile 16:10:29 INFO - Running setup.py install for mozhttpd 16:10:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Running setup.py install for mozinfo 16:10:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Running setup.py install for mozInstall 16:10:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:10:29 INFO - Running setup.py install for mozleak 16:10:29 INFO - Running setup.py install for mozlog 16:10:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:10:30 INFO - Running setup.py install for moznetwork 16:10:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:10:30 INFO - Running setup.py install for mozprocess 16:10:30 INFO - Running setup.py install for mozprofile 16:10:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:10:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:10:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:10:30 INFO - Running setup.py install for mozrunner 16:10:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:10:30 INFO - Running setup.py install for mozscreenshot 16:10:30 INFO - Running setup.py install for moztest 16:10:31 INFO - Running setup.py install for mozversion 16:10:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:10:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:31 INFO - Cleaning up... 16:10:31 INFO - Return code: 0 16:10:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:31 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:31 INFO - 'HOME': '/Users/cltbld', 16:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:31 INFO - 'LOGNAME': 'cltbld', 16:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:31 INFO - 'MOZ_NO_REMOTE': '1', 16:10:31 INFO - 'NO_EM_RESTART': '1', 16:10:31 INFO - 'PAGER': '/bin/cat', 16:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:31 INFO - 'PWD': '/builds/slave/test', 16:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:31 INFO - 'SHELL': '/bin/bash', 16:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:31 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:31 INFO - 'USER': 'cltbld', 16:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:31 INFO - 'XPC_FLAGS': '0x0', 16:10:31 INFO - 'XPC_SERVICE_NAME': '0', 16:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:31 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-X5ZKMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:31 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-yLYgeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:31 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-5FrU4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:31 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-i077pR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:31 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-PC4bPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-PY8QkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-BvKeJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-RwHVMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-cldwmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-NWtUSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-D6HBHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-e9n7v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-dKMIWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:32 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-3AQ4G1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:33 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-lrWv2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:33 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-5fKxWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:33 INFO - Running setup.py (path:/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/pip-EgRlKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:35 INFO - Downloading blessings-1.6.tar.gz 16:10:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:35 INFO - Installing collected packages: blessings 16:10:35 INFO - Running setup.py install for blessings 16:10:36 INFO - Successfully installed blessings 16:10:36 INFO - Cleaning up... 16:10:36 INFO - Return code: 0 16:10:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:10:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:36 INFO - Reading from file tmpfile_stdout 16:10:36 INFO - Current package versions: 16:10:36 INFO - blessings == 1.6 16:10:36 INFO - blobuploader == 1.2.4 16:10:36 INFO - docopt == 0.6.1 16:10:36 INFO - functools32 == 3.2.3-2 16:10:36 INFO - jsonschema == 2.5.1 16:10:36 INFO - manifestparser == 1.1 16:10:36 INFO - mozInstall == 1.12 16:10:36 INFO - mozcrash == 0.17 16:10:36 INFO - mozdebug == 0.1 16:10:36 INFO - mozdevice == 0.48 16:10:36 INFO - mozfile == 1.2 16:10:36 INFO - mozhttpd == 0.7 16:10:36 INFO - mozinfo == 0.9 16:10:36 INFO - mozleak == 0.1 16:10:36 INFO - mozlog == 3.3 16:10:36 INFO - moznetwork == 0.27 16:10:36 INFO - mozprocess == 0.23 16:10:36 INFO - mozprofile == 0.28 16:10:36 INFO - mozrunner == 6.12 16:10:36 INFO - mozscreenshot == 0.1 16:10:36 INFO - mozsystemmonitor == 0.3 16:10:36 INFO - moztest == 0.7 16:10:36 INFO - mozversion == 1.4 16:10:36 INFO - psutil == 3.1.1 16:10:36 INFO - requests == 1.2.3 16:10:36 INFO - wsgiref == 0.1.2 16:10:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:36 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 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:36 INFO - 'HOME': '/Users/cltbld', 16:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:36 INFO - 'LOGNAME': 'cltbld', 16:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:36 INFO - 'MOZ_NO_REMOTE': '1', 16:10:36 INFO - 'NO_EM_RESTART': '1', 16:10:36 INFO - 'PAGER': '/bin/cat', 16:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:36 INFO - 'PWD': '/builds/slave/test', 16:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:36 INFO - 'SHELL': '/bin/bash', 16:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:36 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:36 INFO - 'USER': 'cltbld', 16:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:36 INFO - 'XPC_FLAGS': '0x0', 16:10:36 INFO - 'XPC_SERVICE_NAME': '0', 16:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:10:36 INFO - Cleaning up... 16:10:36 INFO - Return code: 0 16:10:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038e8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10397d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedb4c05b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, '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.InMsYG25wK/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.kbKgMxfOK6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:10:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:36 INFO - 'HOME': '/Users/cltbld', 16:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:36 INFO - 'LOGNAME': 'cltbld', 16:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:36 INFO - 'MOZ_NO_REMOTE': '1', 16:10:36 INFO - 'NO_EM_RESTART': '1', 16:10:36 INFO - 'PAGER': '/bin/cat', 16:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:36 INFO - 'PWD': '/builds/slave/test', 16:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:36 INFO - 'SHELL': '/bin/bash', 16:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:10:36 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:36 INFO - 'USER': 'cltbld', 16:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:36 INFO - 'XPC_FLAGS': '0x0', 16:10:36 INFO - 'XPC_SERVICE_NAME': '0', 16:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:10:36 INFO - Cleaning up... 16:10:37 INFO - Return code: 0 16:10:37 INFO - Running post-action listener: _resource_record_post_action 16:10:37 INFO - Running post-action listener: _start_resource_monitoring 16:10:37 INFO - Starting resource monitoring. 16:10:37 INFO - [mozharness: 2016-08-11 23:10:37.031691Z] Finished create-virtualenv step (success) 16:10:37 INFO - [mozharness: 2016-08-11 23:10:37.032662Z] Running install step. 16:10:37 INFO - Running pre-action listener: _resource_record_pre_action 16:10:37 INFO - Running main action method: install 16:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:37 INFO - Reading from file tmpfile_stdout 16:10:37 INFO - Detecting whether we're running mozinstall >=1.0... 16:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:10:37 INFO - Reading from file tmpfile_stdout 16:10:37 INFO - Output received: 16:10:37 INFO - Usage: mozinstall [options] installer 16:10:37 INFO - Options: 16:10:37 INFO - -h, --help show this help message and exit 16:10:37 INFO - -d DEST, --destination=DEST 16:10:37 INFO - Directory to install application into. [default: 16:10:37 INFO - "/builds/slave/test"] 16:10:37 INFO - --app=APP Application being installed. [default: firefox] 16:10:37 INFO - mkdir: /builds/slave/test/build/application 16:10:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:11:11 INFO - Reading from file tmpfile_stdout 16:11:11 INFO - Output received: 16:11:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:11:11 INFO - Running post-action listener: _resource_record_post_action 16:11:11 INFO - [mozharness: 2016-08-11 23:11:11.404001Z] Finished install step (success) 16:11:11 INFO - [mozharness: 2016-08-11 23:11:11.404144Z] Running run-tests step. 16:11:11 INFO - Running pre-action listener: _resource_record_pre_action 16:11:11 INFO - Running main action method: run_tests 16:11:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:11 INFO - 2016-08-11 16:11:11.418 screenresolution[2304:334736] starting screenresolution argv=screenresolution get 16:11:11 INFO - 2016-08-11 16:11:11.421 screenresolution[2304:334736] Display 0: 1600x1200x32@60 16:11:11 INFO - 2016-08-11 16:11:11.427 screenresolution[2305:334739] starting screenresolution argv=screenresolution list 16:11:11 INFO - Available Modes on Display 0 16:11:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:11 INFO - Intel Iris: 16:11:11 INFO - Chipset Model: Intel Iris 16:11:11 INFO - Type: GPU 16:11:11 INFO - Bus: Built-In 16:11:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:11 INFO - Vendor: Intel (0x8086) 16:11:11 INFO - Device ID: 0x0a2e 16:11:11 INFO - Revision ID: 0x0009 16:11:11 INFO - Displays: 16:11:11 INFO - Display: 16:11:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:11 INFO - Display Serial Number: 1600x1200 60 16:11:11 INFO - Main Display: Yes 16:11:11 INFO - Mirror: Off 16:11:11 INFO - Online: Yes 16:11:11 INFO - Rotation: Supported 16:11:11 INFO - Return code: 0 16:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:11 INFO - Python 2.7.3 16:11:11 INFO - Return code: 0 16:11:11 INFO - grabbing minidump binary from tooltool 16:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:11 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 0x10384cd30>, '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 0x1035b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103844b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:11:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:11:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:11:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:11 INFO - Return code: 0 16:11:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:11:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:11:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--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 16:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0047 --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 16:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InMsYG25wK/Render', 16:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:11 INFO - 'HOME': '/Users/cltbld', 16:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:11 INFO - 'LOGNAME': 'cltbld', 16:11:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:11 INFO - 'MOZ_NO_REMOTE': '1', 16:11:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:11 INFO - 'NO_EM_RESTART': '1', 16:11:11 INFO - 'PAGER': '/bin/cat', 16:11:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:11 INFO - 'PWD': '/builds/slave/test', 16:11:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:11 INFO - 'SHELL': '/bin/bash', 16:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbKgMxfOK6/Listeners', 16:11:11 INFO - 'TMPDIR': '/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/', 16:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:11 INFO - 'USER': 'cltbld', 16:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:11 INFO - 'XPC_FLAGS': '0x0', 16:11:11 INFO - 'XPC_SERVICE_NAME': '0', 16:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0047', '--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 16:11:12 INFO - mozversion application_buildid: 20160811135022 16:11:12 INFO - mozversion application_changeset: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 16:11:12 INFO - mozversion application_display_name: Nightly 16:11:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:12 INFO - mozversion application_name: Firefox 16:11:12 INFO - mozversion application_remotingname: firefox 16:11:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:11:12 INFO - mozversion application_vendor: Mozilla 16:11:12 INFO - mozversion application_version: 51.0a1 16:11:12 INFO - mozversion platform_buildid: 20160811135022 16:11:12 INFO - mozversion platform_changeset: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 16:11:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:11:12 INFO - mozversion platform_version: 51.0a1 16:11:12 INFO - using testdate: 1470957072 16:11:12 INFO - actual date: 1470957072 16:11:12 INFO - starting webserver on 'localhost:51041' 16:11:12 INFO - SUITE-START | Running 2 tests 16:11:12 INFO - TEST-START | dromaeo_css 16:11:12 INFO - operating with platform_type : mac_ 16:11:12 INFO - Initialising browser for dromaeo_css test... 16:11:12 INFO - TEST-INFO | started process 2315 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpa2Vutr/profile http://localhost:51041/getInfo.html) 16:11:15 INFO - PROCESS | 2315 | __metrics Screen width/height:1600/1200 16:11:15 INFO - PROCESS | 2315 | colorDepth:24 16:11:15 INFO - PROCESS | 2315 | Browser inner width/height: 1024/689 16:11:15 INFO - PROCESS | 2315 | __metrics 16:11:15 ERROR - PROCESS | 2315 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 16:11:15 INFO - TEST-INFO | 2315: exit 0 16:11:15 INFO - Browser initialized. 16:11:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:15 INFO - TEST-INFO | started process 2319 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpa2Vutr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:27 INFO - PROCESS | 2319 | RSS: Main: 254631936 16:11:27 INFO - PROCESS | 2319 | 16:12:17 INFO - PROCESS | 2319 | 1470957137430 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:14:29 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:51041/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51041/tests/dromaeo/cssquery-ext.html) 16:14:30 INFO - PROCESS | 2319 | RSS: Main: 267218944 16:14:30 INFO - PROCESS | 2319 | 16:17:32 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:51041/tests/dromaeo/cssquery-ext.html (next: http://localhost:51041/tests/dromaeo/cssquery-jquery.html) 16:17:33 INFO - PROCESS | 2319 | RSS: Main: 275300352 16:17:33 INFO - PROCESS | 2319 | 16:20:35 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:51041/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51041/tests/dromaeo/cssquery-mootools.html) 16:20:36 INFO - PROCESS | 2319 | RSS: Main: 280264704 16:20:36 INFO - PROCESS | 2319 | 16:23:37 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:51041/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51041/tests/dromaeo/cssquery-prototype.html) 16:23:38 INFO - PROCESS | 2319 | RSS: Main: 278790144 16:23:38 INFO - PROCESS | 2319 | 16:26:41 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:51041/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51041/tests/dromaeo/cssquery-yui.html) 16:26:41 INFO - PROCESS | 2319 | RSS: Main: 283086848 16:26:41 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:51041/tests/dromaeo/cssquery-yui.html (next: http://localhost:51041/tests/dromaeo/cssquery-dojo.html) 16:29:44 INFO - PROCESS | 2319 | RSS: Main: 284585984 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | __start_tp_report 16:29:44 INFO - PROCESS | 2319 | _x_x_mozilla_page_load 16:29:44 INFO - PROCESS | 2319 | _x_x_mozilla_page_load_details 16:29:44 INFO - PROCESS | 2319 | |i|pagename|runs| 16:29:44 INFO - PROCESS | 2319 | |0;dojo.html;3108.9689103108967;3373.3421982713367;3405.0976491229812;3412.8634854605816;3432.399330117229;5310.36275646922;5322.148456247001;5529.447055294469;5753.5684823638185;5755.482006619403;5623.859403514914;5640.5487560995125;5652.321721393439;5732.942670573293;5781.248437703092;5114.795408183678;5232.372115346164;5235.293235413227;5277;5330.680159190461;5311.840644780663;5333.333333333333;5353.812616558421;5413.593980451462;5420.051490989081;5137.434882162959;5199.974000130013;5218.6868787872845;5235.842924712227;5265.394479634814;5218.1129208034345;5228.398734145584;5252.684838909678;5263.44733802949;5278.577713782888;5182.4299327073995;5209.218617207449;5225.033368826741;5232.607554433433;5264.236685680555;4842.806287748486;5505.587080968903;5561.416051314634;5621.494065534081;5645.407232240637;6132.785352512686;6166.506679465632;6225.7509699611965;6225.750969961241;6241.96879015602;6218.657973811439;6253.6247825130185;6258.843528911768;6268.87462250757;6285.245770507523;5679.744411501492;5751.769929202785;5791.0734282514595;5800.303963524319;5810.738516766755;6245.843853903689;6248.31268560458;6268.843278918063;6273.215848018997;6298.9291820390645;4987.351644286219;6057.909131363033;6139.907901381483;6141.170941922784;6159.199304090529;6120.632762034292;6127.403330890726;6133.26400831903;6155.076738489262;6172.8765424691255;6146.7233974471255;6188.535859810532;6196.845078872974;6198.349173336825;6234.407731265523;6168.9691551541955;6217.595856269328;6229.221347331583;6239.469645080128;6262.436380725757;4128.855490057834;4814.735189564538;4885.6335774817035;4909.140900342426;4980.950190498049;4373.759443230653;4426.9778651106535;4449.532799056053;4451.465824101128;4455.509893911667;5622.465865742748;5698.914516282259;5748.683822389725;5752.252207213036;5763.769449222068;6119.632822030692;6185.350538193425;6203.627782333075;6218.409251121227;6235.251769787655;5679.176519404663;5727.3699893011735;5744.454276843693;5753;5761.510271626875;5433.049216387117;5434.103372943499;5436.130219164915;5442.156465747816;5463.836084917459;6171.475424588871;6228.626282423069;6231;6256.5307601930945;6375.139356186948;6201.976673848764;6247.125402443752;6247.281562620356;6271.592346497463;6277.1525844010475;6317.778877739257;6326.905096423466;6328;6330.778422755366;6332.556721029484;5024.246363045572;5453.945460545344;6018.969905150446;6074.483668888017;6147.508199343953;6257.43683068526;6269;6270.122182894307;6287.842803929939;6301.149344838388;6079;6117.388261173847;6117.510599152146;6126.632402055891;6141.969290153521;5901.852453688521;5989.071693887455;6097.176881120992;6115.694215289307;6123.173371594778;4911.656184067081;5790.913136302875;5922.141289512911;5972.940270597239;5986.281646202484;4909.558139767295;5612.849365879903;6073.423024812636;6092.573519853568;6102.9694851525455;5135.15269980449;5837.912431313619;5843.444872737083;5868.2077919482035;5990.970045149921 16:29:44 INFO - PROCESS | 2319 | |1;ext.html;509.76805553473173;519.5739493615234;533.6344603946297;543.3696911582565;560.8950367775484;38170.80914595426;39793.60206397935;39866.402003969924;39915.800420997926;39925.401118983165;41280.79359603198;41873.162536749245;41933.79033104838;42017;42084.158316833644;9646.324757266977;9787.119159275664;9841.36031157976;9850;9870.851937220947;9689.418634881895;9802.264830137732;9814.411135331902;9824.459654718987;9838.606455741761;9798.461084640341;9802.852957205647;9823.459709715999;9829.115379615832;9841.36031157976;9759.463229522373;9800.264980126447;9811.460369679664;9814.950925245364;9841.852372214422;8337;9725.56234969428;9826.508674566243;9840.065193806577;9880.802383952352;39352.80323598392;39372.40941385881;39502.802485987675;39736.80131599324;39744.60255397438;2645.8015648826413;2687.690915544718;3392.4402473591826;3548.8580456781697;3559.1280136366495;23014.194503192477;25715.48569028609;29367.2658183545;29507;29566.40867182666;2851.17315978366;2862.6974726499393;3041.2548925513393;3154.794938329001;3165.2403423178503;516685;517215;522798.3860080713;524874;526101;62787.744245114845;62952.68523657444;63343;63352.049719254246;63474.682626586575;34559.4816077759;34882.47676284858;35169.82415087908;35734;67266.66366668136;16275.430359937582;16418.425355112515;16428.6714265714;16474.917625411796;16477.83522164763;12528.686782830502;12604.306763128116;12620.432080556397;12647.557335493213;12669.429875655618;19407.29581834759;19438.653886434204;19536;19607.705884411746;19734;7820.687172513036;7910.485818421784;7927.643256053491;7937.841243175104;7984.880226796603;868.6395146014333;973.2846357926918;995.5420506566916;1006.8942761010135;1091.7652704668535;29258.561121583192;30414.087577372273;30417.69582304193;30489.39021219563;30652;1036.6941752183125;1037.9792404151874;1077.3966578715945;1086.266769930294;1159.652104368686;13176.341182940814;15886;15917.761233581505;16184.433544825875;16318.673626527403;666307;675366.6231668908;676321;676639.6168019129;679321;58417;58557.70721146367;59018;59202;59258.40741592615;1339.80572816941;1369.4453746232791;1401.6706074072788;1406.627243780399;1419.3541938417995;26898.175691552104;27705.86147069252;30265.394692106474;30416.239594010327;30719.846400768747;46496.53503464922;46700.7664961673;46763;46801.53198468108;46826.53173468222;580890;583794;584095;584329;585916;26941;26953.865230674506;27082.05212817542;27305.863470682518;27324.453510930067;47440.80022795116;47546.52453475559;48233;48851.51148488469;49318;38796;38881.6111838878;39188;39517.9670005009;39817.40273895952;39342;39449;39717.80141099276;40781.796091020544;41109;38758.4186237201;40249.79250622486;40715.38926916158;40715.79642101771;40875.38686919758;30736.692633073384;34620;35097.29805403929;35097.64902350944;35130.29739405249;22195.223167189717;25954.610680840182;26019.479610408063;26043.479130417665;26300.74554776753 16:29:44 INFO - PROCESS | 2319 | |2;jquery.html;375.7951916205666;378.4417983474375;380.916198436344;387.1521368203636;399.44876071021986;19246.711299330505;21870.23454179104;21916;21982.01080951357;22217.666734998948;20806.375808725723;20910.26814061508;21557.46106347342;21740.673889891663;21818.89090554545;4817.445993710724;5106.846794596168;5717.942820571803;5896.852578685546;6008.579399442041;5046.369203849519;5996.100584912277;6062.666553339564;6137.539684523656;6210.4100110489435;6017.398260173992;6106.847328816771;6122.8469288267925;6186.443220110189;6242.563020588561;6753.696083676246;6825.215100263459;6834.624095674736;6834.794956151323;6838.521303508756;5850.297964244318;5903.822885313448;5941.494972927307;6034.728437220336;6059.878922399326;10926.781464370748;13644.454221831114;13652.795208071886;16665.25006374716;26493;2460.3849037740565;2609.595512695535;2990.6112205413156;2996.9061292628253;3106.130283520618;9967.302288839785;10088.747781305452;10103.359173126644;10179.18566514677;10570.418626975514;1849.7502837116951;1877.4836919847014;1898.8575856810655;1941.3399444188901;1954.9706754398696;128432.71567284394;130761.34619326938;132302;133091;136039;9494.762630934281;10115.949420252999;10198.592056317813;10250.794984100276;10362.326448780805;12360;12505.624831255076;12512.186707863959;12524.499020039279;12536.749265014649;7094.184168820549;7699.9990001299475;8137.226963438582;8221.17788221185;8290.668373265122;8472.068072512018;8491.405601607828;8491.702790402307;8539.249453861943;8624.52565108925;3888.9027774305305;3961.7226794124135;3968.325384684553;3977.840886364571;4005.0788318686864;1831.84429323509;1888.4806678163616;1968.6456437841325;2045.7136000959877;2048.2114385961213;852.6461518469889;859.8409294280578;876.6712482818942;891.2825175733597;947.7914858731068;20943.68584471234;22158.22446214407;22307.219247326266;22321.441963950707;22492.88753556222;930.692871352452;941.4633658814547;978.6966040527459;986.5264672957021;996.1831298335295;11471.483783229774;11572.148456247129;11740.178187526963;11752.88247117535;11764.705882352839;601365;609235;615870;616496;631201;104481;131888.68111318763;132804;133254.33372833073;138717;938.3712912348743;951.8381875081253;988.5600907596195;988.5749127874939;1023.370627064365;17823;19601.215951361784;19713.605727885075;19876.801231988073;19909.800901991373;54881.72559137179;55263.17105243344;57242;57854;58416.415835842796;74132.62933685297;75853.24146758682;81873.18126818656;89253;92072.53963730138;7571.280728330799;7930.127685954656;8272.255497005299;8373.330133589176;8568.828623427622;12690.809637855255;13297.734045319234;13444.865551344752;13738.587842364745;13789.724205516035;11522.942385288019;11613.76772464563;11863.940680296544;11914;11930.22453540517;9764.414135151914;10986.340819550853;11419.371934543347;11526.308421494738;11831.11266654987;9807.705768826947;11692.35692036946;11824.1030947415;11923.880761192277;12164.99277619183;6617.470602351704;8139.4709343892455;8293.046299675374;8436.915630843612;8518.74443766688;9797.85303220438;11803.586874459354;11876.643700688994;11927.224730392496;11949.761004779682 16:29:44 INFO - PROCESS | 2319 | |3;mootools.html;1503.3911265937295;1542.637480192155;1777.8621682123444;1877.601187115599;1902.2486117983406;7983.562958667438;8460.703875364363;8631.223189912907;9038.638454461821;9072.228860546853;7743.341815945637;8647.524386158759;8761.912380876189;8902.955485222565;8910.376273660846;2067.824234940032;2171.2183613899024;2333.0434521846046;2338.766123387656;2344.097522453859;2299.1837897546297;2324.1400681747778;2332.7667233276707;2351.47091904321;2358.917437889674;2315.2822624986225;2336.894839732208;2346.2609278077434;2347.084636991575;2350.518143780521;2295.540891821642;2313.1556981701556;2314.9768502315014;2325.1745630301252;2326.232343326698;2319.617263151578;2330.5222429401933;2330.7669233076726;2333.7432882382923;2338.088145623208;8998.920129584492;9009.009009009003;9065.728028159167;9070.86393704095;9091.772705682344;5154.098032844275;5253.212018197301;5319.20211968204;5320.654157479751;5328.653637513588;3422.8801991930163;3472.826358682056;3541.8760343388117;3543.8228088595724;3572.2319701264355;928.5310917986441;1012.7569383348017;1058.3544038136731;1062.8618279623677;1076.9138468922467;9666;11558.479868405859;11680.88319116815;11737.237079589799;11757.823632645432;4395.7142785718825;4536.6143877770755;4751.40607424072;4754.001659651442;4764.309175169581;9222.170004699608;9812.950935245279;9891.752706182404;9981.401115933068;10010.54952527138;4345.174416860849;5310.7610157543;5312.07038768214;5316.282301889273;5317.8404647860625;4351.608355248043;4701.600363969101;4816.325714399987;4863.294822250754;4864.781084851119;3205.6954589313978;3318.684724951174;3366.9326613467592;3927.823247953792;4027.838886444509;1068.887766784492;1073.9355638661705;1083.2309060567086;1084.5770149641646;1293.6507143071506;782.9804254893673;922.9077092290718;936.0779632062413;944.7071407863584;947.6114792934864;5631.746571404296;5882.088276317178;5938.851528711817;6033.879322413615;6114.174586430864;898.9280857531381;928.6981730937472;929.8098434004485;961.3654987708079;970.5292932927665;3500.579930408367;3636.5999740028574;3682.815859207029;3825.407061905409;3880.4567360569545;8742.956285218534;10932.289401188897;10981.890181098246;11016.449177541092;11025.952534509208;10493.737656558647;10620.787584248272;10954.452277386099;10992.725181870517;11003.22977391591;8176.427650064438;8256.99999999988;8363.749087527383;8396.622152003052;8398.286145677686;5889.234399528034;5979.880402392015;6048.758049677964;6133.877322453615;6170.598911070766;9939.850902236616;10075.09324160829;10192;10219.131373833308;10257.33327333721;9913.65302214419;10218.335808172445;10358.689239322832;10404.803447603623;10441.477926103817;7075.186353569406;7458.552486850806;7644.579548124907;7790.14308426084;7794.88307675374;8352.248297653241;8585.399022068395;8647.135286471303;8653.653853845775;8684.478931264144;7470.626468676436;7569.621518923922;7615.4669173157345;7673.271039251262;7705.961470192613;7392.191012072242;7491.737789177353;7537.283958023979;7608.125065617525;7665.731699390494;6985.895211571728;7145;7146.285371462812;7165.964170179116;7186.676599552928;4929;4956.306117143675;5039.470855560114;5137.17805151174;5176.938727560916;4509.413776209072;5038.874028149326;5095.490450954875;5189.92215116766;5279.52484276417 16:29:44 INFO - PROCESS | 2319 | |4;prototype.html;300.0189380725028;306.9877204911803;314.33047608593694;316.89088191330353;317.1405491119067;5004.724740139291;5320.627556071081;5410.269613602162;5560.888782224358;5633.408492108331;5083.669561478501;5432.809851655193;5571.749271282792;5598.748056337465;5614.522765564931;4215.873523794291;4231.767252801094;4243.363495475688;4257.127288905767;4271.359296105594;4041.8989525261954;4189.434426352434;4189.811458484361;4234.1320029394055;4258.318669012959;3477.8087205203706;3640.5449318835144;4184.51878034027;4185.430463576159;4256.169647184619;4192.979035104835;4193.140406216734;4210.410542524049;4218;4223.155368926227;3021.3353062326255;3201.551782750417;3281.261716113879;3429.7995701504524;3648.5986541480415;4753.098760495829;5202.583793296565;5264.6838290427395;5392.352917649868;5445.237666726662;4252.383404406344;4852.708837469763;4856.101621200054;4863.537963893425;4869.586085182764;4738.815296175956;5302.708351040691;5652.943470565283;5915.816836632647;5934.881302373971;3968.8214030368413;3980.6218409251073;4002.4396584478195;4012.538558065831;4023.807025171289;6240.906386404207;6261.624302541816;6293.213348331458;6338.239411270677;6377.681115944184;3609.9639003609627;3874.9418758718643;4062.532808726975;4084.6732261419015;4115.135821477524;3915.0997463358303;4128.6284234418445;4140.8136633851545;4145.927036481759;4149.419081328617;4794.880127996758;4806.663533552688;4864.270359446112;4887.804487820518;4890.24201248807;5606.635568688022;5878.823635290948;5905.320888097838;5968.895754285384;6030.487408570233;1322.127395918689;1391.373881753215;1392.4987004678308;1401;1416.9302176856409;758.6510205305511;791.8614242507538;795.9005124359455;835.5655059369094;840.4075127035426;4301.763403012802;4441.578050085237;4453.732776033448;4653.37132003796;4701.811927522861;4691.601213896789;4790.4969978152485;5115.87931361869;5120.564751996048;5140.1004824155625;4481.865544033684;4574.565416285448;4585.380973568593;4589.751021999196;4630.4443466784205;2548.0444833187553;2863.656361236665;3104.4943202547197;3124.625044994615;3197.2894501441615;5426.802145131228;6183.598066125777;6251.374862513712;6334.954732469091;6372.745090196433;4376.321670141133;5533.363663178786;6251.218402755193;6262.3111457739615;6341.746330146835;4737.0525894820485;5812.360640329476;5884.470397664317;5967.701614919237;6202.34875338092;4981.900361992667;5907.438793314628;6038.486728628116;6149.846253843511;6156.5998210116195;5179.45615710345;5549.417311182429;5583.548277447811;5665.1871401152885;5958.553108516792;5605.803796867253;6310.432061114522;6377.17096777416;6378.45783108441;6412.6793660317735;3856.2094770571402;3951.881443556698;3992.843864313238;3999.7200195986;4044.41356003372;5023.6985780853265;6135.53983451234;6147.784827531015;6162.414570615784;6188.566800324114;5765.3946335634155;5945.64326140433;5977.193078934462;5995.790147344822;5996.160537524663;4883.974365383309;5934.762609495572;5965.164876917148;5978.671173085522;5985.221921150222;4196.349565817303;5014.598832093497;5099.847004589868;5735;5898.8230353089475;5198.506141916512;5910.995130827683;5983.371745966785;5994.340622531431;6030.190942717079;4511;5859.648421094748;5863.149843272617;5886.411358864079;5898.705064746831 16:29:44 INFO - PROCESS | 2319 | |5;yui.html;617.2870334763347;678.4199509419446;689.8804339219259;694.7221111555381;856.9528675922826;15569.065856048632;15755.60610984726;16073.75889361659;16209.108499032549;16240.837591624095;11894.167408281402;14688.118712877234;14706.558803235894;14776.85223147771;14868.851311486882;849.9830003399928;856.7858035491128;864.4510735682824;867.301822033263;867.7743786615463;676.1210426445601;832.4672209785712;859.4113032572676;862.3963225741975;870.6430363550913;914.4513292024799;918.274563095154;918.7151982885327;922.4926290540176;922.8754118194058;762.393896852004;808.8988876390481;911.098012967161;913.7258822353334;917.0233701108299;672.0456951129408;678.670844640354;679.8232459560501;681.3731367142241;682.6654939079815;14917.925410372878;15073.547793566211;15236.695266094615;15276.465323713617;15288.770668439982;14981.10113393189;14989.325480353411;15003.474878379204;15209.771853422208;15237.314320855588;11995.760084798256;12143.167656861364;12147.331896745676;12547.686307842285;12582.937085314516;3778.614607075617;3837.1558257183374;3893.201893611803;4012.779297138656;4049.1091959768805;79337.60331198365;116793;118017;118246.40876795647;121811;11291.49188286529;11349.319040857576;11469.037066382714;11559.36423496699;11702.941485292518;24096.27711168668;24147;24410.633840492406;24436.633450498255;24534.631980520306;6993;8201;8272.586370681442;8388.748337549883;8526.87209691855;3774.471573979645;4550.954490455119;4614.607758340511;4663.393758811333;4761.2382018876815;3542.769719968194;3606.2787442511603;3663.2307215484516;3665.8350374233223;3682.5212722345923;633.4773811605444;641.6118248459708;648.8475208326134;649.389573800626;661.5898143151277;525.3380740267214;593.3858456497504;673.1652750589234;707.2030859771065;709.9680514376865;9355.859662104938;9465.810683786285;9579.616815327308;9661.227101831837;9704.514774261259;526.5918912842501;608.8219295662907;635.4566845347254;635.5499549237715;663.5355251323996;4514.367988481616;4638.582527572535;4650.651201159926;4707.388039554836;4719.4100737407825;151899;159043;164656;172373;181839;149600;151123;151920;154138.22930885275;154507;122279;123594.73039522071;125033;126017.99999999817;126840;7289.4168466523615;8874.467531948103;8934.374593778373;8991.39758134896;9127.58925848325;63405.365946339945;65543.67228163828;70253.64873175601;71020.28979710344;72167.27832721816;103579.482102589;103982.48008759908;104657.47671261596;105248;106833;12007.339596322287;12017.699557511132;12074.818877717016;12634.684132896751;12695.746085078432;13211.933940330237;13335.799963000369;13374.398152082986;13502.459901603826;13770.93114534421;12128.762010640805;12220.694482638006;12401.93799030999;12684.97474120896;12810;12291.201071930296;12435.689107772016;12455.314957677414;12858.164219325714;12860.935695321463;12009.519619215484;12253.69365765863;12450.875491244971;12626.179298345578;12685.619431417072;10501.9474902625;10673.41296228715;10679.751767932017;10825.512851921525;11120.833187502356;8433.578321083798;9390.342676012613;9571.760705982128;9803.656872009446;10708.678739637824 16:29:44 INFO - PROCESS | 2319 | __end_tp_report 16:29:44 INFO - PROCESS | 2319 | __start_cc_report 16:29:44 INFO - PROCESS | 2319 | _x_x_mozilla_cycle_collect,416 16:29:44 INFO - PROCESS | 2319 | __end_cc_report 16:29:44 INFO - PROCESS | 2319 | __startTimestamp1470958184715__endTimestamp 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | ------- Summary: start ------- 16:29:44 INFO - PROCESS | 2319 | Number of tests: 6 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | [#0] dojo.html Cycles:165 Average:5688.36 Median:5810.74 stddev:650.59 (11.2%) stddev-sans-first:620.32 16:29:44 INFO - PROCESS | 2319 | Values: 3109.0 3373.3 3405.1 3412.9 3432.4 5310.4 5322.1 5529.4 5753.6 5755.5 5623.9 5640.5 5652.3 5732.9 5781.2 5114.8 5232.4 5235.3 5277.0 5330.7 5311.8 5333.3 5353.8 5413.6 5420.1 5137.4 5200.0 5218.7 5235.8 5265.4 5218.1 5228.4 5252.7 5263.4 5278.6 5182.4 5209.2 5225.0 5232.6 5264.2 4842.8 5505.6 5561.4 5621.5 5645.4 6132.8 6166.5 6225.8 6225.8 6242.0 6218.7 6253.6 6258.8 6268.9 6285.2 5679.7 5751.8 5791.1 5800.3 5810.7 6245.8 6248.3 6268.8 6273.2 6298.9 4987.4 6057.9 6139.9 6141.2 6159.2 6120.6 6127.4 6133.3 6155.1 6172.9 6146.7 6188.5 6196.8 6198.3 6234.4 6169.0 6217.6 6229.2 6239.5 6262.4 4128.9 4814.7 4885.6 4909.1 4981.0 4373.8 4427.0 4449.5 4451.5 4455.5 5622.5 5698.9 5748.7 5752.3 5763.8 6119.6 6185.4 6203.6 6218.4 6235.3 5679.2 5727.4 5744.5 5753.0 5761.5 5433.0 5434.1 5436.1 5442.2 5463.8 6171.5 6228.6 6231.0 6256.5 6375.1 6202.0 6247.1 6247.3 6271.6 6277.2 6317.8 6326.9 6328.0 6330.8 6332.6 5024.2 5453.9 6019.0 6074.5 6147.5 6257.4 6269.0 6270.1 6287.8 6301.1 6079.0 6117.4 6117.5 6126.6 6142.0 5901.9 5989.1 6097.2 6115.7 6123.2 4911.7 5790.9 5922.1 5972.9 5986.3 4909.6 5612.8 6073.4 6092.6 6103.0 5135.2 5837.9 5843.4 5868.2 5991.0 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | [#1] ext.html Cycles:180 Average:71765.46 Median:39805.50 stddev:159695.71 (401.2%) stddev-sans-first:160054.09 16:29:44 INFO - PROCESS | 2319 | Values: 509.8 519.6 533.6 543.4 560.9 38170.8 39793.6 39866.4 39915.8 39925.4 41280.8 41873.2 41933.8 42017.0 42084.2 9646.3 9787.1 9841.4 9850.0 9870.9 9689.4 9802.3 9814.4 9824.5 9838.6 9798.5 9802.9 9823.5 9829.1 9841.4 9759.5 9800.3 9811.5 9815.0 9841.9 8337.0 9725.6 9826.5 9840.1 9880.8 39352.8 39372.4 39502.8 39736.8 39744.6 2645.8 2687.7 3392.4 3548.9 3559.1 23014.2 25715.5 29367.3 29507.0 29566.4 2851.2 2862.7 3041.3 3154.8 3165.2 516685.0 517215.0 522798.4 524874.0 526101.0 62787.7 62952.7 63343.0 63352.0 63474.7 34559.5 34882.5 35169.8 35734.0 67266.7 16275.4 16418.4 16428.7 16474.9 16477.8 12528.7 12604.3 12620.4 12647.6 12669.4 19407.3 19438.7 19536.0 19607.7 19734.0 7820.7 7910.5 7927.6 7937.8 7984.9 868.6 973.3 995.5 1006.9 1091.8 29258.6 30414.1 30417.7 30489.4 30652.0 1036.7 1038.0 1077.4 1086.3 1159.7 13176.3 15886.0 15917.8 16184.4 16318.7 666307.0 675366.6 676321.0 676639.6 679321.0 58417.0 58557.7 59018.0 59202.0 59258.4 1339.8 1369.4 1401.7 1406.6 1419.4 26898.2 27705.9 30265.4 30416.2 30719.8 46496.5 46700.8 46763.0 46801.5 46826.5 580890.0 583794.0 584095.0 584329.0 585916.0 26941.0 26953.9 27082.1 27305.9 27324.5 47440.8 47546.5 48233.0 48851.5 49318.0 38796.0 38881.6 39188.0 39518.0 39817.4 39342.0 39449.0 39717.8 40781.8 41109.0 38758.4 40249.8 40715.4 40715.8 40875.4 30736.7 34620.0 35097.3 35097.6 35130.3 22195.2 25954.6 26019.5 26043.5 26300.7 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | [#2] jquery.html Cycles:180 Average:35984.63 Median:1740.96 stddev:102947.83 (5913.3%) stddev-sans-first:103201.91 16:29:44 INFO - PROCESS | 2319 | Values: 375.8 378.4 380.9 387.2 399.4 19246.7 21870.2 21916.0 21982.0 22217.7 20806.4 20910.3 21557.5 21740.7 21818.9 4817.4 5106.8 5717.9 5896.9 6008.6 5046.4 5996.1 6062.7 6137.5 6210.4 6017.4 6106.8 6122.8 6186.4 6242.6 6753.7 6825.2 6834.6 6834.8 6838.5 5850.3 5903.8 5941.5 6034.7 6059.9 10926.8 13644.5 13652.8 16665.3 26493.0 2460.4 2609.6 2990.6 2996.9 3106.1 9967.3 10088.7 10103.4 10179.2 10570.4 1849.8 1877.5 1898.9 1941.3 1955.0 128432.7 130761.3 132302.0 133091.0 136039.0 9494.8 10115.9 10198.6 10250.8 10362.3 12360.0 12505.6 12512.2 12524.5 12536.7 7094.2 7700.0 8137.2 8221.2 8290.7 8472.1 8491.4 8491.7 8539.2 8624.5 3888.9 3961.7 3968.3 3977.8 4005.1 1831.8 1888.5 1968.6 2045.7 2048.2 852.6 859.8 876.7 891.3 947.8 20943.7 22158.2 22307.2 22321.4 22492.9 930.7 941.5 978.7 986.5 996.2 11471.5 11572.1 11740.2 11752.9 11764.7 601365.0 609235.0 615870.0 616496.0 631201.0 104481.0 131888.7 132804.0 133254.3 138717.0 938.4 951.8 988.6 988.6 1023.4 17823.0 19601.2 19713.6 19876.8 19909.8 54881.7 55263.2 57242.0 57854.0 58416.4 74132.6 75853.2 81873.2 89253.0 92072.5 7571.3 7930.1 8272.3 8373.3 8568.8 12690.8 13297.7 13444.9 13738.6 13789.7 11522.9 11613.8 11863.9 11914.0 11930.2 9764.4 10986.3 11419.4 11526.3 11831.1 9807.7 11692.4 11824.1 11923.9 12165.0 6617.5 8139.5 8293.0 8436.9 8518.7 9797.9 11803.6 11876.6 11927.2 11949.8 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | [#3] mootools.html Cycles:180 Average:5713.48 Median:5039.17 stddev:3236.52 (64.2%) stddev-sans-first:3230.14 16:29:44 INFO - PROCESS | 2319 | Values: 1503.4 1542.6 1777.9 1877.6 1902.2 7983.6 8460.7 8631.2 9038.6 9072.2 7743.3 8647.5 8761.9 8903.0 8910.4 2067.8 2171.2 2333.0 2338.8 2344.1 2299.2 2324.1 2332.8 2351.5 2358.9 2315.3 2336.9 2346.3 2347.1 2350.5 2295.5 2313.2 2315.0 2325.2 2326.2 2319.6 2330.5 2330.8 2333.7 2338.1 8998.9 9009.0 9065.7 9070.9 9091.8 5154.1 5253.2 5319.2 5320.7 5328.7 3422.9 3472.8 3541.9 3543.8 3572.2 928.5 1012.8 1058.4 1062.9 1076.9 9666.0 11558.5 11680.9 11737.2 11757.8 4395.7 4536.6 4751.4 4754.0 4764.3 9222.2 9813.0 9891.8 9981.4 10010.5 4345.2 5310.8 5312.1 5316.3 5317.8 4351.6 4701.6 4816.3 4863.3 4864.8 3205.7 3318.7 3366.9 3927.8 4027.8 1068.9 1073.9 1083.2 1084.6 1293.7 783.0 922.9 936.1 944.7 947.6 5631.7 5882.1 5938.9 6033.9 6114.2 898.9 928.7 929.8 961.4 970.5 3500.6 3636.6 3682.8 3825.4 3880.5 8743.0 10932.3 10981.9 11016.4 11026.0 10493.7 10620.8 10954.5 10992.7 11003.2 8176.4 8257.0 8363.7 8396.6 8398.3 5889.2 5979.9 6048.8 6133.9 6170.6 9939.9 10075.1 10192.0 10219.1 10257.3 9913.7 10218.3 10358.7 10404.8 10441.5 7075.2 7458.6 7644.6 7790.1 7794.9 8352.2 8585.4 8647.1 8653.7 8684.5 7470.6 7569.6 7615.5 7673.3 7706.0 7392.2 7491.7 7537.3 7608.1 7665.7 6985.9 7145.0 7146.3 7166.0 7186.7 4929.0 4956.3 5039.5 5137.2 5176.9 4509.4 5038.9 5095.5 5189.9 5279.5 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | [#4] prototype.html Cycles:180 Average:4673.37 Median:5009.66 stddev:1474.72 (29.4%) stddev-sans-first:1441.87 16:29:44 INFO - PROCESS | 2319 | Values: 300.0 307.0 314.3 316.9 317.1 5004.7 5320.6 5410.3 5560.9 5633.4 5083.7 5432.8 5571.7 5598.7 5614.5 4215.9 4231.8 4243.4 4257.1 4271.4 4041.9 4189.4 4189.8 4234.1 4258.3 3477.8 3640.5 4184.5 4185.4 4256.2 4193.0 4193.1 4210.4 4218.0 4223.2 3021.3 3201.6 3281.3 3429.8 3648.6 4753.1 5202.6 5264.7 5392.4 5445.2 4252.4 4852.7 4856.1 4863.5 4869.6 4738.8 5302.7 5652.9 5915.8 5934.9 3968.8 3980.6 4002.4 4012.5 4023.8 6240.9 6261.6 6293.2 6338.2 6377.7 3610.0 3874.9 4062.5 4084.7 4115.1 3915.1 4128.6 4140.8 4145.9 4149.4 4794.9 4806.7 4864.3 4887.8 4890.2 5606.6 5878.8 5905.3 5968.9 6030.5 1322.1 1391.4 1392.5 1401.0 1416.9 758.7 791.9 795.9 835.6 840.4 4301.8 4441.6 4453.7 4653.4 4701.8 4691.6 4790.5 5115.9 5120.6 5140.1 4481.9 4574.6 4585.4 4589.8 4630.4 2548.0 2863.7 3104.5 3124.6 3197.3 5426.8 6183.6 6251.4 6335.0 6372.7 4376.3 5533.4 6251.2 6262.3 6341.7 4737.1 5812.4 5884.5 5967.7 6202.3 4981.9 5907.4 6038.5 6149.8 6156.6 5179.5 5549.4 5583.5 5665.2 5958.6 5605.8 6310.4 6377.2 6378.5 6412.7 3856.2 3951.9 3992.8 3999.7 4044.4 5023.7 6135.5 6147.8 6162.4 6188.6 5765.4 5945.6 5977.2 5995.8 5996.2 4884.0 5934.8 5965.2 5978.7 5985.2 4196.3 5014.6 5099.8 5735.0 5898.8 5198.5 5911.0 5983.4 5994.3 6030.2 4511.0 5859.6 5863.1 5886.4 5898.7 16:29:44 INFO - PROCESS | 2319 | 16:29:44 INFO - PROCESS | 2319 | [#5] yui.html Cycles:180 Average:26911.86 Median:3574.52 stddev:44742.81 (1251.7%) stddev-sans-first:44824.77 16:29:44 INFO - PROCESS | 2319 | Values: 617.3 678.4 689.9 694.7 857.0 15569.1 15755.6 16073.8 16209.1 16240.8 11894.2 14688.1 14706.6 14776.9 14868.9 850.0 856.8 864.5 867.3 867.8 676.1 832.5 859.4 862.4 870.6 914.5 918.3 918.7 922.5 922.9 762.4 808.9 911.1 913.7 917.0 672.0 678.7 679.8 681.4 682.7 14917.9 15073.5 15236.7 15276.5 15288.8 14981.1 14989.3 15003.5 15209.8 15237.3 11995.8 12143.2 12147.3 12547.7 12582.9 3778.6 3837.2 3893.2 4012.8 4049.1 79337.6 116793.0 118017.0 118246.4 121811.0 11291.5 11349.3 11469.0 11559.4 11702.9 24096.3 24147.0 24410.6 24436.6 24534.6 6993.0 8201.0 8272.6 8388.7 8526.9 3774.5 4551.0 4614.6 4663.4 4761.2 3542.8 3606.3 3663.2 3665.8 3682.5 633.5 641.6 648.8 649.4 661.6 525.3 593.4 673.2 707.2 710.0 9355.9 9465.8 9579.6 9661.2 9704.5 526.6 608.8 635.5 635.5 663.5 4514.4 4638.6 4650.7 4707.4 4719.4 151899.0 159043.0 164656.0 172373.0 181839.0 149600.0 151123.0 151920.0 154138.2 154507.0 122279.0 123594.7 125033.0 126018.0 126840.0 7289.4 8874.5 8934.4 8991.4 9127.6 63405.4 65543.7 70253.6 71020.3 72167.3 103579.5 103982.5 104657.5 105248.0 106833.0 12007.3 12017.7 12074.8 12634.7 12695.7 13211.9 13335.8 13374.4 13502.5 13770.9 12128.8 12220.7 12401.9 12685.0 12810.0 12291.2 12435.7 12455.3 12858.2 12860.9 12009.5 12253.7 12450.9 12626.2 12685.6 10501.9 10673.4 10679.8 10825.5 11120.8 8433.6 9390.3 9571.8 9803.7 10708.7 16:29:44 INFO - PROCESS | 2319 | -------- Summary: end -------- 16:29:44 INFO - PROCESS | 2319 | 16:29:45 INFO - TEST-INFO | 2319: exit 0 16:29:45 INFO - TEST-OK | dromaeo_css | took 1113326ms 16:29:45 INFO - TEST-START | kraken 16:29:45 INFO - operating with platform_type : mac_ 16:29:45 INFO - Initialising browser for kraken test... 16:29:45 INFO - TEST-INFO | started process 2323 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpocX5AW/profile http://localhost:51041/getInfo.html) 16:29:48 INFO - PROCESS | 2323 | __metrics Screen width/height:1600/1200 16:29:48 INFO - PROCESS | 2323 | colorDepth:24 16:29:48 INFO - PROCESS | 2323 | Browser inner width/height: 1024/689 16:29:48 INFO - PROCESS | 2323 | __metrics 16:29:48 ERROR - PROCESS | 2323 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 16:29:49 INFO - TEST-INFO | 2323: exit 0 16:29:49 INFO - Browser initialized. 16:29:49 INFO - Running cycle 1/1 for kraken test... 16:29:49 INFO - TEST-INFO | started process 2325 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/tmpocX5AW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:01 INFO - PROCESS | 2325 | RSS: Main: 254676992 16:30:01 INFO - PROCESS | 2325 | 16:30:04 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51041/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:04 INFO - PROCESS | 2325 | RSS: Main: 267382784 16:30:04 INFO - PROCESS | 2325 | 16:30:07 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51041/tests/kraken/driver.html?testName=audio-dft) 16:30:08 INFO - PROCESS | 2325 | RSS: Main: 276176896 16:30:08 INFO - PROCESS | 2325 | 16:30:11 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51041/tests/kraken/driver.html?testName=audio-fft) 16:30:12 INFO - PROCESS | 2325 | RSS: Main: 278667264 16:30:12 INFO - PROCESS | 2325 | 16:30:14 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51041/tests/kraken/driver.html?testName=audio-oscillator) 16:30:15 INFO - PROCESS | 2325 | RSS: Main: 280158208 16:30:15 INFO - PROCESS | 2325 | 16:30:17 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51041/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:18 INFO - PROCESS | 2325 | RSS: Main: 280596480 16:30:18 INFO - PROCESS | 2325 | 16:30:21 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51041/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:21 INFO - PROCESS | 2325 | RSS: Main: 280612864 16:30:21 INFO - PROCESS | 2325 | 16:30:24 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51041/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:25 INFO - PROCESS | 2325 | RSS: Main: 282312704 16:30:25 INFO - PROCESS | 2325 | 16:30:28 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51041/tests/kraken/driver.html?testName=json-parse-financial) 16:30:29 INFO - PROCESS | 2325 | RSS: Main: 279810048 16:30:29 INFO - PROCESS | 2325 | 16:30:31 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51041/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:31 INFO - PROCESS | 2325 | RSS: Main: 280969216 16:30:31 INFO - PROCESS | 2325 | 16:30:33 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:34 INFO - PROCESS | 2325 | RSS: Main: 281337856 16:30:34 INFO - PROCESS | 2325 | 16:30:36 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:37 INFO - PROCESS | 2325 | RSS: Main: 281546752 16:30:37 INFO - PROCESS | 2325 | 16:30:40 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:40 INFO - PROCESS | 2325 | RSS: Main: 281772032 16:30:40 INFO - PROCESS | 2325 | 16:30:43 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:44 INFO - PROCESS | 2325 | RSS: Main: 281927680 16:30:44 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:51041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51041/tests/kraken/driver.html?testName=ai-astar) 16:30:46 INFO - PROCESS | 2325 | RSS: Main: 282972160 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | __start_tp_report 16:30:46 INFO - PROCESS | 2325 | _x_x_mozilla_page_load 16:30:46 INFO - PROCESS | 2325 | _x_x_mozilla_page_load_details 16:30:46 INFO - PROCESS | 2325 | |i|pagename|runs| 16:30:46 INFO - PROCESS | 2325 | |0;ai-astar;104;118;109;120;116;117;115;126;168;124 16:30:46 INFO - PROCESS | 2325 | |1;audio-beat-detection;145;108;154;193;120;131;130;110;158;141 16:30:46 INFO - PROCESS | 2325 | |2;audio-dft;272;250;228;199;196;204;201;201;194;225 16:30:46 INFO - PROCESS | 2325 | |3;audio-fft;64;108;69;166;53;67;84;81;153;74 16:30:46 INFO - PROCESS | 2325 | |4;audio-oscillator;74;112;172;102;72;63;132;103;103;147 16:30:46 INFO - PROCESS | 2325 | |5;imaging-gaussian-blur;92;97;136;87;88;86;122;95;136;130 16:30:46 INFO - PROCESS | 2325 | |6;imaging-darkroom;95;96;95;95;97;96;96;116;90;146 16:30:46 INFO - PROCESS | 2325 | |7;imaging-desaturate;147;95;147;145;91;149;148;83;139;142 16:30:46 INFO - PROCESS | 2325 | |8;json-parse-financial;56;58;58;57;61;92;87;89;50;50 16:30:46 INFO - PROCESS | 2325 | |9;json-stringify-tinderbox;72;73;72;83;79;80;81;72;79;75 16:30:46 INFO - PROCESS | 2325 | |10;stanford-crypto-aes;91;87;89;87;82;83;89;87;84;84 16:30:46 INFO - PROCESS | 2325 | |11;stanford-crypto-ccm;138;142;136;134;140;164;143;160;180;162 16:30:46 INFO - PROCESS | 2325 | |12;stanford-crypto-pbkdf2;166;157;198;231;219;169;171;169;168;170 16:30:46 INFO - PROCESS | 2325 | |13;stanford-crypto-sha256-iterative;68;68;69;65;67;67;68;66;68;68 16:30:46 INFO - PROCESS | 2325 | __end_tp_report 16:30:46 INFO - PROCESS | 2325 | __start_cc_report 16:30:46 INFO - PROCESS | 2325 | _x_x_mozilla_cycle_collect,1048 16:30:46 INFO - PROCESS | 2325 | __end_cc_report 16:30:46 INFO - PROCESS | 2325 | __startTimestamp1470958246407__endTimestamp 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | ------- Summary: start ------- 16:30:46 INFO - PROCESS | 2325 | Number of tests: 14 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#0] ai-astar Cycles:10 Average:121.70 Median:119.00 stddev:17.51 (14.7%) stddev-sans-first:17.36 16:30:46 INFO - PROCESS | 2325 | Values: 104.0 118.0 109.0 120.0 116.0 117.0 115.0 126.0 168.0 124.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:143.00 stddev:25.50 (17.8%) stddev-sans-first:26.95 16:30:46 INFO - PROCESS | 2325 | Values: 145.0 108.0 154.0 193.0 120.0 131.0 130.0 110.0 158.0 141.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#2] audio-dft Cycles:10 Average:217.00 Median:214.50 stddev:26.40 (12.3%) stddev-sans-first:19.08 16:30:46 INFO - PROCESS | 2325 | Values: 272.0 250.0 228.0 199.0 196.0 204.0 201.0 201.0 194.0 225.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#3] audio-fft Cycles:10 Average:91.90 Median:68.00 stddev:38.62 (56.8%) stddev-sans-first:39.62 16:30:46 INFO - PROCESS | 2325 | Values: 64.0 108.0 69.0 166.0 53.0 67.0 84.0 81.0 153.0 74.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#4] audio-oscillator Cycles:10 Average:108.00 Median:159.50 stddev:34.60 (21.7%) stddev-sans-first:34.44 16:30:46 INFO - PROCESS | 2325 | Values: 74.0 112.0 172.0 102.0 72.0 63.0 132.0 103.0 103.0 147.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#5] imaging-gaussian-blur Cycles:10 Average:106.90 Median:87.50 stddev:21.36 (24.4%) stddev-sans-first:21.97 16:30:46 INFO - PROCESS | 2325 | Values: 92.0 97.0 136.0 87.0 88.0 86.0 122.0 95.0 136.0 130.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:95.50 stddev:16.85 (17.6%) stddev-sans-first:17.67 16:30:46 INFO - PROCESS | 2325 | Values: 95.0 96.0 95.0 95.0 97.0 96.0 96.0 116.0 90.0 146.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#7] imaging-desaturate Cycles:10 Average:128.60 Median:148.50 stddev:27.18 (18.3%) stddev-sans-first:28.00 16:30:46 INFO - PROCESS | 2325 | Values: 147.0 95.0 147.0 145.0 91.0 149.0 148.0 83.0 139.0 142.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:59.50 stddev:16.64 (28.0%) stddev-sans-first:17.27 16:30:46 INFO - PROCESS | 2325 | Values: 56.0 58.0 58.0 57.0 61.0 92.0 87.0 89.0 50.0 50.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:79.00 stddev:4.25 (5.4%) stddev-sans-first:4.17 16:30:46 INFO - PROCESS | 2325 | Values: 72.0 73.0 72.0 83.0 79.0 80.0 81.0 72.0 79.0 75.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:87.00 stddev:2.95 (3.4%) stddev-sans-first:2.59 16:30:46 INFO - PROCESS | 2325 | Values: 91.0 87.0 89.0 87.0 82.0 83.0 89.0 87.0 84.0 84.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:151.50 stddev:15.45 (10.2%) stddev-sans-first:15.78 16:30:46 INFO - PROCESS | 2325 | Values: 138.0 142.0 136.0 134.0 140.0 164.0 143.0 160.0 180.0 162.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.80 Median:170.50 stddev:25.18 (14.8%) stddev-sans-first:26.05 16:30:46 INFO - PROCESS | 2325 | Values: 166.0 157.0 198.0 231.0 219.0 169.0 171.0 169.0 168.0 170.0 16:30:46 INFO - PROCESS | 2325 | 16:30:46 INFO - PROCESS | 2325 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 16:30:46 INFO - PROCESS | 2325 | Values: 68.0 68.0 69.0 65.0 67.0 67.0 68.0 66.0 68.0 68.0 16:30:46 INFO - PROCESS | 2325 | -------- Summary: end -------- 16:30:46 INFO - PROCESS | 2325 | 16:30:47 INFO - TEST-INFO | 2325: exit 0 16:30:47 INFO - TEST-OK | kraken | took 62078ms 16:30:47 INFO - SUITE-END | took 1175s 16:30:47 INFO - Completed test suite (00:19:35) 16:30:47 INFO - javascript benchmark 16:30:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7394.979159895692, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.9689103108967, 3373.3421982713367, 3405.0976491229812, 3412.8634854605816, 3432.399330117229, 5310.36275646922, 5322.148456247001, 5529.447055294469, 5753.5684823638185, 5755.482006619403, 5623.859403514914, 5640.5487560995125, 5652.321721393439, 5732.942670573293, 5781.248437703092, 5114.795408183678, 5232.372115346164, 5235.293235413227, 5277.0, 5330.680159190461, 5311.840644780663, 5333.333333333333, 5353.812616558421, 5413.593980451462, 5420.051490989081, 5137.434882162959, 5199.974000130013, 5218.6868787872845, 5235.842924712227, 5265.394479634814, 5218.1129208034345, 5228.398734145584, 5252.684838909678, 5263.44733802949, 5278.577713782888, 5182.4299327073995, 5209.218617207449, 5225.033368826741, 5232.607554433433, 5264.236685680555, 4842.806287748486, 5505.587080968903, 5561.416051314634, 5621.494065534081, 5645.407232240637, 6132.785352512686, 6166.506679465632, 6225.7509699611965, 6225.750969961241, 6241.96879015602, 6218.657973811439, 6253.6247825130185, 6258.843528911768, 6268.87462250757, 6285.245770507523, 5679.744411501492, 5751.769929202785, 5791.0734282514595, 5800.303963524319, 5810.738516766755, 6245.843853903689, 6248.31268560458, 6268.843278918063, 6273.215848018997, 6298.9291820390645, 4987.351644286219, 6057.909131363033, 6139.907901381483, 6141.170941922784, 6159.199304090529, 6120.632762034292, 6127.403330890726, 6133.26400831903, 6155.076738489262, 6172.8765424691255, 6146.7233974471255, 6188.535859810532, 6196.845078872974, 6198.349173336825, 6234.407731265523, 6168.9691551541955, 6217.595856269328, 6229.221347331583, 6239.469645080128, 6262.436380725757, 4128.855490057834, 4814.735189564538, 4885.6335774817035, 4909.140900342426, 4980.950190498049, 4373.759443230653, 4426.9778651106535, 4449.532799056053, 4451.465824101128, 4455.509893911667, 5622.465865742748, 5698.914516282259, 5748.683822389725, 5752.252207213036, 5763.769449222068, 6119.632822030692, 6185.350538193425, 6203.627782333075, 6218.409251121227, 6235.251769787655, 5679.176519404663, 5727.3699893011735, 5744.454276843693, 5753.0, 5761.510271626875, 5433.049216387117, 5434.103372943499, 5436.130219164915, 5442.156465747816, 5463.836084917459, 6171.475424588871, 6228.626282423069, 6231.0, 6256.5307601930945, 6375.139356186948, 6201.976673848764, 6247.125402443752, 6247.281562620356, 6271.592346497463, 6277.1525844010475, 6317.778877739257, 6326.905096423466, 6328.0, 6330.778422755366, 6332.556721029484, 5024.246363045572, 5453.945460545344, 6018.969905150446, 6074.483668888017, 6147.508199343953, 6257.43683068526, 6269.0, 6270.122182894307, 6287.842803929939, 6301.149344838388, 6079.0, 6117.388261173847, 6117.510599152146, 6126.632402055891, 6141.969290153521, 5901.852453688521, 5989.071693887455, 6097.176881120992, 6115.694215289307, 6123.173371594778, 4911.656184067081, 5790.913136302875, 5922.141289512911, 5972.940270597239, 5986.281646202484, 4909.558139767295, 5612.849365879903, 6073.423024812636, 6092.573519853568, 6102.9694851525455, 5135.15269980449, 5837.912431313619, 5843.444872737083, 5868.2077919482035, 5990.970045149921], "value": 5648.107444741216, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.76805553473173, 519.5739493615234, 533.6344603946297, 543.3696911582565, 560.8950367775484, 38170.80914595426, 39793.60206397935, 39866.402003969924, 39915.800420997926, 39925.401118983165, 41280.79359603198, 41873.162536749245, 41933.79033104838, 42017.0, 42084.158316833644, 9646.324757266977, 9787.119159275664, 9841.36031157976, 9850.0, 9870.851937220947, 9689.418634881895, 9802.264830137732, 9814.411135331902, 9824.459654718987, 9838.606455741761, 9798.461084640341, 9802.852957205647, 9823.459709715999, 9829.115379615832, 9841.36031157976, 9759.463229522373, 9800.264980126447, 9811.460369679664, 9814.950925245364, 9841.852372214422, 8337.0, 9725.56234969428, 9826.508674566243, 9840.065193806577, 9880.802383952352, 39352.80323598392, 39372.40941385881, 39502.802485987675, 39736.80131599324, 39744.60255397438, 2645.8015648826413, 2687.690915544718, 3392.4402473591826, 3548.8580456781697, 3559.1280136366495, 23014.194503192477, 25715.48569028609, 29367.2658183545, 29507.0, 29566.40867182666, 2851.17315978366, 2862.6974726499393, 3041.2548925513393, 3154.794938329001, 3165.2403423178503, 516685.0, 517215.0, 522798.3860080713, 524874.0, 526101.0, 62787.744245114845, 62952.68523657444, 63343.0, 63352.049719254246, 63474.682626586575, 34559.4816077759, 34882.47676284858, 35169.82415087908, 35734.0, 67266.66366668136, 16275.430359937582, 16418.425355112515, 16428.6714265714, 16474.917625411796, 16477.83522164763, 12528.686782830502, 12604.306763128116, 12620.432080556397, 12647.557335493213, 12669.429875655618, 19407.29581834759, 19438.653886434204, 19536.0, 19607.705884411746, 19734.0, 7820.687172513036, 7910.485818421784, 7927.643256053491, 7937.841243175104, 7984.880226796603, 868.6395146014333, 973.2846357926918, 995.5420506566916, 1006.8942761010135, 1091.7652704668535, 29258.561121583192, 30414.087577372273, 30417.69582304193, 30489.39021219563, 30652.0, 1036.6941752183125, 1037.9792404151874, 1077.3966578715945, 1086.266769930294, 1159.652104368686, 13176.341182940814, 15886.0, 15917.761233581505, 16184.433544825875, 16318.673626527403, 666307.0, 675366.6231668908, 676321.0, 676639.6168019129, 679321.0, 58417.0, 58557.70721146367, 59018.0, 59202.0, 59258.40741592615, 1339.80572816941, 1369.4453746232791, 1401.6706074072788, 1406.627243780399, 1419.3541938417995, 26898.175691552104, 27705.86147069252, 30265.394692106474, 30416.239594010327, 30719.846400768747, 46496.53503464922, 46700.7664961673, 46763.0, 46801.53198468108, 46826.53173468222, 580890.0, 583794.0, 584095.0, 584329.0, 585916.0, 26941.0, 26953.865230674506, 27082.05212817542, 27305.863470682518, 27324.453510930067, 47440.80022795116, 47546.52453475559, 48233.0, 48851.51148488469, 49318.0, 38796.0, 38881.6111838878, 39188.0, 39517.9670005009, 39817.40273895952, 39342.0, 39449.0, 39717.80141099276, 40781.796091020544, 41109.0, 38758.4186237201, 40249.79250622486, 40715.38926916158, 40715.79642101771, 40875.38686919758, 30736.692633073384, 34620.0, 35097.29805403929, 35097.64902350944, 35130.29739405249, 22195.223167189717, 25954.610680840182, 26019.479610408063, 26043.479130417665, 26300.74554776753], "value": 19882.97212652747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.7951916205666, 378.4417983474375, 380.916198436344, 387.1521368203636, 399.44876071021986, 19246.711299330505, 21870.23454179104, 21916.0, 21982.01080951357, 22217.666734998948, 20806.375808725723, 20910.26814061508, 21557.46106347342, 21740.673889891663, 21818.89090554545, 4817.445993710724, 5106.846794596168, 5717.942820571803, 5896.852578685546, 6008.579399442041, 5046.369203849519, 5996.100584912277, 6062.666553339564, 6137.539684523656, 6210.4100110489435, 6017.398260173992, 6106.847328816771, 6122.8469288267925, 6186.443220110189, 6242.563020588561, 6753.696083676246, 6825.215100263459, 6834.624095674736, 6834.794956151323, 6838.521303508756, 5850.297964244318, 5903.822885313448, 5941.494972927307, 6034.728437220336, 6059.878922399326, 10926.781464370748, 13644.454221831114, 13652.795208071886, 16665.25006374716, 26493.0, 2460.3849037740565, 2609.595512695535, 2990.6112205413156, 2996.9061292628253, 3106.130283520618, 9967.302288839785, 10088.747781305452, 10103.359173126644, 10179.18566514677, 10570.418626975514, 1849.7502837116951, 1877.4836919847014, 1898.8575856810655, 1941.3399444188901, 1954.9706754398696, 128432.71567284394, 130761.34619326938, 132302.0, 133091.0, 136039.0, 9494.762630934281, 10115.949420252999, 10198.592056317813, 10250.794984100276, 10362.326448780805, 12360.0, 12505.624831255076, 12512.186707863959, 12524.499020039279, 12536.749265014649, 7094.184168820549, 7699.9990001299475, 8137.226963438582, 8221.17788221185, 8290.668373265122, 8472.068072512018, 8491.405601607828, 8491.702790402307, 8539.249453861943, 8624.52565108925, 3888.9027774305305, 3961.7226794124135, 3968.325384684553, 3977.840886364571, 4005.0788318686864, 1831.84429323509, 1888.4806678163616, 1968.6456437841325, 2045.7136000959877, 2048.2114385961213, 852.6461518469889, 859.8409294280578, 876.6712482818942, 891.2825175733597, 947.7914858731068, 20943.68584471234, 22158.22446214407, 22307.219247326266, 22321.441963950707, 22492.88753556222, 930.692871352452, 941.4633658814547, 978.6966040527459, 986.5264672957021, 996.1831298335295, 11471.483783229774, 11572.148456247129, 11740.178187526963, 11752.88247117535, 11764.705882352839, 601365.0, 609235.0, 615870.0, 616496.0, 631201.0, 104481.0, 131888.68111318763, 132804.0, 133254.33372833073, 138717.0, 938.3712912348743, 951.8381875081253, 988.5600907596195, 988.5749127874939, 1023.370627064365, 17823.0, 19601.215951361784, 19713.605727885075, 19876.801231988073, 19909.800901991373, 54881.72559137179, 55263.17105243344, 57242.0, 57854.0, 58416.415835842796, 74132.62933685297, 75853.24146758682, 81873.18126818656, 89253.0, 92072.53963730138, 7571.280728330799, 7930.127685954656, 8272.255497005299, 8373.330133589176, 8568.828623427622, 12690.809637855255, 13297.734045319234, 13444.865551344752, 13738.587842364745, 13789.724205516035, 11522.942385288019, 11613.76772464563, 11863.940680296544, 11914.0, 11930.22453540517, 9764.414135151914, 10986.340819550853, 11419.371934543347, 11526.308421494738, 11831.11266654987, 9807.705768826947, 11692.35692036946, 11824.1030947415, 11923.880761192277, 12164.99277619183, 6617.470602351704, 8139.4709343892455, 8293.046299675374, 8436.915630843612, 8518.74443766688, 9797.85303220438, 11803.586874459354, 11876.643700688994, 11927.224730392496, 11949.761004779682], "value": 9630.004841519194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.3911265937295, 1542.637480192155, 1777.8621682123444, 1877.601187115599, 1902.2486117983406, 7983.562958667438, 8460.703875364363, 8631.223189912907, 9038.638454461821, 9072.228860546853, 7743.341815945637, 8647.524386158759, 8761.912380876189, 8902.955485222565, 8910.376273660846, 2067.824234940032, 2171.2183613899024, 2333.0434521846046, 2338.766123387656, 2344.097522453859, 2299.1837897546297, 2324.1400681747778, 2332.7667233276707, 2351.47091904321, 2358.917437889674, 2315.2822624986225, 2336.894839732208, 2346.2609278077434, 2347.084636991575, 2350.518143780521, 2295.540891821642, 2313.1556981701556, 2314.9768502315014, 2325.1745630301252, 2326.232343326698, 2319.617263151578, 2330.5222429401933, 2330.7669233076726, 2333.7432882382923, 2338.088145623208, 8998.920129584492, 9009.009009009003, 9065.728028159167, 9070.86393704095, 9091.772705682344, 5154.098032844275, 5253.212018197301, 5319.20211968204, 5320.654157479751, 5328.653637513588, 3422.8801991930163, 3472.826358682056, 3541.8760343388117, 3543.8228088595724, 3572.2319701264355, 928.5310917986441, 1012.7569383348017, 1058.3544038136731, 1062.8618279623677, 1076.9138468922467, 9666.0, 11558.479868405859, 11680.88319116815, 11737.237079589799, 11757.823632645432, 4395.7142785718825, 4536.6143877770755, 4751.40607424072, 4754.001659651442, 4764.309175169581, 9222.170004699608, 9812.950935245279, 9891.752706182404, 9981.401115933068, 10010.54952527138, 4345.174416860849, 5310.7610157543, 5312.07038768214, 5316.282301889273, 5317.8404647860625, 4351.608355248043, 4701.600363969101, 4816.325714399987, 4863.294822250754, 4864.781084851119, 3205.6954589313978, 3318.684724951174, 3366.9326613467592, 3927.823247953792, 4027.838886444509, 1068.887766784492, 1073.9355638661705, 1083.2309060567086, 1084.5770149641646, 1293.6507143071506, 782.9804254893673, 922.9077092290718, 936.0779632062413, 944.7071407863584, 947.6114792934864, 5631.746571404296, 5882.088276317178, 5938.851528711817, 6033.879322413615, 6114.174586430864, 898.9280857531381, 928.6981730937472, 929.8098434004485, 961.3654987708079, 970.5292932927665, 3500.579930408367, 3636.5999740028574, 3682.815859207029, 3825.407061905409, 3880.4567360569545, 8742.956285218534, 10932.289401188897, 10981.890181098246, 11016.449177541092, 11025.952534509208, 10493.737656558647, 10620.787584248272, 10954.452277386099, 10992.725181870517, 11003.22977391591, 8176.427650064438, 8256.99999999988, 8363.749087527383, 8396.622152003052, 8398.286145677686, 5889.234399528034, 5979.880402392015, 6048.758049677964, 6133.877322453615, 6170.598911070766, 9939.850902236616, 10075.09324160829, 10192.0, 10219.131373833308, 10257.33327333721, 9913.65302214419, 10218.335808172445, 10358.689239322832, 10404.803447603623, 10441.477926103817, 7075.186353569406, 7458.552486850806, 7644.579548124907, 7790.14308426084, 7794.88307675374, 8352.248297653241, 8585.399022068395, 8647.135286471303, 8653.653853845775, 8684.478931264144, 7470.626468676436, 7569.621518923922, 7615.4669173157345, 7673.271039251262, 7705.961470192613, 7392.191012072242, 7491.737789177353, 7537.283958023979, 7608.125065617525, 7665.731699390494, 6985.895211571728, 7145.0, 7146.285371462812, 7165.964170179116, 7186.676599552928, 4929.0, 4956.306117143675, 5039.470855560114, 5137.17805151174, 5176.938727560916, 4509.413776209072, 5038.874028149326, 5095.490450954875, 5189.92215116766, 5279.52484276417], "value": 4563.0989153979435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.0189380725028, 306.9877204911803, 314.33047608593694, 316.89088191330353, 317.1405491119067, 5004.724740139291, 5320.627556071081, 5410.269613602162, 5560.888782224358, 5633.408492108331, 5083.669561478501, 5432.809851655193, 5571.749271282792, 5598.748056337465, 5614.522765564931, 4215.873523794291, 4231.767252801094, 4243.363495475688, 4257.127288905767, 4271.359296105594, 4041.8989525261954, 4189.434426352434, 4189.811458484361, 4234.1320029394055, 4258.318669012959, 3477.8087205203706, 3640.5449318835144, 4184.51878034027, 4185.430463576159, 4256.169647184619, 4192.979035104835, 4193.140406216734, 4210.410542524049, 4218.0, 4223.155368926227, 3021.3353062326255, 3201.551782750417, 3281.261716113879, 3429.7995701504524, 3648.5986541480415, 4753.098760495829, 5202.583793296565, 5264.6838290427395, 5392.352917649868, 5445.237666726662, 4252.383404406344, 4852.708837469763, 4856.101621200054, 4863.537963893425, 4869.586085182764, 4738.815296175956, 5302.708351040691, 5652.943470565283, 5915.816836632647, 5934.881302373971, 3968.8214030368413, 3980.6218409251073, 4002.4396584478195, 4012.538558065831, 4023.807025171289, 6240.906386404207, 6261.624302541816, 6293.213348331458, 6338.239411270677, 6377.681115944184, 3609.9639003609627, 3874.9418758718643, 4062.532808726975, 4084.6732261419015, 4115.135821477524, 3915.0997463358303, 4128.6284234418445, 4140.8136633851545, 4145.927036481759, 4149.419081328617, 4794.880127996758, 4806.663533552688, 4864.270359446112, 4887.804487820518, 4890.24201248807, 5606.635568688022, 5878.823635290948, 5905.320888097838, 5968.895754285384, 6030.487408570233, 1322.127395918689, 1391.373881753215, 1392.4987004678308, 1401.0, 1416.9302176856409, 758.6510205305511, 791.8614242507538, 795.9005124359455, 835.5655059369094, 840.4075127035426, 4301.763403012802, 4441.578050085237, 4453.732776033448, 4653.37132003796, 4701.811927522861, 4691.601213896789, 4790.4969978152485, 5115.87931361869, 5120.564751996048, 5140.1004824155625, 4481.865544033684, 4574.565416285448, 4585.380973568593, 4589.751021999196, 4630.4443466784205, 2548.0444833187553, 2863.656361236665, 3104.4943202547197, 3124.625044994615, 3197.2894501441615, 5426.802145131228, 6183.598066125777, 6251.374862513712, 6334.954732469091, 6372.745090196433, 4376.321670141133, 5533.363663178786, 6251.218402755193, 6262.3111457739615, 6341.746330146835, 4737.0525894820485, 5812.360640329476, 5884.470397664317, 5967.701614919237, 6202.34875338092, 4981.900361992667, 5907.438793314628, 6038.486728628116, 6149.846253843511, 6156.5998210116195, 5179.45615710345, 5549.417311182429, 5583.548277447811, 5665.1871401152885, 5958.553108516792, 5605.803796867253, 6310.432061114522, 6377.17096777416, 6378.45783108441, 6412.6793660317735, 3856.2094770571402, 3951.881443556698, 3992.843864313238, 3999.7200195986, 4044.41356003372, 5023.6985780853265, 6135.53983451234, 6147.784827531015, 6162.414570615784, 6188.566800324114, 5765.3946335634155, 5945.64326140433, 5977.193078934462, 5995.790147344822, 5996.160537524663, 4883.974365383309, 5934.762609495572, 5965.164876917148, 5978.671173085522, 5985.221921150222, 4196.349565817303, 5014.598832093497, 5099.847004589868, 5735.0, 5898.8230353089475, 5198.506141916512, 5910.995130827683, 5983.371745966785, 5994.340622531431, 6030.190942717079, 4511.0, 5859.648421094748, 5863.149843272617, 5886.411358864079, 5898.705064746831], "value": 4197.396865247987, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.2870334763347, 678.4199509419446, 689.8804339219259, 694.7221111555381, 856.9528675922826, 15569.065856048632, 15755.60610984726, 16073.75889361659, 16209.108499032549, 16240.837591624095, 11894.167408281402, 14688.118712877234, 14706.558803235894, 14776.85223147771, 14868.851311486882, 849.9830003399928, 856.7858035491128, 864.4510735682824, 867.301822033263, 867.7743786615463, 676.1210426445601, 832.4672209785712, 859.4113032572676, 862.3963225741975, 870.6430363550913, 914.4513292024799, 918.274563095154, 918.7151982885327, 922.4926290540176, 922.8754118194058, 762.393896852004, 808.8988876390481, 911.098012967161, 913.7258822353334, 917.0233701108299, 672.0456951129408, 678.670844640354, 679.8232459560501, 681.3731367142241, 682.6654939079815, 14917.925410372878, 15073.547793566211, 15236.695266094615, 15276.465323713617, 15288.770668439982, 14981.10113393189, 14989.325480353411, 15003.474878379204, 15209.771853422208, 15237.314320855588, 11995.760084798256, 12143.167656861364, 12147.331896745676, 12547.686307842285, 12582.937085314516, 3778.614607075617, 3837.1558257183374, 3893.201893611803, 4012.779297138656, 4049.1091959768805, 79337.60331198365, 116793.0, 118017.0, 118246.40876795647, 121811.0, 11291.49188286529, 11349.319040857576, 11469.037066382714, 11559.36423496699, 11702.941485292518, 24096.27711168668, 24147.0, 24410.633840492406, 24436.633450498255, 24534.631980520306, 6993.0, 8201.0, 8272.586370681442, 8388.748337549883, 8526.87209691855, 3774.471573979645, 4550.954490455119, 4614.607758340511, 4663.393758811333, 4761.2382018876815, 3542.769719968194, 3606.2787442511603, 3663.2307215484516, 3665.8350374233223, 3682.5212722345923, 633.4773811605444, 641.6118248459708, 648.8475208326134, 649.389573800626, 661.5898143151277, 525.3380740267214, 593.3858456497504, 673.1652750589234, 707.2030859771065, 709.9680514376865, 9355.859662104938, 9465.810683786285, 9579.616815327308, 9661.227101831837, 9704.514774261259, 526.5918912842501, 608.8219295662907, 635.4566845347254, 635.5499549237715, 663.5355251323996, 4514.367988481616, 4638.582527572535, 4650.651201159926, 4707.388039554836, 4719.4100737407825, 151899.0, 159043.0, 164656.0, 172373.0, 181839.0, 149600.0, 151123.0, 151920.0, 154138.22930885275, 154507.0, 122279.0, 123594.73039522071, 125033.0, 126017.99999999817, 126840.0, 7289.4168466523615, 8874.467531948103, 8934.374593778373, 8991.39758134896, 9127.58925848325, 63405.365946339945, 65543.67228163828, 70253.64873175601, 71020.28979710344, 72167.27832721816, 103579.482102589, 103982.48008759908, 104657.47671261596, 105248.0, 106833.0, 12007.339596322287, 12017.699557511132, 12074.818877717016, 12634.684132896751, 12695.746085078432, 13211.933940330237, 13335.799963000369, 13374.398152082986, 13502.459901603826, 13770.93114534421, 12128.762010640805, 12220.694482638006, 12401.93799030999, 12684.97474120896, 12810.0, 12291.201071930296, 12435.689107772016, 12455.314957677414, 12858.164219325714, 12860.935695321463, 12009.519619215484, 12253.69365765863, 12450.875491244971, 12626.179298345578, 12685.619431417072, 10501.9474902625, 10673.41296228715, 10679.751767932017, 10825.512851921525, 11120.833187502356, 8433.578321083798, 9390.342676012613, 9571.760705982128, 9803.656872009446, 10708.678739637824], "value": 7894.523917681885, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1643.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 109.0, 120.0, 116.0, 117.0, 115.0, 126.0, 168.0, 124.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 154.0, 193.0, 120.0, 131.0, 130.0, 110.0, 158.0, 141.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [272.0, 250.0, 228.0, 199.0, 196.0, 204.0, 201.0, 201.0, 194.0, 225.0], "value": 217.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 108.0, 69.0, 166.0, 53.0, 67.0, 84.0, 81.0, 153.0, 74.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 112.0, 172.0, 102.0, 72.0, 63.0, 132.0, 103.0, 103.0, 147.0], "value": 108.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 136.0, 87.0, 88.0, 86.0, 122.0, 95.0, 136.0, 130.0], "value": 106.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 97.0, 96.0, 96.0, 116.0, 90.0, 146.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 95.0, 147.0, 145.0, 91.0, 149.0, 148.0, 83.0, 139.0, 142.0], "value": 128.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 57.0, 61.0, 92.0, 87.0, 89.0, 50.0, 50.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 83.0, 79.0, 80.0, 81.0, 72.0, 79.0, 75.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 87.0, 82.0, 83.0, 89.0, 87.0, 84.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 136.0, 134.0, 140.0, 164.0, 143.0, 160.0, 180.0, 162.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 157.0, 198.0, 231.0, 219.0, 169.0, 171.0, 169.0, 168.0, 170.0], "value": 181.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 65.0, 67.0, 67.0, 68.0, 66.0, 68.0, 68.0], "value": 67.4, "unit": "score"}]}]} 16:30:47 INFO - Return code: 0 16:30:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:30:47 ERROR - # TBPL SUCCESS # 16:30:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:47 INFO - 2016-08-11 16:30:47.748 screenresolution[2331:337290] starting screenresolution argv=screenresolution get 16:30:47 INFO - 2016-08-11 16:30:47.751 screenresolution[2331:337290] Display 0: 1600x1200x32@60 16:30:47 INFO - 2016-08-11 16:30:47.757 screenresolution[2332:337293] starting screenresolution argv=screenresolution list 16:30:47 INFO - Available Modes on Display 0 16:30:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:47 INFO - Intel Iris: 16:30:47 INFO - Chipset Model: Intel Iris 16:30:47 INFO - Type: GPU 16:30:47 INFO - Bus: Built-In 16:30:47 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:47 INFO - Vendor: Intel (0x8086) 16:30:47 INFO - Device ID: 0x0a2e 16:30:47 INFO - Revision ID: 0x0009 16:30:47 INFO - Displays: 16:30:47 INFO - Display: 16:30:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:47 INFO - Display Serial Number: 1600x1200 60 16:30:47 INFO - Main Display: Yes 16:30:47 INFO - Mirror: Off 16:30:47 INFO - Online: Yes 16:30:47 INFO - Rotation: Supported 16:30:47 INFO - Return code: 0 16:30:47 INFO - Running post-action listener: _resource_record_post_action 16:30:47 INFO - [mozharness: 2016-08-11 23:30:47.997195Z] Finished run-tests step (success) 16:30:47 INFO - Running post-run listener: _resource_record_post_run 16:30:48 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 6341120; Write bytes: 704087040; Read time: 53; Write time: 2010 16:30:48 INFO - TinderboxPrint: CPU usage
54.4% 16:30:48 INFO - TinderboxPrint: I/O read bytes / time
6,341,120 / 53 16:30:48 INFO - TinderboxPrint: I/O write bytes / time
704,087,040 / 2,010 16:30:48 INFO - TinderboxPrint: CPU idle
2,210.1 (45.6%) 16:30:48 INFO - TinderboxPrint: CPU system
976.5 (20.2%) 16:30:48 INFO - TinderboxPrint: CPU user
1,655.0 (34.2%) 16:30:48 INFO - TinderboxPrint: Swap in / out
417,435,648 / 0 16:30:48 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399331840; Write bytes: 395360256; Read time: 29475; Write time: 877 16:30:48 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 6341120; Write bytes: 294837248; Read time: 53; Write time: 1094 16:30:48 INFO - Running post-run listener: _upload_blobber_files 16:30:48 INFO - Blob upload gear active. 16:30:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:30:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:30:48 INFO - (blobuploader) - INFO - Open directory for files ... 16:30:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:30:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:30:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:49 INFO - (blobuploader) - INFO - Done attempting. 16:30:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:30:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:30:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:51 INFO - (blobuploader) - INFO - Done attempting. 16:30:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:30:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:30:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:53 INFO - (blobuploader) - INFO - Done attempting. 16:30:53 INFO - (blobuploader) - INFO - Iteration through files over. 16:30:53 INFO - Return code: 0 16:30:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:30:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:30:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6cf22472ce8012ad14c5b86349957a454bde3ebedc108adc059783415f809231dab7786914bee07e4c45fa991e7347e59fa6adefafbf47a1329c99104a45279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80a7c724ae921fab9be887e8f32ee1818b198e48e011676d90428d2f3c8cff731098fa16734c653983a8506e3ae8750eeb6aaa7c78026c5f9714adf7a24ef5dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c636cacc9dc85357798e52a7aa3d0e761a7624be7bff7a1e8799692e62292342a19a898010b206ee84bf4256f5d388dfdf74ee6503808cf558b64b345c61bd46"} 16:30:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:30:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:30:53 INFO - Contents: 16:30:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6cf22472ce8012ad14c5b86349957a454bde3ebedc108adc059783415f809231dab7786914bee07e4c45fa991e7347e59fa6adefafbf47a1329c99104a45279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80a7c724ae921fab9be887e8f32ee1818b198e48e011676d90428d2f3c8cff731098fa16734c653983a8506e3ae8750eeb6aaa7c78026c5f9714adf7a24ef5dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c636cacc9dc85357798e52a7aa3d0e761a7624be7bff7a1e8799692e62292342a19a898010b206ee84bf4256f5d388dfdf74ee6503808cf558b64b345c61bd46"} 16:30:53 INFO - Running post-run listener: copy_logs_to_upload_dir 16:30:53 INFO - Copying logs to upload dir... 16:30:53 INFO - mkdir: /builds/slave/test/build/upload/logs 16:30:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.659369 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-08-11 16:30:53.655069) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 16:30:53.659103) ========= 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.InMsYG25wK/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.kbKgMxfOK6/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6cf22472ce8012ad14c5b86349957a454bde3ebedc108adc059783415f809231dab7786914bee07e4c45fa991e7347e59fa6adefafbf47a1329c99104a45279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80a7c724ae921fab9be887e8f32ee1818b198e48e011676d90428d2f3c8cff731098fa16734c653983a8506e3ae8750eeb6aaa7c78026c5f9714adf7a24ef5dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c636cacc9dc85357798e52a7aa3d0e761a7624be7bff7a1e8799692e62292342a19a898010b206ee84bf4256f5d388dfdf74ee6503808cf558b64b345c61bd46"} build_url:https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6cf22472ce8012ad14c5b86349957a454bde3ebedc108adc059783415f809231dab7786914bee07e4c45fa991e7347e59fa6adefafbf47a1329c99104a45279", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80a7c724ae921fab9be887e8f32ee1818b198e48e011676d90428d2f3c8cff731098fa16734c653983a8506e3ae8750eeb6aaa7c78026c5f9714adf7a24ef5dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c636cacc9dc85357798e52a7aa3d0e761a7624be7bff7a1e8799692e62292342a19a898010b206ee84bf4256f5d388dfdf74ee6503808cf558b64b345c61bd46"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 16:30:53.692086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:30:53.692423) ========= 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.InMsYG25wK/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.kbKgMxfOK6/Listeners TMPDIR=/var/folders/mz/ztxs6sw15fq60c9skn1bw95r00000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:30:53.741686) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 16:30:53.741991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 16:30:53.742378) ========= ========= Total master_lag: 0.18 =========