builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0206 starttime: 1472227057.56 results: success (0) buildid: 20160826062717 builduid: 97bf17da105740beb4803c17f5fa846e revision: 7852b1545a876a191139739e18e297e4974c4db7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.559079) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.559536) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.559818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.584462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.584814) ========= 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.HNlToO1u4C/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.RlsYrENLUv/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-26 08:57:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 170M=0s 2016-08-26 08:57:37 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.740482) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.740805) ========= 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.HNlToO1u4C/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.RlsYrENLUv/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.026206 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:37.788873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 08:57:37.789240) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7852b1545a876a191139739e18e297e4974c4db7 --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 7852b1545a876a191139739e18e297e4974c4db7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HNlToO1u4C/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.RlsYrENLUv/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-26 08:57:37,875 truncating revision to first 12 chars 2016-08-26 08:57:37,876 Setting DEBUG logging. 2016-08-26 08:57:37,876 attempt 1/10 2016-08-26 08:57:37,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7852b1545a87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 08:57:38,939 unpacking tar archive at: mozilla-central-7852b1545a87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.316545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 08:57:39.125458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:39.125784) ========= script_repo_revision: 7852b1545a876a191139739e18e297e4974c4db7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:39.126192) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:39.126463) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 08:57:39.143410) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-08-26 08:57:39.143686) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HNlToO1u4C/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.RlsYrENLUv/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:57:39 INFO - MultiFileLogger online at 20160826 08:57:39 in /builds/slave/test 08:57:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:57:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:57:39 INFO - {'append_to_log': False, 08:57:39 INFO - 'base_work_dir': '/builds/slave/test', 08:57:39 INFO - 'blob_upload_branch': 'Firefox', 08:57:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:57:39 INFO - 'branch': 'Firefox', 08:57:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:57:39 INFO - 'config_files': ('talos/mac_config.py',), 08:57:39 INFO - 'default_actions': ('clobber', 08:57:39 INFO - 'read-buildbot-config', 08:57:39 INFO - 'download-and-extract', 08:57:39 INFO - 'populate-webroot', 08:57:39 INFO - 'create-virtualenv', 08:57:39 INFO - 'install', 08:57:39 INFO - 'run-tests'), 08:57:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:57:39 INFO - 'download_minidump_stackwalk': True, 08:57:39 INFO - 'download_symbols': 'ondemand', 08:57:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:57:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:57:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:57:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:57:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:57:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:57:39 INFO - 'installer_path': 'installer.exe', 08:57:39 INFO - 'log_level': 'info', 08:57:39 INFO - 'log_name': 'talos', 08:57:39 INFO - 'log_to_console': True, 08:57:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:57:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:57:39 INFO - 'opt_config_files': (), 08:57:39 INFO - 'pip_index': False, 08:57:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:39 INFO - 'cmd': ('bash', 08:57:39 INFO - '-c', 08:57:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:39 INFO - 'enabled': True, 08:57:39 INFO - 'halt_on_failure': False, 08:57:39 INFO - 'name': 'check_screen_resolution'},), 08:57:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:39 INFO - 'cmd': ('bash', 08:57:39 INFO - '-c', 08:57:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:39 INFO - 'enabled': True, 08:57:39 INFO - 'halt_on_failure': False, 08:57:39 INFO - 'name': 'check_screen_resolution'},), 08:57:39 INFO - 'run_cmd_checks_enabled': True, 08:57:39 INFO - 'sps_profile': False, 08:57:39 INFO - 'sps_profile_interval': 0, 08:57:39 INFO - 'suite': 'dromaeojs', 08:57:39 INFO - 'system_bits': '32', 08:57:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:57:39 INFO - 'title': 't-yosemite-r7-0206', 08:57:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:57:39 INFO - 'use_talos_json': True, 08:57:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:57:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:57:39 INFO - 'work_dir': 'build'} 08:57:39 INFO - [mozharness: 2016-08-26 15:57:39.338700Z] Running clobber step. 08:57:39 INFO - Running pre-action listener: _resource_record_pre_action 08:57:39 INFO - Running main action method: clobber 08:57:39 INFO - rmtree: /builds/slave/test/build 08:57:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:57:41 INFO - Running post-action listener: _resource_record_post_action 08:57:41 INFO - [mozharness: 2016-08-26 15:57:41.325348Z] Finished clobber step (success) 08:57:41 INFO - [mozharness: 2016-08-26 15:57:41.325515Z] Running read-buildbot-config step. 08:57:41 INFO - Running pre-action listener: _resource_record_pre_action 08:57:41 INFO - Running main action method: read_buildbot_config 08:57:41 INFO - Using buildbot properties: 08:57:41 INFO - { 08:57:41 INFO - "project": "", 08:57:41 INFO - "product": "firefox", 08:57:41 INFO - "script_repo_revision": "production", 08:57:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:57:41 INFO - "repository": "", 08:57:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:57:41 INFO - "buildid": "20160826062717", 08:57:41 INFO - "pgo_build": "False", 08:57:41 INFO - "basedir": "/builds/slave/test", 08:57:41 INFO - "buildnumber": 16, 08:57:41 INFO - "slavename": "t-yosemite-r7-0206", 08:57:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:57:41 INFO - "platform": "yosemite_r7", 08:57:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:57:41 INFO - "branch": "mozilla-central", 08:57:41 INFO - "repo_path": "mozilla-central", 08:57:41 INFO - "revision": "7852b1545a876a191139739e18e297e4974c4db7", 08:57:41 INFO - "stage_platform": "macosx64", 08:57:41 INFO - "builduid": "97bf17da105740beb4803c17f5fa846e", 08:57:41 INFO - "slavebuilddir": "test" 08:57:41 INFO - } 08:57:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:57:41 INFO - Running post-action listener: _resource_record_post_action 08:57:41 INFO - [mozharness: 2016-08-26 15:57:41.328975Z] Finished read-buildbot-config step (success) 08:57:41 INFO - [mozharness: 2016-08-26 15:57:41.329092Z] Running download-and-extract step. 08:57:41 INFO - Running pre-action listener: _resource_record_pre_action 08:57:41 INFO - Running main action method: download_and_extract 08:57:41 INFO - mkdir: /builds/slave/test/build/tests 08:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:41 INFO - trying https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:57:41 INFO - Downloading https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:42 INFO - Downloaded 1199 bytes. 08:57:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:57:42 INFO - Using the following test package requirements: 08:57:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:57:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:57:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:57:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'jsshell-mac.zip'], 08:57:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:57:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:57:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:57:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:57:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:57:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:57:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:57:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:57:42 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')]} 08:57:42 INFO - trying https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:57:42 INFO - Downloading https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:43 INFO - Downloaded 16367263 bytes. 08:57:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:57:50 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 08:57: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')]} 08:57:50 INFO - trying https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:57:50 INFO - Downloading https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:51 INFO - Downloaded 16367263 bytes. 08:57:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:58 INFO - trying https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:57:58 INFO - Downloading https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:57:59 INFO - Downloaded 14413998 bytes. 08:57:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:59 INFO - trying https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:57:59 INFO - Downloading https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:57:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/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 08:58:03 INFO - Downloaded 111776629 bytes. 08:58:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:58:03 INFO - mkdir: /builds/slave/test/properties 08:58:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:58:03 INFO - Writing to file /builds/slave/test/properties/build_url 08:58:03 INFO - Contents: 08:58:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:58:04 INFO - Running post-action listener: _resource_record_post_action 08:58:04 INFO - Running post-action listener: set_extra_try_arguments 08:58:04 INFO - [mozharness: 2016-08-26 15:58:04.200632Z] Finished download-and-extract step (success) 08:58:04 INFO - [mozharness: 2016-08-26 15:58:04.200779Z] Running populate-webroot step. 08:58:04 INFO - Running pre-action listener: _resource_record_pre_action 08:58:04 INFO - Running main action method: populate_webroot 08:58:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:58:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:58:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:58:04 INFO - '--tppagecycles', 08:58:04 INFO - '7'], 08:58:04 INFO - 'tests': ['tsvgm']}}, 08:58:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:58:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:58:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:58:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:58:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['damp', 'tps']}, 08:58:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'tests': ['damp', 'tps']}, 08:58:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['dromaeo_dom']}, 08:58:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:58:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['basic_compositor_video']}, 08:58:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:58:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['a11yr', 08:58:04 INFO - 'ts_paint', 08:58:04 INFO - 'tpaint', 08:58:04 INFO - 'sessionrestore', 08:58:04 INFO - 'sessionrestore_no_auto_restore', 08:58:04 INFO - 'tabpaint']}, 08:58:04 INFO - 'other-e10s': {'tests': ['a11yr', 08:58:04 INFO - 'ts_paint', 08:58:04 INFO - 'tpaint', 08:58:04 INFO - 'sessionrestore', 08:58:04 INFO - 'sessionrestore_no_auto_restore', 08:58:04 INFO - 'tabpaint']}, 08:58:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:58:04 INFO - 'ts_paint', 08:58:04 INFO - 'tpaint', 08:58:04 INFO - 'sessionrestore', 08:58:04 INFO - 'sessionrestore_no_auto_restore', 08:58:04 INFO - 'tabpaint']}, 08:58:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:58:04 INFO - 'ts_paint', 08:58:04 INFO - 'tpaint', 08:58:04 INFO - 'sessionrestore', 08:58:04 INFO - 'sessionrestore_no_auto_restore', 08:58:04 INFO - 'tabpaint']}, 08:58:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['a11yr', 08:58:04 INFO - 'ts_paint', 08:58:04 INFO - 'tpaint', 08:58:04 INFO - 'sessionrestore', 08:58:04 INFO - 'sessionrestore_no_auto_restore', 08:58:04 INFO - 'tabpaint']}, 08:58:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['a11yr', 08:58:04 INFO - 'ts_paint', 08:58:04 INFO - 'tpaint', 08:58:04 INFO - 'sessionrestore', 08:58:04 INFO - 'sessionrestore_no_auto_restore', 08:58:04 INFO - 'tabpaint']}, 08:58:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['tsvgx', 08:58:04 INFO - 'tsvgr_opacity', 08:58:04 INFO - 'tart', 08:58:04 INFO - 'tscrollx', 08:58:04 INFO - 'cart']}, 08:58:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:58:04 INFO - 'tsvgr_opacity', 08:58:04 INFO - 'tart', 08:58:04 INFO - 'tscrollx', 08:58:04 INFO - 'cart']}, 08:58:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'talos_options': ['--disable-e10s'], 08:58:04 INFO - 'tests': ['tp5o']}, 08:58:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'tests': ['tp5o']}, 08:58:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'talos_options': ['--disable-e10s', 08:58:04 INFO - '--xperf_path', 08:58:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:04 INFO - 'tests': ['tp5n']}, 08:58:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:04 INFO - 'talos_options': ['--xperf_path', 08:58:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:04 INFO - 'tests': ['tp5n']}}, 08:58:04 INFO - 'talos.zip': {'path': '', 08:58:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:58:04 INFO - Running post-action listener: _resource_record_post_action 08:58:04 INFO - [mozharness: 2016-08-26 15:58:04.219855Z] Finished populate-webroot step (success) 08:58:04 INFO - [mozharness: 2016-08-26 15:58:04.219989Z] Running create-virtualenv step. 08:58:04 INFO - Running pre-action listener: _resource_record_pre_action 08:58:04 INFO - Running main action method: create_virtualenv 08:58:04 INFO - Creating virtualenv /builds/slave/test/build/venv 08:58:04 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 08:58:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:58:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:58:04 INFO - Using real prefix '/tools/python27' 08:58:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:58:05 INFO - Installing distribute.............................................................................................................................................................................................done. 08:58:07 INFO - Installing pip.................done. 08:58:07 INFO - Return code: 0 08:58:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:58:07 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')]} 08:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:07 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')]} 08:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:07 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:07 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 08:58:07 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 08:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:07 INFO - 'HOME': '/Users/cltbld', 08:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:07 INFO - 'LOGNAME': 'cltbld', 08:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:07 INFO - 'MOZ_NO_REMOTE': '1', 08:58:07 INFO - 'NO_EM_RESTART': '1', 08:58:07 INFO - 'PAGER': '/bin/cat', 08:58:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:07 INFO - 'PWD': '/builds/slave/test', 08:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:07 INFO - 'SHELL': '/bin/bash', 08:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:07 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:07 INFO - 'USER': 'cltbld', 08:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:07 INFO - 'XPC_FLAGS': '0x0', 08:58:07 INFO - 'XPC_SERVICE_NAME': '0', 08:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:08 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 08:58:08 INFO - Cleaning up... 08:58:08 INFO - Return code: 0 08:58:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:08 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:08 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 08:58:08 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 08:58:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:08 INFO - 'HOME': '/Users/cltbld', 08:58:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:08 INFO - 'LOGNAME': 'cltbld', 08:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:08 INFO - 'MOZ_NO_REMOTE': '1', 08:58:08 INFO - 'NO_EM_RESTART': '1', 08:58:08 INFO - 'PAGER': '/bin/cat', 08:58:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:08 INFO - 'PWD': '/builds/slave/test', 08:58:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:08 INFO - 'SHELL': '/bin/bash', 08:58:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:08 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:08 INFO - 'USER': 'cltbld', 08:58:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:08 INFO - 'XPC_FLAGS': '0x0', 08:58:08 INFO - 'XPC_SERVICE_NAME': '0', 08:58:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:08 INFO - Downloading/unpacking psutil>=3.1.1 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:08 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 08:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:58:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:58:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:58:11 INFO - Installing collected packages: psutil 08:58:11 INFO - Running setup.py install for psutil 08:58:11 INFO - building 'psutil._psutil_osx' extension 08:58:11 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 08:58:12 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 08:58:12 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 08:58:12 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 08:58:12 INFO - building 'psutil._psutil_posix' extension 08:58:12 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 08:58:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:58:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:58:12 INFO - ^ 08:58:12 INFO - 1 warning generated. 08:58:12 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 08:58:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:58:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:58:12 INFO - Successfully installed psutil 08:58:12 INFO - Cleaning up... 08:58:12 INFO - Return code: 0 08:58:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:12 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:12 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 08:58:12 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 08:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:12 INFO - 'HOME': '/Users/cltbld', 08:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:12 INFO - 'LOGNAME': 'cltbld', 08:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:12 INFO - 'MOZ_NO_REMOTE': '1', 08:58:12 INFO - 'NO_EM_RESTART': '1', 08:58:12 INFO - 'PAGER': '/bin/cat', 08:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:12 INFO - 'PWD': '/builds/slave/test', 08:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:12 INFO - 'SHELL': '/bin/bash', 08:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:12 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:12 INFO - 'USER': 'cltbld', 08:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:12 INFO - 'XPC_FLAGS': '0x0', 08:58:12 INFO - 'XPC_SERVICE_NAME': '0', 08:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:58:13 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 08:58:13 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 08:58:13 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 08:58:13 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 08:58:13 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 08:58:13 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 08:58:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:58:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:58:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:58:15 INFO - Installing collected packages: mozsystemmonitor 08:58:15 INFO - Running setup.py install for mozsystemmonitor 08:58:15 INFO - Successfully installed mozsystemmonitor 08:58:15 INFO - Cleaning up... 08:58:15 INFO - Return code: 0 08:58:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:58: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')]} 08:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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')]} 08:58:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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', '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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:58: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 jsonschema==2.5.1 08:58:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:15 INFO - 'HOME': '/Users/cltbld', 08:58:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:15 INFO - 'LOGNAME': 'cltbld', 08:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:15 INFO - 'MOZ_NO_REMOTE': '1', 08:58:15 INFO - 'NO_EM_RESTART': '1', 08:58:15 INFO - 'PAGER': '/bin/cat', 08:58:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:15 INFO - 'PWD': '/builds/slave/test', 08:58:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:15 INFO - 'SHELL': '/bin/bash', 08:58:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:15 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:15 INFO - 'USER': 'cltbld', 08:58:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:15 INFO - 'XPC_FLAGS': '0x0', 08:58:15 INFO - 'XPC_SERVICE_NAME': '0', 08:58:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:58:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58:19 INFO - Downloading functools32-3.2.3-2.tar.gz 08:58:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:58:19 INFO - warning: no files found matching '*.txt' 08:58:19 INFO - no previously-included directories found matching 'build' 08:58:19 INFO - no previously-included directories found matching 'dist' 08:58:19 INFO - no previously-included directories found matching '.git*' 08:58:19 INFO - Installing collected packages: jsonschema, functools32 08:58:19 INFO - Running setup.py install for functools32 08:58:19 INFO - warning: no files found matching '*.txt' 08:58:19 INFO - no previously-included directories found matching 'build' 08:58:19 INFO - no previously-included directories found matching 'dist' 08:58:19 INFO - no previously-included directories found matching '.git*' 08:58:19 INFO - Successfully installed jsonschema functools32 08:58:19 INFO - Cleaning up... 08:58:19 INFO - Return code: 0 08:58:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:58:19 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')]} 08:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:19 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')]} 08:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:19 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:19 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 08:58:19 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 08:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:19 INFO - 'HOME': '/Users/cltbld', 08:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:19 INFO - 'LOGNAME': 'cltbld', 08:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:19 INFO - 'MOZ_NO_REMOTE': '1', 08:58:19 INFO - 'NO_EM_RESTART': '1', 08:58:19 INFO - 'PAGER': '/bin/cat', 08:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:19 INFO - 'PWD': '/builds/slave/test', 08:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:19 INFO - 'SHELL': '/bin/bash', 08:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:19 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:19 INFO - 'USER': 'cltbld', 08:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:19 INFO - 'XPC_FLAGS': '0x0', 08:58:19 INFO - 'XPC_SERVICE_NAME': '0', 08:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:58:19 INFO - Cleaning up... 08:58:19 INFO - Return code: 0 08:58:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:58:19 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')]} 08:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:19 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')]} 08:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:19 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:19 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 08:58:19 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 08:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:19 INFO - 'HOME': '/Users/cltbld', 08:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:19 INFO - 'LOGNAME': 'cltbld', 08:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:19 INFO - 'MOZ_NO_REMOTE': '1', 08:58:19 INFO - 'NO_EM_RESTART': '1', 08:58:19 INFO - 'PAGER': '/bin/cat', 08:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:19 INFO - 'PWD': '/builds/slave/test', 08:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:19 INFO - 'SHELL': '/bin/bash', 08:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:19 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:19 INFO - 'USER': 'cltbld', 08:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:19 INFO - 'XPC_FLAGS': '0x0', 08:58:19 INFO - 'XPC_SERVICE_NAME': '0', 08:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:19 INFO - Downloading/unpacking blobuploader==1.2.4 08:58:19 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 08:58:19 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 08:58:19 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 08:58:19 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 08:58:19 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 08:58:19 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 08:58:22 INFO - Downloading blobuploader-1.2.4.tar.gz 08:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:58:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:58:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:58:23 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 08:58:23 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 08:58:23 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 08:58:23 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 08:58:23 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 08:58:23 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 08:58:23 INFO - Downloading docopt-0.6.1.tar.gz 08:58:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:58:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:58:23 INFO - Running setup.py install for blobuploader 08:58:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:58:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:58:23 INFO - Running setup.py install for requests 08:58:24 INFO - Running setup.py install for docopt 08:58:24 INFO - Successfully installed blobuploader requests docopt 08:58:24 INFO - Cleaning up... 08:58:24 INFO - Return code: 0 08:58:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:24 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')]} 08:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:24 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')]} 08:58:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:24 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:24 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 08:58:24 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 08:58:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:24 INFO - 'HOME': '/Users/cltbld', 08:58:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:24 INFO - 'LOGNAME': 'cltbld', 08:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:24 INFO - 'MOZ_NO_REMOTE': '1', 08:58:24 INFO - 'NO_EM_RESTART': '1', 08:58:24 INFO - 'PAGER': '/bin/cat', 08:58:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:24 INFO - 'PWD': '/builds/slave/test', 08:58:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:24 INFO - 'SHELL': '/bin/bash', 08:58:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:24 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:24 INFO - 'USER': 'cltbld', 08:58:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:24 INFO - 'XPC_FLAGS': '0x0', 08:58:24 INFO - 'XPC_SERVICE_NAME': '0', 08:58:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:58:24 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-_KHQRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:58:24 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Q2LRhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:58:24 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-WnjPiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-35DJOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-h2tw_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-juK_pS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-hVoe_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-u85UND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-CcKxDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-1tvaEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-cLndLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:58:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:58:25 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-QffFU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:58:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-1wok0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:58:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-UAHPD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ZvRCAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:58:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-fDd360-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:58:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:58:26 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-l4eV19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:58:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:58:26 INFO - Running setup.py install for manifestparser 08:58:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:58:26 INFO - Running setup.py install for mozcrash 08:58:26 INFO - Running setup.py install for mozdebug 08:58:26 INFO - Running setup.py install for mozdevice 08:58:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Running setup.py install for mozfile 08:58:27 INFO - Running setup.py install for mozhttpd 08:58:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Running setup.py install for mozinfo 08:58:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Running setup.py install for mozInstall 08:58:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:58:27 INFO - Running setup.py install for mozleak 08:58:27 INFO - Running setup.py install for mozlog 08:58:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:58:28 INFO - Running setup.py install for moznetwork 08:58:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:58:28 INFO - Running setup.py install for mozprocess 08:58:28 INFO - Running setup.py install for mozprofile 08:58:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:58:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:58:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:58:28 INFO - Running setup.py install for mozrunner 08:58:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:58:28 INFO - Running setup.py install for mozscreenshot 08:58:28 INFO - Running setup.py install for moztest 08:58:28 INFO - Running setup.py install for mozversion 08:58:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:58:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:58:29 INFO - Cleaning up... 08:58:29 INFO - Return code: 0 08:58:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:29 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:29 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 08:58:29 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 08:58:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:29 INFO - 'HOME': '/Users/cltbld', 08:58:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:29 INFO - 'LOGNAME': 'cltbld', 08:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:29 INFO - 'MOZ_NO_REMOTE': '1', 08:58:29 INFO - 'NO_EM_RESTART': '1', 08:58:29 INFO - 'PAGER': '/bin/cat', 08:58:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:29 INFO - 'PWD': '/builds/slave/test', 08:58:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:29 INFO - 'SHELL': '/bin/bash', 08:58:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:29 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:29 INFO - 'USER': 'cltbld', 08:58:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:29 INFO - 'XPC_FLAGS': '0x0', 08:58:29 INFO - 'XPC_SERVICE_NAME': '0', 08:58:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:58:29 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-mPP5T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:58:29 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)) 08:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:58:29 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ycqhnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:58:29 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)) 08:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:58:29 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-9M3UJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:58:29 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)) 08:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:58:29 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-q84yqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:58:29 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)) 08:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:58:29 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-E480Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:58:29 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)) 08:58:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:58:29 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-u0GAPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-E8wNDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-mSzmmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-fBn3C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-FoYsG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-HsIN0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-U07sMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-n9f0MQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-RJBCkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:58:30 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)) 08:58:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:30 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Z1L8dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:31 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)) 08:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:58:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-DtP5to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:58:31 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)) 08:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:58:31 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Aq50Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:58:31 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)) 08:58:31 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)) 08:58:31 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)) 08:58:31 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)) 08:58:31 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)) 08:58:31 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)) 08:58:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:33 INFO - Downloading blessings-1.6.tar.gz 08:58:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:58:33 INFO - Installing collected packages: blessings 08:58:33 INFO - Running setup.py install for blessings 08:58:33 INFO - Successfully installed blessings 08:58:33 INFO - Cleaning up... 08:58:34 INFO - Return code: 0 08:58:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:58:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:34 INFO - Reading from file tmpfile_stdout 08:58:34 INFO - Current package versions: 08:58:34 INFO - blessings == 1.6 08:58:34 INFO - blobuploader == 1.2.4 08:58:34 INFO - docopt == 0.6.1 08:58:34 INFO - functools32 == 3.2.3-2 08:58:34 INFO - jsonschema == 2.5.1 08:58:34 INFO - manifestparser == 1.1 08:58:34 INFO - mozInstall == 1.12 08:58:34 INFO - mozcrash == 0.17 08:58:34 INFO - mozdebug == 0.1 08:58:34 INFO - mozdevice == 0.48 08:58:34 INFO - mozfile == 1.2 08:58:34 INFO - mozhttpd == 0.7 08:58:34 INFO - mozinfo == 0.9 08:58:34 INFO - mozleak == 0.1 08:58:34 INFO - mozlog == 3.3 08:58:34 INFO - moznetwork == 0.27 08:58:34 INFO - mozprocess == 0.23 08:58:34 INFO - mozprofile == 0.28 08:58:34 INFO - mozrunner == 6.12 08:58:34 INFO - mozscreenshot == 0.1 08:58:34 INFO - mozsystemmonitor == 0.3 08:58:34 INFO - moztest == 0.7 08:58:34 INFO - mozversion == 1.4 08:58:34 INFO - psutil == 3.1.1 08:58:34 INFO - requests == 1.2.3 08:58:34 INFO - wsgiref == 0.1.2 08:58:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:34 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')]} 08:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:34 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')]} 08:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:34 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:34 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 08:58:34 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 08:58:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:34 INFO - 'HOME': '/Users/cltbld', 08:58:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:34 INFO - 'LOGNAME': 'cltbld', 08:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:34 INFO - 'MOZ_NO_REMOTE': '1', 08:58:34 INFO - 'NO_EM_RESTART': '1', 08:58:34 INFO - 'PAGER': '/bin/cat', 08:58:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:34 INFO - 'PWD': '/builds/slave/test', 08:58:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:34 INFO - 'SHELL': '/bin/bash', 08:58:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:34 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:34 INFO - 'USER': 'cltbld', 08:58:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:34 INFO - 'XPC_FLAGS': '0x0', 08:58:34 INFO - 'XPC_SERVICE_NAME': '0', 08:58:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 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)) 08:58:34 INFO - Cleaning up... 08:58:34 INFO - Return code: 0 08:58:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:58:34 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')]} 08:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:34 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')]} 08:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:34 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 0x10833ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb23cf0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, '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.HNlToO1u4C/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.RlsYrENLUv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 08:58:34 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 08:58:34 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 08:58:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:58:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:34 INFO - 'HOME': '/Users/cltbld', 08:58:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:34 INFO - 'LOGNAME': 'cltbld', 08:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:34 INFO - 'MOZ_NO_REMOTE': '1', 08:58:34 INFO - 'NO_EM_RESTART': '1', 08:58:34 INFO - 'PAGER': '/bin/cat', 08:58:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:34 INFO - 'PWD': '/builds/slave/test', 08:58:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:34 INFO - 'SHELL': '/bin/bash', 08:58:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:58:34 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:58:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:34 INFO - 'USER': 'cltbld', 08:58:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:34 INFO - 'XPC_FLAGS': '0x0', 08:58:34 INFO - 'XPC_SERVICE_NAME': '0', 08:58:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:58:35 INFO - Cleaning up... 08:58:35 INFO - Return code: 0 08:58:35 INFO - Running post-action listener: _resource_record_post_action 08:58:35 INFO - Running post-action listener: _start_resource_monitoring 08:58:35 INFO - Starting resource monitoring. 08:58:35 INFO - [mozharness: 2016-08-26 15:58:35.229102Z] Finished create-virtualenv step (success) 08:58:35 INFO - [mozharness: 2016-08-26 15:58:35.230077Z] Running install step. 08:58:35 INFO - Running pre-action listener: _resource_record_pre_action 08:58:35 INFO - Running main action method: install 08:58:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:35 INFO - Reading from file tmpfile_stdout 08:58:35 INFO - Detecting whether we're running mozinstall >=1.0... 08:58:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:58:35 INFO - Reading from file tmpfile_stdout 08:58:35 INFO - Output received: 08:58:35 INFO - Usage: mozinstall [options] installer 08:58:35 INFO - Options: 08:58:35 INFO - -h, --help show this help message and exit 08:58:35 INFO - -d DEST, --destination=DEST 08:58:35 INFO - Directory to install application into. [default: 08:58:35 INFO - "/builds/slave/test"] 08:58:35 INFO - --app=APP Application being installed. [default: firefox] 08:58:35 INFO - mkdir: /builds/slave/test/build/application 08:58:35 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'] 08:58:35 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 08:59:10 INFO - Reading from file tmpfile_stdout 08:59:10 INFO - Output received: 08:59:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:59:10 INFO - Running post-action listener: _resource_record_post_action 08:59:10 INFO - [mozharness: 2016-08-26 15:59:10.113309Z] Finished install step (success) 08:59:10 INFO - [mozharness: 2016-08-26 15:59:10.113443Z] Running run-tests step. 08:59:10 INFO - Running pre-action listener: _resource_record_pre_action 08:59:10 INFO - Running main action method: run_tests 08:59:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:59:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:59:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:59:10 INFO - 2016-08-26 08:59:10.127 screenresolution[1637:7684] starting screenresolution argv=screenresolution get 08:59:10 INFO - 2016-08-26 08:59:10.130 screenresolution[1637:7684] Display 0: 1600x1200x32@60 08:59:10 INFO - 2016-08-26 08:59:10.136 screenresolution[1638:7686] starting screenresolution argv=screenresolution list 08:59:10 INFO - Available Modes on Display 0 08:59:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:59:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:59:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:59:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:59:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:59:10 INFO - Intel Iris: 08:59:10 INFO - Chipset Model: Intel Iris 08:59:10 INFO - Type: GPU 08:59:10 INFO - Bus: Built-In 08:59:10 INFO - VRAM (Dynamic, Max): 1536 MB 08:59:10 INFO - Vendor: Intel (0x8086) 08:59:10 INFO - Device ID: 0x0a2e 08:59:10 INFO - Revision ID: 0x0009 08:59:10 INFO - Displays: 08:59:10 INFO - Display: 08:59:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:59:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:59:10 INFO - Display Serial Number: 1600x1200 60 08:59:10 INFO - Main Display: Yes 08:59:10 INFO - Mirror: Off 08:59:10 INFO - Online: Yes 08:59:10 INFO - Rotation: Supported 08:59:10 INFO - Return code: 0 08:59:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:59:10 INFO - Python 2.7.3 08:59:10 INFO - Return code: 0 08:59:10 INFO - grabbing minidump binary from tooltool 08:59: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')]} 08:59:10 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 0x1082a2d30>, '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 0x108007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10829ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:59:10 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 08:59:10 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 08:59:10 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 08:59:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:59:10 INFO - Return code: 0 08:59:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:59:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:59:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:59:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:59:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:59:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HNlToO1u4C/Render', 08:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:10 INFO - 'HOME': '/Users/cltbld', 08:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:10 INFO - 'LOGNAME': 'cltbld', 08:59:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:59:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:10 INFO - 'MOZ_NO_REMOTE': '1', 08:59:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:59:10 INFO - 'NO_EM_RESTART': '1', 08:59:10 INFO - 'PAGER': '/bin/cat', 08:59:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:10 INFO - 'PWD': '/builds/slave/test', 08:59:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:10 INFO - 'SHELL': '/bin/bash', 08:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlsYrENLUv/Listeners', 08:59:10 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 08:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:10 INFO - 'USER': 'cltbld', 08:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:10 INFO - 'XPC_FLAGS': '0x0', 08:59:10 INFO - 'XPC_SERVICE_NAME': '0', 08:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:59:10 INFO - mozversion application_buildid: 20160826062717 08:59:10 INFO - mozversion application_changeset: 7852b1545a876a191139739e18e297e4974c4db7 08:59:10 INFO - mozversion application_display_name: Nightly 08:59:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:59:10 INFO - mozversion application_name: Firefox 08:59:10 INFO - mozversion application_remotingname: firefox 08:59:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:59:10 INFO - mozversion application_vendor: Mozilla 08:59:10 INFO - mozversion application_version: 51.0a1 08:59:10 INFO - mozversion platform_buildid: 20160826062717 08:59:10 INFO - mozversion platform_changeset: 7852b1545a876a191139739e18e297e4974c4db7 08:59:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:59:10 INFO - mozversion platform_version: 51.0a1 08:59:10 INFO - using testdate: 1472227150 08:59:10 INFO - actual date: 1472227150 08:59:10 INFO - starting webserver on 'localhost:49268' 08:59:10 INFO - SUITE-START | Running 2 tests 08:59:10 INFO - TEST-START | dromaeo_css 08:59:10 INFO - operating with platform_type : mac_ 08:59:10 INFO - Initialising browser for dromaeo_css test... 08:59:10 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpLLjm0f/profile http://localhost:49268/getInfo.html) 08:59:13 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 08:59:13 INFO - PROCESS | 1648 | colorDepth:24 08:59:13 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 08:59:13 INFO - PROCESS | 1648 | __metrics 08:59:13 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:59:13 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:59:14 INFO - TEST-INFO | 1648: exit 0 08:59:14 INFO - Browser initialized. 08:59:14 INFO - Running cycle 1/1 for dromaeo_css test... 08:59:14 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpLLjm0f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:59:25 INFO - PROCESS | 1650 | RSS: Main: 233664512 08:59:25 INFO - PROCESS | 1650 | 09:00:17 INFO - PROCESS | 1650 | 1472227217645 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:02:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 09:02:30 INFO - PROCESS | 1650 | RSS: Main: 376774656 09:02:30 INFO - PROCESS | 1650 | 09:05:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 09:05:34 INFO - PROCESS | 1650 | RSS: Main: 373379072 09:05:34 INFO - PROCESS | 1650 | 09:08:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 09:08:37 INFO - PROCESS | 1650 | RSS: Main: 325894144 09:08:37 INFO - PROCESS | 1650 | 09:11:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 09:11:40 INFO - PROCESS | 1650 | RSS: Main: 331804672 09:11:40 INFO - PROCESS | 1650 | 09:14:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 09:14:44 INFO - PROCESS | 1650 | RSS: Main: 324313088 09:14:44 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 09:17:48 INFO - PROCESS | 1650 | RSS: Main: 434995200 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | __start_tp_report 09:17:48 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:17:48 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:17:48 INFO - PROCESS | 1650 | |i|pagename|runs| 09:17:48 INFO - PROCESS | 1650 | |0;dojo.html;3338.8497517611704;3347.5146103814945;3362.2266878617916;3387.780399056338;3435.879744208953;5685.1756495308155;5686.0617998030275;5722.055860782971;5733.168690539869;5770.047942089561;5750.2812148481335;5785.421457854212;5843.065109582468;5852.092925596528;5893.440123188301;5215.295935048777;5225.634205605609;5286.920696189561;5301.0988132017455;5392.595555333346;5381.434949330303;5393.865153371177;5398.4871437213405;5412.621116521845;5431.185322201662;5060.583036749716;5122.667026643257;5252.816151434681;5254.185601231815;5301.973490132563;5276.947230527684;5280.049591073605;5280.075986702312;5288.92066619001;5291.497307755757;5202.427732949411;5235.6073294502685;5235.947640523584;5246.291750613656;5249.711265880375;5741.995150848585;5841.503472204868;5863.179154918314;5864.472197502203;5928.822135335947;6231.127642130105;6251.9687401562705;6266.3159462008225;6321;6327.14583531222;6301.180846489927;6316.905246421307;6318.399752023551;6322.9051564226575;6335.239771227436;4953.554180123734;5461.399246082928;5741.799037033726;5782.161586569966;5803.912941305884;6315.141954271005;6372.076048972873;6382.393672601095;6382.904256436157;6393.009083592051;6218.378162183745;6227.190465239581;6236.376362363727;6242.15730876335;6249.0626406039455;5378.2470454136455;6008.21893153896;6100.369238098956;6272.466840318532;6296.33888441707;6265.624062556261;6284.12022316876;6288.213973253343;6291.276503202191;6293.653849038347;6260.280067792171;6286.151369565142;6312.4634406076;6330.145430366933;6334.208223972004;4889.975550122298;4925.532074452921;4930.654854160174;4931.556159945622;4966.90066198674;4474.485434175112;4478.373027776049;4479.0817882334695;4515.774211289422;4522.276435770261;5786.797462088806;5793.304803423532;5799.5070419014855;5809.128630705428;5828.300603927556;6218.844528886723;6225.719842607094;6273.215848018997;6301.4328710416285;6307.211598550181;4877.707337559757;4885.462599114095;5863.618864773776;5873.559483038789;5901.085331773582;5038.647294689336;5497.422770609109;5509.228707980806;5517.393086760453;5532.695701736363;6248.250209974832;6339.841503962438;6378.075179098912;6392.648404337713;6468.288488266287;6275.74897004133;6312.747490100344;6318.462930650946;6321.209848768904;6336.936630633634;6433.710483028181;6439.162908821823;6447.613143211234;6449.322821103717;6467.126937863516;6102.489251635809;6167.167432396748;6167.969160154171;6252.843678907881;6273.090401891609;5569.275994120739;6069.9089513656445;6290.622562646256;6292.874142517216;6293.181886354744;6183.9405271276;6205.65868877198;6222.160008398808;6226.62640241587;6239.438450539473;6026.939730602813;6042.395760423922;6124.289394097845;6189.566730328834;6237.939550276373;6064.666443345658;6078.90881636767;6113.602615829956;6123;6143.477804386677;6203.534734894811;6218.81343559694;6229.595076320024;6239.376062393906;6242.906356404748;5834.795782147605;5926.525877929669;6058.303295121117;6079.787207447718;6086.939130608815 09:17:48 INFO - PROCESS | 1650 | |1;ext.html;464.5493870945184;517.5781737883625;523.8952209558088;531.4366771222504;545.8754964772567;28373;40768.796156019256;41924.371134432935;41979.160416791645;41988.790056049715;42548.787256063675;42996.92507687309;43031.70904872851;43159.35260971088;43240.56759432405;7780.805479862992;9694.56374463149;9892.30753847231;9899.109080182781;9902.207823374112;8789.077146899579;11992.70018249542;12218.755624887497;12225.205361651464;12476.126671132979;12188;12188.878111218908;12260.448279827384;12278.754424911496;12285.324307163102;12199.756004879897;12210.694732631666;12243.142979991404;12258.570950016707;12268.938655306667;12010.979066779335;12106.576269830603;12114.333711645944;12122.818157727641;12129.636110916686;39651.40522892159;39753.40369894454;40072.59927400718;40094.59905400938;40124;3539.3983022885914;3540.522029526007;3657.6159503252043;3671.07195301028;3800.3539398302355;31529.684703152903;31996.360072798645;32326;32900.177495562566;32906.17734556631;3243.675632436761;3303.9174020649434;3308.4375656138513;3314.1880239341276;3346.5816772903386;470877;476655;476763.9999999965;478574;480754;59657.806843862876;59921.70039149776;60069;60092;60122.79754404887;34423.311533769185;35010.82494587562;35037.299254014775;61989.690051549456;62125;16488.83511164873;16665.583360416087;16677.583060423345;16678.249478773483;16688.83311166897;11392.772144557228;11661.475233614508;12437.315947622787;12598.055145864097;12700.618981430573;18540.629187416445;19226;19244;19267.614647707247;19283;7540.585267810214;7620.276073773093;7698.307152356315;7768.495047821873;7782.392898379107;845.6659619450282;920.4615300049546;989.4113002763344;1058.4969343532202;1072.871255449351;27468.725312747018;29676.258093547833;30733.38533229323;31314.843425782725;31923.84038079841;926.4117285523741;1041.7083216699336;1069.550788668761;1139.9772004559863;1238.4798384678456;15354;15974.041557506587;15986.440474583334;16309.755353669705;16416;684918;690059;690279;692008;693239;60415;60982.69508652517;61155.77688446206;61173.69413152995;61246.081308780405;1255.7990721484518;1370.047816767337;1400.2228763036612;1405.5291477355202;1418.7446259673359;25101.74898251067;29894.85052574723;32013.359732804747;32205;32616.184595384355;50951.74524127356;51491;51498.22752658638;51628;51757.22364164616;559200;559746;560704;561430;562303;26663;27113.72886271112;27457.450850983267;27717.30706732348;27745.306367340978;46981;48963.5103648959;49391.01217975692;49825;50054.749726252594;39029.804850976696;39449.60550394459;39605.80197098996;40624.390634141106;41181.794091029355;38789.03027424337;40299.99250018773;40334.39498407585;40371.59628403796;40435.7978210107;34809.12977175591;36940.44589331216;38072.42891356577;38306.80846595749;39146.608533914296;32263.35473290474;34079.82960085184;34449.48325775166;34468.82765586156;34505;19847.30534431288;24652.64410457442;25427.87286063558;25782.710864457076;26455.60316595291 09:17:48 INFO - PROCESS | 1650 | |2;jquery.html;350.3850741947881;371.2871287128713;377.1645804542935;382.18022342076245;404.6176363336646;15560.688786224282;16013.199340032996;20261.594768104627;21024.264150754727;21044;17025.659486810287;20238.797612023875;20549;20628.48428789281;20853.748775073505;5655.293088363965;5725.341585717633;5933.910991335112;5941.55438342126;6095.603785753934;4153.937690934638;6008.909866352008;6077.4226448487325;6106.2977757558;6108.908366374508;6154.261488621371;6446.838829029265;6459.612423254596;6502.089707440962;6531.7387304507765;5829.280362293133;6121.632702037848;6186.319504854462;6483.578567393104;6494.383033611823;6004.489618382443;6009.519238460913;6021;6022.578419510636;6028.577999540034;21633.675494867588;24216.757832421627;25319.873400633063;25426.491470170677;25616.847241874162;2090.602785470771;2192.3751730756817;2224.443889027743;2311.5492478966607;2405.9879700601387;9577.569009394594;9853.950730246375;10143.89856101437;10170.338927969658;10483.47582620866;1569.8351673074392;1819.3541292840982;1824.616830465604;1876.2307453943818;1907.2657027044688;72545;77924;97200;117674;119369;11076.446177691083;11099.167562432689;11353.432328383547;11354.943225283821;11469.483873225725;14313.928430357782;14314;14351.497697580744;14353.210573418562;14357.282135893163;8211.466254693425;8386.538740369338;8404.411691181558;8485.193906578865;8494.023187333412;8288.544130072903;8314.334853211729;8344.666213351398;8484.19400156984;8509;4293.785310734451;4414.27164517851;4426.712263702849;4477.977610111929;4484.68607197493;1597.6405308805427;1713.8543223825866;1716.356366362614;1890.6124244529838;1892.858035647332;720.4164626652429;949.6581230756922;951.6811868024151;993.0863605482829;1051.169576034924;19409.126589303516;20944.581108377748;20950.37148885536;21195.5004188441;21219.36341909745;926.5784068249066;934.8691183234353;964.2719746591418;965.9227261819037;1010.4745532323151;8327.95836020816;10146.898531014594;10949.890501094887;10994.670159895217;11058.778824423627;497794;575459;594546;609706;611626;124340;126293.36853315674;127210;132558;146207;894.4320356573622;934.0799312677008;955.1165172215673;970.3256236915277;1040.089921318846;15240.3903843845;19045.333413330467;19143.329983450512;19221.423357299303;19262.13320400557;52890.73554632202;56680;56796.88828005499;56853.14720279282;57271.713641431525;83344.58327708565;84356;84862;85809.57095214484;86048;5895.115732640138;7723.806904827425;7836.177201393772;7939.880901786362;7995.520268783659;10174.796504070026;10867.782644347228;12042.157049006486;12137.907588317094;12511.499540018298;6139.662318572343;10579.259451838298;10726.570937162738;10885;11169.888301116885;9726.832780066437;10838.945805270923;11012.063974562252;11182.496787644739;11233.49449274801;9607.711768646952;10042;10795.514201860778;10915.617953371593;11507.827382589436;5954.166416701752;6800.625965571941;6892.414144797748;7096.432285417258;8025.357971362394;10376.636817711344;10688.198385121303;10978.286411383235;11038.834417483904;11075.833862491907 09:17:48 INFO - PROCESS | 1650 | |3;mootools.html;1524.5273965070828;1547.1954583616516;1744.2151032035588;1879.5207222158347;1906.1898693055457;8501.022382426023;9025.323100767453;9028.097190280976;9039.231665308449;9109.726708198748;7875.094364148124;8098.91901080989;8538.10349913258;8734.86897696535;8798.208161265486;2396.2212281008656;2430.0644251962945;2430.829841911067;2433.0632706408073;2435.062500937143;2384.380061184096;2408.313630615275;2426.745191754867;2428.7935525480357;2431.987840060797;2406.4104294447884;2423.9272821815375;2425.7816796488314;2428.101602407103;2429.501952099824;2081.7613519955603;2383.1778036577352;2397.4725560376687;2406.6149416093517;2410.2769169249154;2348.6594443805725;2372.833901626887;2385.997880890013;2393.042782886842;2402.3994001499627;8819.470831750052;8902.599383027778;8930.196282334557;8970.192682658528;8980.730578082668;5285.365756109253;5421.891562168735;5430.809921652763;5435.592330575223;5437.401885792559;3590.7486475946434;3687.9815600922093;3745.138618117821;3759.642833930772;3760.5487341519192;949.7530642033053;1121.2711737370694;1123.7246874515795;1140.372794962767;1146.1117633833762;11618.302901825833;11643.650690479299;11719.648410547697;11734.882651173464;11754.88245117555;4394.209042372404;4555.293929440942;4673.859784206479;4683.882902927386;4712.764361781898;9164.8625270621;9179.495127767903;9182.081791820765;9183.127602877716;9193.034731353246;5291;5301.469853014745;5309.681419114865;5312.73436328182;5315.096433606296;4085.4893138357706;4435.534268901783;4589.5639914208095;4968.031233909425;4995.700257984533;3296.57144571209;3580.9820950895078;3630.9818450908106;3736.7010639148803;3774.7168962327937;1165.1086918507349;1184.390039129849;1192.8628207756046;1196.2403873540366;1196.4257156564897;902.1339514066439;915.4278575890069;918.6417297254076;935.9485228312373;940.2403056275813;5676.2337084493065;5948.899453601098;6002.939970600325;6094.055421409688;6202.41077097675;359.9496070550125;418.7424733788698;437.99781001095425;439.4419087758529;453.2634468987895;3871.1676989447406;3964.7621142731527;3979.0848104936026;3982.904701207191;4000.7999600019884;10528.263021588415;10599.205059620559;10967.83548246777;10977.945110274397;10981;10718.464076796285;10888.61889833852;10898.618548350769;10900.672979810619;10917.508712107974;8076;8133.75598732039;8179.182081791892;8297.676650537858;8304.211099945496;5841.474267315962;5905.261842269716;5912.881742365214;6011.887800756874;6034.69826508664;9942.900570994487;10022.3485473444;10121.493925303557;10124.69625911224;10211.948940255548;9889.159421449258;10211.948940255252;10263.12763415118;10357.016083472057;10371.844422333521;5510.944890551204;6720.831979200363;7036.19083805369;7122.715091396286;7129.572225666269;7913.881291780743;7986.640601173078;7987;8103.959480202561;8486.448380855225;6929.965350173217;7391.039164908528;7825.686972521035;7849.764507064797;7897.802554936173;7552.207018263003;7594.696212151452;7650.540967541965;7683.308502234826;7858.13560508355;6909.689063992233;7101.396381307647;7102.964485177541;7104.573725576482;7120.501564890408;5118.8976220476125;5148.9227661584355;5211.957608478244;5233.921491177559;5347.331583552056;5069.214271787879;5146.125158722951;5354.099294750307;5413.593980451561;5423.566114710892 09:17:48 INFO - PROCESS | 1650 | |4;prototype.html;300.7203300930135;307.4588723846031;307.8122345369324;319.63880814679396;320.4584252613082;4846.8061277548895;4883.486119303018;5397.946020539794;5415;5588.273524441817;4710.3405523226775;5184.144616138342;5352.304200453936;5372.7045012524395;5380.300560927084;3924.3917192835083;4057.3508238681816;4061.309577371839;4064.9024763313887;4071.735337203087;3389.5594372391756;3433.3991551478516;4088.325426304671;4142.851432910979;4144.8549300774475;4090.2228576570355;4093.7923312622847;4097.323941549641;4104.733192342488;4122.97938510307;3447.120984149051;3864.7294689371756;3918.902027449308;4113.053997580559;4132.214879172948;3388.8816690492076;3436.570428696413;3568.7145028397927;3577.53321138332;3585.8386372613295;5187.948120518784;5217.373915130171;5217.634765566412;5251.579873610102;5269.7365131743645;3578.7494875358734;4699.929501057487;4715.7170569765585;4721.339012538213;4751.384529260034;4693.389859318267;5269.287481568505;5626.803061892814;5706.9429305707245;5718.142278658193;3814.8474061037805;3822.4266360045935;3831.5593706723803;3845.2309538092495;3848.6536211741077;5267.868303292448;5487.807926722586;5750.654960702372;5867.266591676041;5980.43185897339;3470.444728843373;3520.2959408117968;3801.4487899254455;3863.613638636114;3938.8621398250925;3965.524137103566;3978;3984.8007599619905;3997.060690737674;4010.959890401117;4468.687191896536;4601.953980460153;4639.605633521121;4647.814087436464;4682.2508398656055;5197.8180763673845;5685.459881311269;5727.4845263926445;5768.826935191951;5772.393898640582;1331.374254100571;1351.6755978565075;1355.4645914863574;1357.7216670582507;1358.6875018745545;718.6586371473509;888.8711336602607;904.2088172848757;915.3363811236933;916.3997581584073;4664.55686709762;5066.898662026739;5316.494932981361;5321.946780532223;5330.680159190461;5313.973430132824;5707.914381284284;5827.388124246893;5830.105215804881;5851.912221316684;4306.74159550428;4879.4388645305535;5115.8454336269615;5176.298946253447;5303.628745987821;2136.920854968231;2373.655819906104;2494.1145893208013;2586.3146266239087;2601.726818684049;4518.593326600556;4662;4774.570288674036;5085.593152547787;5120.667156634807;4242.257604919127;4552.97723511387;5025.447200807908;5460.918086228709;5486.845064088166;6162.815115546451;6174.320824709189;6182.350853160443;6184.330230837649;6192.628442293477;5926.4666180044005;5929.733162007634;5930.318013428338;5966.642001479925;5989.431004054608;5366.517013468807;5486.945130548803;5523.944760552504;5821.330546987151;5882.323532793613;4726.669133160645;5846.736896839738;6033.517318614413;6161.644438223584;6192.535559832941;3471.795409914672;3830.2531006453473;3909.123620662041;3947.230290093404;3956.7625942443547;4973.726445045557;5962.612430192024;5970.432808883149;5988.6706231157705;6001.2498437695285;5355.678659280456;5689.2319536862;5742.5405967521665;5750.281214848144;5758.93459709955;5609.663420194802;5716.171155182392;5720.3707592164;5791.710414479344;5822.388649191943;4592.874745687328;5264.947350526446;5308.362996440297;5567.190663034611;5639.182318563868;5630.987421729519;5743.913841292468;5759.798407055733;5768.913466297925;5788;5129.846104616868;5630.718464076862;5654.642885707319;5663.915041274302;5671 09:17:48 INFO - PROCESS | 1650 | |5;yui.html;731.228553875661;731.3016069653481;736.9189389167192;768.4659161882493;804.2239239134234;15683.333833248347;16369.462733276332;16379.836201637994;16382.525572698454;16499.91750041251;13979;13985.95105367099;13998.93000534996;13999.580012599612;14473.131612103281;685.4002747595854;701.2216439751871;702.3047183288533;704.3942209699679;847.3390755210945;694.6839188169371;827.462149602757;862.9946112778605;868.1145231863495;872.8341615093144;911.676354894013;918.4305730447132;924.560833604036;924.5885580916495;928.7213835849252;915.1077699243218;916.1845957098188;916.9266458683289;918.715198288526;918.7381596245104;532.8374845672068;642.6915080761214;678.5080816408114;679.1612358736955;680.9285025072346;14806.185659788707;14883.553493395108;14942.925285373503;14947;14967.625809354853;11807.350595717233;13655.248961307125;13984.720305593832;14135.64660883486;14228.857711422857;12231.449584768618;12562.246265224116;12664.74670506594;12762.298073605947;12855.421506032251;4530.093981203772;4546.068056048535;4547.658925580562;4737.218358970766;4760.309755085528;111720.4413977933;111833;112174;114751;118173;9141.405808622418;10942.398168100832;10966;11160.616608652412;11388.373639449745;21881.562368752537;21942;21994.670079948814;22039.889800551216;22161.778382216293;6624.7681331154145;7768.495047821873;7782.805429864298;7978.202179781975;8045.517268963881;4406.206882761134;4451.5103338632725;4487.596116349544;4548.249538826123;4685.484596694361;3322.3853587085982;3430.0567343980106;3440.9827950860085;3444.380011597886;3446.8104254266254;633.6926590603554;637.9234491860914;643.7481767884782;663.1213641924373;663.5620490476263;604.5949214026613;618.6999305336908;618.848382146377;629.2543336146681;632.9841753956096;8336.749897503085;9028.142326478974;9032.32257580684;9205.447673139633;9220.581248020224;579.3337661689092;598.6976576828673;602.8492876780805;615.2862679292;649.7888186339364;4352.542982986741;4370.453693288339;4380.780960952004;4382.890427739332;4470.709403888802;139876;142813;147042;157750;164306;139511;142231.2888435551;143951;144142.55857441502;145306;119527.40236298936;121264.99999999824;121368.39315803365;122978;125727;7157.57054576727;7973.923520324914;8004.279614834692;8526.317894568296;8670.523121228392;53483.732581336844;64535;66247;66840.65535740161;67884;71449;100301.49849250707;100766.4961675187;100844;102282.4657630151;11116.610918618133;11486.770264594828;11526.193166478266;11556.826647600124;11698.824517632413;12010.099242557018;12043.879561204627;12264.509419623115;12428.56500022495;12571.308578028296;11434.485448154686;11590.826137607773;11670.94164529172;11907.880921190677;12320.630381088582;11694.590689325832;11698.590549330733;11995.340256285988;12059.457324420599;12261.526232114538;11459.885401145882;11549.942250288696;11857.703557410789;11882.940585297363;12069;9620.278479114235;9843.409395436296;10202.489875506344;10254.948725256327;10285.2800303981;8601.827963440572;9894.950525247328;9895.406275623485;9912.702618921156;9943.950280248553 09:17:48 INFO - PROCESS | 1650 | __end_tp_report 09:17:48 INFO - PROCESS | 1650 | __start_cc_report 09:17:48 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1318 09:17:48 INFO - PROCESS | 1650 | __end_cc_report 09:17:48 INFO - PROCESS | 1650 | __startTimestamp1472228268302__endTimestamp 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:17:48 INFO - PROCESS | 1650 | Number of tests: 6 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5787.96 Median:6058.30 stddev:659.41 (10.9%) stddev-sans-first:632.82 09:17:48 INFO - PROCESS | 1650 | Values: 3338.8 3347.5 3362.2 3387.8 3435.9 5685.2 5686.1 5722.1 5733.2 5770.0 5750.3 5785.4 5843.1 5852.1 5893.4 5215.3 5225.6 5286.9 5301.1 5392.6 5381.4 5393.9 5398.5 5412.6 5431.2 5060.6 5122.7 5252.8 5254.2 5302.0 5276.9 5280.0 5280.1 5288.9 5291.5 5202.4 5235.6 5235.9 5246.3 5249.7 5742.0 5841.5 5863.2 5864.5 5928.8 6231.1 6252.0 6266.3 6321.0 6327.1 6301.2 6316.9 6318.4 6322.9 6335.2 4953.6 5461.4 5741.8 5782.2 5803.9 6315.1 6372.1 6382.4 6382.9 6393.0 6218.4 6227.2 6236.4 6242.2 6249.1 5378.2 6008.2 6100.4 6272.5 6296.3 6265.6 6284.1 6288.2 6291.3 6293.7 6260.3 6286.2 6312.5 6330.1 6334.2 4890.0 4925.5 4930.7 4931.6 4966.9 4474.5 4478.4 4479.1 4515.8 4522.3 5786.8 5793.3 5799.5 5809.1 5828.3 6218.8 6225.7 6273.2 6301.4 6307.2 4877.7 4885.5 5863.6 5873.6 5901.1 5038.6 5497.4 5509.2 5517.4 5532.7 6248.3 6339.8 6378.1 6392.6 6468.3 6275.7 6312.7 6318.5 6321.2 6336.9 6433.7 6439.2 6447.6 6449.3 6467.1 6102.5 6167.2 6168.0 6252.8 6273.1 5569.3 6069.9 6290.6 6292.9 6293.2 6183.9 6205.7 6222.2 6226.6 6239.4 6026.9 6042.4 6124.3 6189.6 6237.9 6064.7 6078.9 6113.6 6123.0 6143.5 6203.5 6218.8 6229.6 6239.4 6242.9 5834.8 5926.5 6058.3 6079.8 6086.9 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:70911.20 Median:3539.96 stddev:155737.92 (4399.4%) stddev-sans-first:156084.99 09:17:48 INFO - PROCESS | 1650 | Values: 464.5 517.6 523.9 531.4 545.9 28373.0 40768.8 41924.4 41979.2 41988.8 42548.8 42996.9 43031.7 43159.4 43240.6 7780.8 9694.6 9892.3 9899.1 9902.2 8789.1 11992.7 12218.8 12225.2 12476.1 12188.0 12188.9 12260.4 12278.8 12285.3 12199.8 12210.7 12243.1 12258.6 12268.9 12011.0 12106.6 12114.3 12122.8 12129.6 39651.4 39753.4 40072.6 40094.6 40124.0 3539.4 3540.5 3657.6 3671.1 3800.4 31529.7 31996.4 32326.0 32900.2 32906.2 3243.7 3303.9 3308.4 3314.2 3346.6 470877.0 476655.0 476764.0 478574.0 480754.0 59657.8 59921.7 60069.0 60092.0 60122.8 34423.3 35010.8 35037.3 61989.7 62125.0 16488.8 16665.6 16677.6 16678.2 16688.8 11392.8 11661.5 12437.3 12598.1 12700.6 18540.6 19226.0 19244.0 19267.6 19283.0 7540.6 7620.3 7698.3 7768.5 7782.4 845.7 920.5 989.4 1058.5 1072.9 27468.7 29676.3 30733.4 31314.8 31923.8 926.4 1041.7 1069.6 1140.0 1238.5 15354.0 15974.0 15986.4 16309.8 16416.0 684918.0 690059.0 690279.0 692008.0 693239.0 60415.0 60982.7 61155.8 61173.7 61246.1 1255.8 1370.0 1400.2 1405.5 1418.7 25101.7 29894.9 32013.4 32205.0 32616.2 50951.7 51491.0 51498.2 51628.0 51757.2 559200.0 559746.0 560704.0 561430.0 562303.0 26663.0 27113.7 27457.5 27717.3 27745.3 46981.0 48963.5 49391.0 49825.0 50054.7 39029.8 39449.6 39605.8 40624.4 41181.8 38789.0 40300.0 40334.4 40371.6 40435.8 34809.1 36940.4 38072.4 38306.8 39146.6 32263.4 34079.8 34449.5 34468.8 34505.0 19847.3 24652.6 25427.9 25782.7 26455.6 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:33993.77 Median:374.23 stddev:96763.96 (25857.1%) stddev-sans-first:97002.43 09:17:48 INFO - PROCESS | 1650 | Values: 350.4 371.3 377.2 382.2 404.6 15560.7 16013.2 20261.6 21024.3 21044.0 17025.7 20238.8 20549.0 20628.5 20853.7 5655.3 5725.3 5933.9 5941.6 6095.6 4153.9 6008.9 6077.4 6106.3 6108.9 6154.3 6446.8 6459.6 6502.1 6531.7 5829.3 6121.6 6186.3 6483.6 6494.4 6004.5 6009.5 6021.0 6022.6 6028.6 21633.7 24216.8 25319.9 25426.5 25616.8 2090.6 2192.4 2224.4 2311.5 2406.0 9577.6 9854.0 10143.9 10170.3 10483.5 1569.8 1819.4 1824.6 1876.2 1907.3 72545.0 77924.0 97200.0 117674.0 119369.0 11076.4 11099.2 11353.4 11354.9 11469.5 14313.9 14314.0 14351.5 14353.2 14357.3 8211.5 8386.5 8404.4 8485.2 8494.0 8288.5 8314.3 8344.7 8484.2 8509.0 4293.8 4414.3 4426.7 4478.0 4484.7 1597.6 1713.9 1716.4 1890.6 1892.9 720.4 949.7 951.7 993.1 1051.2 19409.1 20944.6 20950.4 21195.5 21219.4 926.6 934.9 964.3 965.9 1010.5 8328.0 10146.9 10949.9 10994.7 11058.8 497794.0 575459.0 594546.0 609706.0 611626.0 124340.0 126293.4 127210.0 132558.0 146207.0 894.4 934.1 955.1 970.3 1040.1 15240.4 19045.3 19143.3 19221.4 19262.1 52890.7 56680.0 56796.9 56853.1 57271.7 83344.6 84356.0 84862.0 85809.6 86048.0 5895.1 7723.8 7836.2 7939.9 7995.5 10174.8 10867.8 12042.2 12137.9 12511.5 6139.7 10579.3 10726.6 10885.0 11169.9 9726.8 10838.9 11012.1 11182.5 11233.5 9607.7 10042.0 10795.5 10915.6 11507.8 5954.2 6800.6 6892.4 7096.4 8025.4 10376.6 10688.2 10978.3 11038.8 11075.8 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5705.11 Median:4981.87 stddev:3211.92 (64.5%) stddev-sans-first:3205.56 09:17:48 INFO - PROCESS | 1650 | Values: 1524.5 1547.2 1744.2 1879.5 1906.2 8501.0 9025.3 9028.1 9039.2 9109.7 7875.1 8098.9 8538.1 8734.9 8798.2 2396.2 2430.1 2430.8 2433.1 2435.1 2384.4 2408.3 2426.7 2428.8 2432.0 2406.4 2423.9 2425.8 2428.1 2429.5 2081.8 2383.2 2397.5 2406.6 2410.3 2348.7 2372.8 2386.0 2393.0 2402.4 8819.5 8902.6 8930.2 8970.2 8980.7 5285.4 5421.9 5430.8 5435.6 5437.4 3590.7 3688.0 3745.1 3759.6 3760.5 949.8 1121.3 1123.7 1140.4 1146.1 11618.3 11643.7 11719.6 11734.9 11754.9 4394.2 4555.3 4673.9 4683.9 4712.8 9164.9 9179.5 9182.1 9183.1 9193.0 5291.0 5301.5 5309.7 5312.7 5315.1 4085.5 4435.5 4589.6 4968.0 4995.7 3296.6 3581.0 3631.0 3736.7 3774.7 1165.1 1184.4 1192.9 1196.2 1196.4 902.1 915.4 918.6 935.9 940.2 5676.2 5948.9 6002.9 6094.1 6202.4 359.9 418.7 438.0 439.4 453.3 3871.2 3964.8 3979.1 3982.9 4000.8 10528.3 10599.2 10967.8 10977.9 10981.0 10718.5 10888.6 10898.6 10900.7 10917.5 8076.0 8133.8 8179.2 8297.7 8304.2 5841.5 5905.3 5912.9 6011.9 6034.7 9942.9 10022.3 10121.5 10124.7 10211.9 9889.2 10211.9 10263.1 10357.0 10371.8 5510.9 6720.8 7036.2 7122.7 7129.6 7913.9 7986.6 7987.0 8104.0 8486.4 6930.0 7391.0 7825.7 7849.8 7897.8 7552.2 7594.7 7650.5 7683.3 7858.1 6909.7 7101.4 7103.0 7104.6 7120.5 5118.9 5148.9 5212.0 5233.9 5347.3 5069.2 5146.1 5354.1 5413.6 5423.6 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4556.52 Median:5153.07 stddev:1436.54 (27.9%) stddev-sans-first:1404.61 09:17:48 INFO - PROCESS | 1650 | Values: 300.7 307.5 307.8 319.6 320.5 4846.8 4883.5 5397.9 5415.0 5588.3 4710.3 5184.1 5352.3 5372.7 5380.3 3924.4 4057.4 4061.3 4064.9 4071.7 3389.6 3433.4 4088.3 4142.9 4144.9 4090.2 4093.8 4097.3 4104.7 4123.0 3447.1 3864.7 3918.9 4113.1 4132.2 3388.9 3436.6 3568.7 3577.5 3585.8 5187.9 5217.4 5217.6 5251.6 5269.7 3578.7 4699.9 4715.7 4721.3 4751.4 4693.4 5269.3 5626.8 5706.9 5718.1 3814.8 3822.4 3831.6 3845.2 3848.7 5267.9 5487.8 5750.7 5867.3 5980.4 3470.4 3520.3 3801.4 3863.6 3938.9 3965.5 3978.0 3984.8 3997.1 4011.0 4468.7 4602.0 4639.6 4647.8 4682.3 5197.8 5685.5 5727.5 5768.8 5772.4 1331.4 1351.7 1355.5 1357.7 1358.7 718.7 888.9 904.2 915.3 916.4 4664.6 5066.9 5316.5 5321.9 5330.7 5314.0 5707.9 5827.4 5830.1 5851.9 4306.7 4879.4 5115.8 5176.3 5303.6 2136.9 2373.7 2494.1 2586.3 2601.7 4518.6 4662.0 4774.6 5085.6 5120.7 4242.3 4553.0 5025.4 5460.9 5486.8 6162.8 6174.3 6182.4 6184.3 6192.6 5926.5 5929.7 5930.3 5966.6 5989.4 5366.5 5486.9 5523.9 5821.3 5882.3 4726.7 5846.7 6033.5 6161.6 6192.5 3471.8 3830.3 3909.1 3947.2 3956.8 4973.7 5962.6 5970.4 5988.7 6001.2 5355.7 5689.2 5742.5 5750.3 5758.9 5609.7 5716.2 5720.4 5791.7 5822.4 4592.9 5264.9 5308.4 5567.2 5639.2 5631.0 5743.9 5759.8 5768.9 5788.0 5129.8 5630.7 5654.6 5663.9 5671.0 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:25544.08 Median:3899.68 stddev:42159.33 (1081.1%) stddev-sans-first:42236.44 09:17:48 INFO - PROCESS | 1650 | Values: 731.2 731.3 736.9 768.5 804.2 15683.3 16369.5 16379.8 16382.5 16499.9 13979.0 13986.0 13998.9 13999.6 14473.1 685.4 701.2 702.3 704.4 847.3 694.7 827.5 863.0 868.1 872.8 911.7 918.4 924.6 924.6 928.7 915.1 916.2 916.9 918.7 918.7 532.8 642.7 678.5 679.2 680.9 14806.2 14883.6 14942.9 14947.0 14967.6 11807.4 13655.2 13984.7 14135.6 14228.9 12231.4 12562.2 12664.7 12762.3 12855.4 4530.1 4546.1 4547.7 4737.2 4760.3 111720.4 111833.0 112174.0 114751.0 118173.0 9141.4 10942.4 10966.0 11160.6 11388.4 21881.6 21942.0 21994.7 22039.9 22161.8 6624.8 7768.5 7782.8 7978.2 8045.5 4406.2 4451.5 4487.6 4548.2 4685.5 3322.4 3430.1 3441.0 3444.4 3446.8 633.7 637.9 643.7 663.1 663.6 604.6 618.7 618.8 629.3 633.0 8336.7 9028.1 9032.3 9205.4 9220.6 579.3 598.7 602.8 615.3 649.8 4352.5 4370.5 4380.8 4382.9 4470.7 139876.0 142813.0 147042.0 157750.0 164306.0 139511.0 142231.3 143951.0 144142.6 145306.0 119527.4 121265.0 121368.4 122978.0 125727.0 7157.6 7973.9 8004.3 8526.3 8670.5 53483.7 64535.0 66247.0 66840.7 67884.0 71449.0 100301.5 100766.5 100844.0 102282.5 11116.6 11486.8 11526.2 11556.8 11698.8 12010.1 12043.9 12264.5 12428.6 12571.3 11434.5 11590.8 11670.9 11907.9 12320.6 11694.6 11698.6 11995.3 12059.5 12261.5 11459.9 11549.9 11857.7 11882.9 12069.0 9620.3 9843.4 10202.5 10254.9 10285.3 8601.8 9895.0 9895.4 9912.7 9944.0 09:17:48 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:17:48 INFO - PROCESS | 1650 | 09:17:48 INFO - TEST-INFO | 1650: exit 0 09:17:48 INFO - TEST-OK | dromaeo_css | took 1118215ms 09:17:48 INFO - TEST-START | kraken 09:17:48 INFO - operating with platform_type : mac_ 09:17:48 INFO - Initialising browser for kraken test... 09:17:48 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpT7Fkod/profile http://localhost:49268/getInfo.html) 09:17:51 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 09:17:51 INFO - PROCESS | 1659 | colorDepth:24 09:17:51 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 09:17:51 INFO - PROCESS | 1659 | __metrics 09:17:51 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:17:51 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:52 INFO - TEST-INFO | 1659: exit 0 09:17:52 INFO - Browser initialized. 09:17:52 INFO - Running cycle 1/1 for kraken test... 09:17:52 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpT7Fkod/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:04 INFO - PROCESS | 1660 | RSS: Main: 233742336 09:18:04 INFO - PROCESS | 1660 | 09:18:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 09:18:08 INFO - PROCESS | 1660 | RSS: Main: 291975168 09:18:08 INFO - PROCESS | 1660 | 09:18:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 09:18:11 INFO - PROCESS | 1660 | RSS: Main: 288587776 09:18:11 INFO - PROCESS | 1660 | 09:18:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 09:18:15 INFO - PROCESS | 1660 | RSS: Main: 379510784 09:18:15 INFO - PROCESS | 1660 | 09:18:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 09:18:17 INFO - PROCESS | 1660 | RSS: Main: 282472448 09:18:17 INFO - PROCESS | 1660 | 09:18:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:18:20 INFO - PROCESS | 1660 | RSS: Main: 283652096 09:18:20 INFO - PROCESS | 1660 | 09:18:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 09:18:24 INFO - PROCESS | 1660 | RSS: Main: 322076672 09:18:24 INFO - PROCESS | 1660 | 09:18:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 09:18:27 INFO - PROCESS | 1660 | RSS: Main: 322736128 09:18:27 INFO - PROCESS | 1660 | 09:18:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 09:18:31 INFO - PROCESS | 1660 | RSS: Main: 321425408 09:18:31 INFO - PROCESS | 1660 | 09:18:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:18:33 INFO - PROCESS | 1660 | RSS: Main: 298532864 09:18:33 INFO - PROCESS | 1660 | 09:18:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:18:35 INFO - PROCESS | 1660 | RSS: Main: 303808512 09:18:35 INFO - PROCESS | 1660 | 09:18:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:18:38 INFO - PROCESS | 1660 | RSS: Main: 307572736 09:18:38 INFO - PROCESS | 1660 | 09:18:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:18:42 INFO - PROCESS | 1660 | RSS: Main: 321110016 09:18:42 INFO - PROCESS | 1660 | 09:18:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:18:45 INFO - PROCESS | 1660 | RSS: Main: 317255680 09:18:45 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 09:18:47 INFO - PROCESS | 1660 | RSS: Main: 360472576 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | __start_tp_report 09:18:47 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 09:18:47 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 09:18:47 INFO - PROCESS | 1660 | |i|pagename|runs| 09:18:47 INFO - PROCESS | 1660 | |0;ai-astar;113;113;131;120;164;123;113;114;112;113 09:18:47 INFO - PROCESS | 1660 | |1;audio-beat-detection;147;113;128;161;109;128;130;108;160;108 09:18:47 INFO - PROCESS | 1660 | |2;audio-dft;185;182;182;180;184;184;182;183;182;181 09:18:47 INFO - PROCESS | 1660 | |3;audio-fft;79;74;75;152;73;83;80;84;147;58 09:18:47 INFO - PROCESS | 1660 | |4;audio-oscillator;81;83;134;70;70;80;117;68;71;116 09:18:47 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;89;84;89;85;87;84;89;129;90;86 09:18:47 INFO - PROCESS | 1660 | |6;imaging-darkroom;94;94;94;96;94;94;95;96;94;95 09:18:47 INFO - PROCESS | 1660 | |7;imaging-desaturate;86;86;92;89;86;85;103;141;87;85 09:18:47 INFO - PROCESS | 1660 | |8;json-parse-financial;55;55;55;55;54;53;53;55;53;53 09:18:47 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;65;68;66;76;74;78;73;69;70;70 09:18:47 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;92;84;86;87;86;83;84;86;85;85 09:18:47 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;129;138;138;140;127;142;155;143;177;140 09:18:47 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;171;168;170;169;170;170;166;180;173;171 09:18:47 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;67;68;68;67;67;66;65;59;72 09:18:47 INFO - PROCESS | 1660 | __end_tp_report 09:18:47 INFO - PROCESS | 1660 | __start_cc_report 09:18:47 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2069 09:18:47 INFO - PROCESS | 1660 | __end_cc_report 09:18:47 INFO - PROCESS | 1660 | __startTimestamp1472228327510__endTimestamp 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | ------- Summary: start ------- 09:18:47 INFO - PROCESS | 1660 | Number of tests: 14 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:121.60 Median:117.00 stddev:16.11 (13.8%) stddev-sans-first:16.79 09:18:47 INFO - PROCESS | 1660 | Values: 113.0 113.0 131.0 120.0 164.0 123.0 113.0 114.0 112.0 113.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:129.00 stddev:20.68 (16.0%) stddev-sans-first:20.91 09:18:47 INFO - PROCESS | 1660 | Values: 147.0 113.0 128.0 161.0 109.0 128.0 130.0 108.0 160.0 108.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.50 stddev:1.51 (0.8%) stddev-sans-first:1.30 09:18:47 INFO - PROCESS | 1660 | Values: 185.0 182.0 182.0 180.0 184.0 184.0 182.0 183.0 182.0 181.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:90.50 Median:77.00 stddev:31.96 (41.5%) stddev-sans-first:33.62 09:18:47 INFO - PROCESS | 1660 | Values: 79.0 74.0 75.0 152.0 73.0 83.0 80.0 84.0 147.0 58.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:70.50 stddev:24.05 (34.1%) stddev-sans-first:25.33 09:18:47 INFO - PROCESS | 1660 | Values: 81.0 83.0 134.0 70.0 70.0 80.0 117.0 68.0 71.0 116.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:88.00 stddev:13.46 (15.3%) stddev-sans-first:14.26 09:18:47 INFO - PROCESS | 1660 | Values: 89.0 84.0 89.0 85.0 87.0 84.0 89.0 129.0 90.0 86.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.50 stddev:0.84 (0.9%) stddev-sans-first:0.87 09:18:47 INFO - PROCESS | 1660 | Values: 94.0 94.0 94.0 96.0 94.0 94.0 95.0 96.0 94.0 95.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:86.00 stddev:17.39 (20.2%) stddev-sans-first:18.20 09:18:47 INFO - PROCESS | 1660 | Values: 86.0 86.0 92.0 89.0 86.0 85.0 103.0 141.0 87.0 85.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 09:18:47 INFO - PROCESS | 1660 | Values: 55.0 55.0 55.0 55.0 54.0 53.0 53.0 55.0 53.0 53.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:4.25 (6.0%) stddev-sans-first:3.94 09:18:47 INFO - PROCESS | 1660 | Values: 65.0 68.0 66.0 76.0 74.0 78.0 73.0 69.0 70.0 70.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:2.49 (2.9%) stddev-sans-first:1.27 09:18:47 INFO - PROCESS | 1660 | Values: 92.0 84.0 86.0 87.0 86.0 83.0 84.0 86.0 85.0 85.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:141.00 stddev:14.22 (10.1%) stddev-sans-first:14.17 09:18:47 INFO - PROCESS | 1660 | Values: 129.0 138.0 138.0 140.0 127.0 142.0 155.0 143.0 177.0 140.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:170.50 stddev:3.74 (2.2%) stddev-sans-first:3.96 09:18:47 INFO - PROCESS | 1660 | Values: 171.0 168.0 170.0 169.0 170.0 170.0 166.0 180.0 173.0 171.0 09:18:47 INFO - PROCESS | 1660 | 09:18:47 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:3.24 (4.8%) stddev-sans-first:3.43 09:18:47 INFO - PROCESS | 1660 | Values: 67.0 67.0 68.0 68.0 67.0 67.0 66.0 65.0 59.0 72.0 09:18:47 INFO - PROCESS | 1660 | -------- Summary: end -------- 09:18:47 INFO - PROCESS | 1660 | 09:18:48 INFO - TEST-INFO | 1660: exit 0 09:18:48 INFO - TEST-OK | kraken | took 59453ms 09:18:48 INFO - SUITE-END | took 1177s 09:18:48 INFO - Completed test suite (00:19:37) 09:18:48 INFO - javascript benchmark 09:18:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.8497517611704, 3347.5146103814945, 3362.2266878617916, 3387.780399056338, 3435.879744208953, 5685.1756495308155, 5686.0617998030275, 5722.055860782971, 5733.168690539869, 5770.047942089561, 5750.2812148481335, 5785.421457854212, 5843.065109582468, 5852.092925596528, 5893.440123188301, 5215.295935048777, 5225.634205605609, 5286.920696189561, 5301.0988132017455, 5392.595555333346, 5381.434949330303, 5393.865153371177, 5398.4871437213405, 5412.621116521845, 5431.185322201662, 5060.583036749716, 5122.667026643257, 5252.816151434681, 5254.185601231815, 5301.973490132563, 5276.947230527684, 5280.049591073605, 5280.075986702312, 5288.92066619001, 5291.497307755757, 5202.427732949411, 5235.6073294502685, 5235.947640523584, 5246.291750613656, 5249.711265880375, 5741.995150848585, 5841.503472204868, 5863.179154918314, 5864.472197502203, 5928.822135335947, 6231.127642130105, 6251.9687401562705, 6266.3159462008225, 6321.0, 6327.14583531222, 6301.180846489927, 6316.905246421307, 6318.399752023551, 6322.9051564226575, 6335.239771227436, 4953.554180123734, 5461.399246082928, 5741.799037033726, 5782.161586569966, 5803.912941305884, 6315.141954271005, 6372.076048972873, 6382.393672601095, 6382.904256436157, 6393.009083592051, 6218.378162183745, 6227.190465239581, 6236.376362363727, 6242.15730876335, 6249.0626406039455, 5378.2470454136455, 6008.21893153896, 6100.369238098956, 6272.466840318532, 6296.33888441707, 6265.624062556261, 6284.12022316876, 6288.213973253343, 6291.276503202191, 6293.653849038347, 6260.280067792171, 6286.151369565142, 6312.4634406076, 6330.145430366933, 6334.208223972004, 4889.975550122298, 4925.532074452921, 4930.654854160174, 4931.556159945622, 4966.90066198674, 4474.485434175112, 4478.373027776049, 4479.0817882334695, 4515.774211289422, 4522.276435770261, 5786.797462088806, 5793.304803423532, 5799.5070419014855, 5809.128630705428, 5828.300603927556, 6218.844528886723, 6225.719842607094, 6273.215848018997, 6301.4328710416285, 6307.211598550181, 4877.707337559757, 4885.462599114095, 5863.618864773776, 5873.559483038789, 5901.085331773582, 5038.647294689336, 5497.422770609109, 5509.228707980806, 5517.393086760453, 5532.695701736363, 6248.250209974832, 6339.841503962438, 6378.075179098912, 6392.648404337713, 6468.288488266287, 6275.74897004133, 6312.747490100344, 6318.462930650946, 6321.209848768904, 6336.936630633634, 6433.710483028181, 6439.162908821823, 6447.613143211234, 6449.322821103717, 6467.126937863516, 6102.489251635809, 6167.167432396748, 6167.969160154171, 6252.843678907881, 6273.090401891609, 5569.275994120739, 6069.9089513656445, 6290.622562646256, 6292.874142517216, 6293.181886354744, 6183.9405271276, 6205.65868877198, 6222.160008398808, 6226.62640241587, 6239.438450539473, 6026.939730602813, 6042.395760423922, 6124.289394097845, 6189.566730328834, 6237.939550276373, 6064.666443345658, 6078.90881636767, 6113.602615829956, 6123.0, 6143.477804386677, 6203.534734894811, 6218.81343559694, 6229.595076320024, 6239.376062393906, 6242.906356404748, 5834.795782147605, 5926.525877929669, 6058.303295121117, 6079.787207447718, 6086.939130608815], "value": 5745.464152629167, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.5493870945184, 517.5781737883625, 523.8952209558088, 531.4366771222504, 545.8754964772567, 28373.0, 40768.796156019256, 41924.371134432935, 41979.160416791645, 41988.790056049715, 42548.787256063675, 42996.92507687309, 43031.70904872851, 43159.35260971088, 43240.56759432405, 7780.805479862992, 9694.56374463149, 9892.30753847231, 9899.109080182781, 9902.207823374112, 8789.077146899579, 11992.70018249542, 12218.755624887497, 12225.205361651464, 12476.126671132979, 12188.0, 12188.878111218908, 12260.448279827384, 12278.754424911496, 12285.324307163102, 12199.756004879897, 12210.694732631666, 12243.142979991404, 12258.570950016707, 12268.938655306667, 12010.979066779335, 12106.576269830603, 12114.333711645944, 12122.818157727641, 12129.636110916686, 39651.40522892159, 39753.40369894454, 40072.59927400718, 40094.59905400938, 40124.0, 3539.3983022885914, 3540.522029526007, 3657.6159503252043, 3671.07195301028, 3800.3539398302355, 31529.684703152903, 31996.360072798645, 32326.0, 32900.177495562566, 32906.17734556631, 3243.675632436761, 3303.9174020649434, 3308.4375656138513, 3314.1880239341276, 3346.5816772903386, 470877.0, 476655.0, 476763.9999999965, 478574.0, 480754.0, 59657.806843862876, 59921.70039149776, 60069.0, 60092.0, 60122.79754404887, 34423.311533769185, 35010.82494587562, 35037.299254014775, 61989.690051549456, 62125.0, 16488.83511164873, 16665.583360416087, 16677.583060423345, 16678.249478773483, 16688.83311166897, 11392.772144557228, 11661.475233614508, 12437.315947622787, 12598.055145864097, 12700.618981430573, 18540.629187416445, 19226.0, 19244.0, 19267.614647707247, 19283.0, 7540.585267810214, 7620.276073773093, 7698.307152356315, 7768.495047821873, 7782.392898379107, 845.6659619450282, 920.4615300049546, 989.4113002763344, 1058.4969343532202, 1072.871255449351, 27468.725312747018, 29676.258093547833, 30733.38533229323, 31314.843425782725, 31923.84038079841, 926.4117285523741, 1041.7083216699336, 1069.550788668761, 1139.9772004559863, 1238.4798384678456, 15354.0, 15974.041557506587, 15986.440474583334, 16309.755353669705, 16416.0, 684918.0, 690059.0, 690279.0, 692008.0, 693239.0, 60415.0, 60982.69508652517, 61155.77688446206, 61173.69413152995, 61246.081308780405, 1255.7990721484518, 1370.047816767337, 1400.2228763036612, 1405.5291477355202, 1418.7446259673359, 25101.74898251067, 29894.85052574723, 32013.359732804747, 32205.0, 32616.184595384355, 50951.74524127356, 51491.0, 51498.22752658638, 51628.0, 51757.22364164616, 559200.0, 559746.0, 560704.0, 561430.0, 562303.0, 26663.0, 27113.72886271112, 27457.450850983267, 27717.30706732348, 27745.306367340978, 46981.0, 48963.5103648959, 49391.01217975692, 49825.0, 50054.749726252594, 39029.804850976696, 39449.60550394459, 39605.80197098996, 40624.390634141106, 41181.794091029355, 38789.03027424337, 40299.99250018773, 40334.39498407585, 40371.59628403796, 40435.7978210107, 34809.12977175591, 36940.44589331216, 38072.42891356577, 38306.80846595749, 39146.608533914296, 32263.35473290474, 34079.82960085184, 34449.48325775166, 34468.82765586156, 34505.0, 19847.30534431288, 24652.64410457442, 25427.87286063558, 25782.710864457076, 26455.60316595291], "value": 20538.390984229503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.3850741947881, 371.2871287128713, 377.1645804542935, 382.18022342076245, 404.6176363336646, 15560.688786224282, 16013.199340032996, 20261.594768104627, 21024.264150754727, 21044.0, 17025.659486810287, 20238.797612023875, 20549.0, 20628.48428789281, 20853.748775073505, 5655.293088363965, 5725.341585717633, 5933.910991335112, 5941.55438342126, 6095.603785753934, 4153.937690934638, 6008.909866352008, 6077.4226448487325, 6106.2977757558, 6108.908366374508, 6154.261488621371, 6446.838829029265, 6459.612423254596, 6502.089707440962, 6531.7387304507765, 5829.280362293133, 6121.632702037848, 6186.319504854462, 6483.578567393104, 6494.383033611823, 6004.489618382443, 6009.519238460913, 6021.0, 6022.578419510636, 6028.577999540034, 21633.675494867588, 24216.757832421627, 25319.873400633063, 25426.491470170677, 25616.847241874162, 2090.602785470771, 2192.3751730756817, 2224.443889027743, 2311.5492478966607, 2405.9879700601387, 9577.569009394594, 9853.950730246375, 10143.89856101437, 10170.338927969658, 10483.47582620866, 1569.8351673074392, 1819.3541292840982, 1824.616830465604, 1876.2307453943818, 1907.2657027044688, 72545.0, 77924.0, 97200.0, 117674.0, 119369.0, 11076.446177691083, 11099.167562432689, 11353.432328383547, 11354.943225283821, 11469.483873225725, 14313.928430357782, 14314.0, 14351.497697580744, 14353.210573418562, 14357.282135893163, 8211.466254693425, 8386.538740369338, 8404.411691181558, 8485.193906578865, 8494.023187333412, 8288.544130072903, 8314.334853211729, 8344.666213351398, 8484.19400156984, 8509.0, 4293.785310734451, 4414.27164517851, 4426.712263702849, 4477.977610111929, 4484.68607197493, 1597.6405308805427, 1713.8543223825866, 1716.356366362614, 1890.6124244529838, 1892.858035647332, 720.4164626652429, 949.6581230756922, 951.6811868024151, 993.0863605482829, 1051.169576034924, 19409.126589303516, 20944.581108377748, 20950.37148885536, 21195.5004188441, 21219.36341909745, 926.5784068249066, 934.8691183234353, 964.2719746591418, 965.9227261819037, 1010.4745532323151, 8327.95836020816, 10146.898531014594, 10949.890501094887, 10994.670159895217, 11058.778824423627, 497794.0, 575459.0, 594546.0, 609706.0, 611626.0, 124340.0, 126293.36853315674, 127210.0, 132558.0, 146207.0, 894.4320356573622, 934.0799312677008, 955.1165172215673, 970.3256236915277, 1040.089921318846, 15240.3903843845, 19045.333413330467, 19143.329983450512, 19221.423357299303, 19262.13320400557, 52890.73554632202, 56680.0, 56796.88828005499, 56853.14720279282, 57271.713641431525, 83344.58327708565, 84356.0, 84862.0, 85809.57095214484, 86048.0, 5895.115732640138, 7723.806904827425, 7836.177201393772, 7939.880901786362, 7995.520268783659, 10174.796504070026, 10867.782644347228, 12042.157049006486, 12137.907588317094, 12511.499540018298, 6139.662318572343, 10579.259451838298, 10726.570937162738, 10885.0, 11169.888301116885, 9726.832780066437, 10838.945805270923, 11012.063974562252, 11182.496787644739, 11233.49449274801, 9607.711768646952, 10042.0, 10795.514201860778, 10915.617953371593, 11507.827382589436, 5954.166416701752, 6800.625965571941, 6892.414144797748, 7096.432285417258, 8025.357971362394, 10376.636817711344, 10688.198385121303, 10978.286411383235, 11038.834417483904, 11075.833862491907], "value": 9343.685978649173, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.5273965070828, 1547.1954583616516, 1744.2151032035588, 1879.5207222158347, 1906.1898693055457, 8501.022382426023, 9025.323100767453, 9028.097190280976, 9039.231665308449, 9109.726708198748, 7875.094364148124, 8098.91901080989, 8538.10349913258, 8734.86897696535, 8798.208161265486, 2396.2212281008656, 2430.0644251962945, 2430.829841911067, 2433.0632706408073, 2435.062500937143, 2384.380061184096, 2408.313630615275, 2426.745191754867, 2428.7935525480357, 2431.987840060797, 2406.4104294447884, 2423.9272821815375, 2425.7816796488314, 2428.101602407103, 2429.501952099824, 2081.7613519955603, 2383.1778036577352, 2397.4725560376687, 2406.6149416093517, 2410.2769169249154, 2348.6594443805725, 2372.833901626887, 2385.997880890013, 2393.042782886842, 2402.3994001499627, 8819.470831750052, 8902.599383027778, 8930.196282334557, 8970.192682658528, 8980.730578082668, 5285.365756109253, 5421.891562168735, 5430.809921652763, 5435.592330575223, 5437.401885792559, 3590.7486475946434, 3687.9815600922093, 3745.138618117821, 3759.642833930772, 3760.5487341519192, 949.7530642033053, 1121.2711737370694, 1123.7246874515795, 1140.372794962767, 1146.1117633833762, 11618.302901825833, 11643.650690479299, 11719.648410547697, 11734.882651173464, 11754.88245117555, 4394.209042372404, 4555.293929440942, 4673.859784206479, 4683.882902927386, 4712.764361781898, 9164.8625270621, 9179.495127767903, 9182.081791820765, 9183.127602877716, 9193.034731353246, 5291.0, 5301.469853014745, 5309.681419114865, 5312.73436328182, 5315.096433606296, 4085.4893138357706, 4435.534268901783, 4589.5639914208095, 4968.031233909425, 4995.700257984533, 3296.57144571209, 3580.9820950895078, 3630.9818450908106, 3736.7010639148803, 3774.7168962327937, 1165.1086918507349, 1184.390039129849, 1192.8628207756046, 1196.2403873540366, 1196.4257156564897, 902.1339514066439, 915.4278575890069, 918.6417297254076, 935.9485228312373, 940.2403056275813, 5676.2337084493065, 5948.899453601098, 6002.939970600325, 6094.055421409688, 6202.41077097675, 359.9496070550125, 418.7424733788698, 437.99781001095425, 439.4419087758529, 453.2634468987895, 3871.1676989447406, 3964.7621142731527, 3979.0848104936026, 3982.904701207191, 4000.7999600019884, 10528.263021588415, 10599.205059620559, 10967.83548246777, 10977.945110274397, 10981.0, 10718.464076796285, 10888.61889833852, 10898.618548350769, 10900.672979810619, 10917.508712107974, 8076.0, 8133.75598732039, 8179.182081791892, 8297.676650537858, 8304.211099945496, 5841.474267315962, 5905.261842269716, 5912.881742365214, 6011.887800756874, 6034.69826508664, 9942.900570994487, 10022.3485473444, 10121.493925303557, 10124.69625911224, 10211.948940255548, 9889.159421449258, 10211.948940255252, 10263.12763415118, 10357.016083472057, 10371.844422333521, 5510.944890551204, 6720.831979200363, 7036.19083805369, 7122.715091396286, 7129.572225666269, 7913.881291780743, 7986.640601173078, 7987.0, 8103.959480202561, 8486.448380855225, 6929.965350173217, 7391.039164908528, 7825.686972521035, 7849.764507064797, 7897.802554936173, 7552.207018263003, 7594.696212151452, 7650.540967541965, 7683.308502234826, 7858.13560508355, 6909.689063992233, 7101.396381307647, 7102.964485177541, 7104.573725576482, 7120.501564890408, 5118.8976220476125, 5148.9227661584355, 5211.957608478244, 5233.921491177559, 5347.331583552056, 5069.214271787879, 5146.125158722951, 5354.099294750307, 5413.593980451561, 5423.566114710892], "value": 4507.086646430589, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7203300930135, 307.4588723846031, 307.8122345369324, 319.63880814679396, 320.4584252613082, 4846.8061277548895, 4883.486119303018, 5397.946020539794, 5415.0, 5588.273524441817, 4710.3405523226775, 5184.144616138342, 5352.304200453936, 5372.7045012524395, 5380.300560927084, 3924.3917192835083, 4057.3508238681816, 4061.309577371839, 4064.9024763313887, 4071.735337203087, 3389.5594372391756, 3433.3991551478516, 4088.325426304671, 4142.851432910979, 4144.8549300774475, 4090.2228576570355, 4093.7923312622847, 4097.323941549641, 4104.733192342488, 4122.97938510307, 3447.120984149051, 3864.7294689371756, 3918.902027449308, 4113.053997580559, 4132.214879172948, 3388.8816690492076, 3436.570428696413, 3568.7145028397927, 3577.53321138332, 3585.8386372613295, 5187.948120518784, 5217.373915130171, 5217.634765566412, 5251.579873610102, 5269.7365131743645, 3578.7494875358734, 4699.929501057487, 4715.7170569765585, 4721.339012538213, 4751.384529260034, 4693.389859318267, 5269.287481568505, 5626.803061892814, 5706.9429305707245, 5718.142278658193, 3814.8474061037805, 3822.4266360045935, 3831.5593706723803, 3845.2309538092495, 3848.6536211741077, 5267.868303292448, 5487.807926722586, 5750.654960702372, 5867.266591676041, 5980.43185897339, 3470.444728843373, 3520.2959408117968, 3801.4487899254455, 3863.613638636114, 3938.8621398250925, 3965.524137103566, 3978.0, 3984.8007599619905, 3997.060690737674, 4010.959890401117, 4468.687191896536, 4601.953980460153, 4639.605633521121, 4647.814087436464, 4682.2508398656055, 5197.8180763673845, 5685.459881311269, 5727.4845263926445, 5768.826935191951, 5772.393898640582, 1331.374254100571, 1351.6755978565075, 1355.4645914863574, 1357.7216670582507, 1358.6875018745545, 718.6586371473509, 888.8711336602607, 904.2088172848757, 915.3363811236933, 916.3997581584073, 4664.55686709762, 5066.898662026739, 5316.494932981361, 5321.946780532223, 5330.680159190461, 5313.973430132824, 5707.914381284284, 5827.388124246893, 5830.105215804881, 5851.912221316684, 4306.74159550428, 4879.4388645305535, 5115.8454336269615, 5176.298946253447, 5303.628745987821, 2136.920854968231, 2373.655819906104, 2494.1145893208013, 2586.3146266239087, 2601.726818684049, 4518.593326600556, 4662.0, 4774.570288674036, 5085.593152547787, 5120.667156634807, 4242.257604919127, 4552.97723511387, 5025.447200807908, 5460.918086228709, 5486.845064088166, 6162.815115546451, 6174.320824709189, 6182.350853160443, 6184.330230837649, 6192.628442293477, 5926.4666180044005, 5929.733162007634, 5930.318013428338, 5966.642001479925, 5989.431004054608, 5366.517013468807, 5486.945130548803, 5523.944760552504, 5821.330546987151, 5882.323532793613, 4726.669133160645, 5846.736896839738, 6033.517318614413, 6161.644438223584, 6192.535559832941, 3471.795409914672, 3830.2531006453473, 3909.123620662041, 3947.230290093404, 3956.7625942443547, 4973.726445045557, 5962.612430192024, 5970.432808883149, 5988.6706231157705, 6001.2498437695285, 5355.678659280456, 5689.2319536862, 5742.5405967521665, 5750.281214848144, 5758.93459709955, 5609.663420194802, 5716.171155182392, 5720.3707592164, 5791.710414479344, 5822.388649191943, 4592.874745687328, 5264.947350526446, 5308.362996440297, 5567.190663034611, 5639.182318563868, 5630.987421729519, 5743.913841292468, 5759.798407055733, 5768.913466297925, 5788.0, 5129.846104616868, 5630.718464076862, 5654.642885707319, 5663.915041274302, 5671.0], "value": 4097.409523135396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.228553875661, 731.3016069653481, 736.9189389167192, 768.4659161882493, 804.2239239134234, 15683.333833248347, 16369.462733276332, 16379.836201637994, 16382.525572698454, 16499.91750041251, 13979.0, 13985.95105367099, 13998.93000534996, 13999.580012599612, 14473.131612103281, 685.4002747595854, 701.2216439751871, 702.3047183288533, 704.3942209699679, 847.3390755210945, 694.6839188169371, 827.462149602757, 862.9946112778605, 868.1145231863495, 872.8341615093144, 911.676354894013, 918.4305730447132, 924.560833604036, 924.5885580916495, 928.7213835849252, 915.1077699243218, 916.1845957098188, 916.9266458683289, 918.715198288526, 918.7381596245104, 532.8374845672068, 642.6915080761214, 678.5080816408114, 679.1612358736955, 680.9285025072346, 14806.185659788707, 14883.553493395108, 14942.925285373503, 14947.0, 14967.625809354853, 11807.350595717233, 13655.248961307125, 13984.720305593832, 14135.64660883486, 14228.857711422857, 12231.449584768618, 12562.246265224116, 12664.74670506594, 12762.298073605947, 12855.421506032251, 4530.093981203772, 4546.068056048535, 4547.658925580562, 4737.218358970766, 4760.309755085528, 111720.4413977933, 111833.0, 112174.0, 114751.0, 118173.0, 9141.405808622418, 10942.398168100832, 10966.0, 11160.616608652412, 11388.373639449745, 21881.562368752537, 21942.0, 21994.670079948814, 22039.889800551216, 22161.778382216293, 6624.7681331154145, 7768.495047821873, 7782.805429864298, 7978.202179781975, 8045.517268963881, 4406.206882761134, 4451.5103338632725, 4487.596116349544, 4548.249538826123, 4685.484596694361, 3322.3853587085982, 3430.0567343980106, 3440.9827950860085, 3444.380011597886, 3446.8104254266254, 633.6926590603554, 637.9234491860914, 643.7481767884782, 663.1213641924373, 663.5620490476263, 604.5949214026613, 618.6999305336908, 618.848382146377, 629.2543336146681, 632.9841753956096, 8336.749897503085, 9028.142326478974, 9032.32257580684, 9205.447673139633, 9220.581248020224, 579.3337661689092, 598.6976576828673, 602.8492876780805, 615.2862679292, 649.7888186339364, 4352.542982986741, 4370.453693288339, 4380.780960952004, 4382.890427739332, 4470.709403888802, 139876.0, 142813.0, 147042.0, 157750.0, 164306.0, 139511.0, 142231.2888435551, 143951.0, 144142.55857441502, 145306.0, 119527.40236298936, 121264.99999999824, 121368.39315803365, 122978.0, 125727.0, 7157.57054576727, 7973.923520324914, 8004.279614834692, 8526.317894568296, 8670.523121228392, 53483.732581336844, 64535.0, 66247.0, 66840.65535740161, 67884.0, 71449.0, 100301.49849250707, 100766.4961675187, 100844.0, 102282.4657630151, 11116.610918618133, 11486.770264594828, 11526.193166478266, 11556.826647600124, 11698.824517632413, 12010.099242557018, 12043.879561204627, 12264.509419623115, 12428.56500022495, 12571.308578028296, 11434.485448154686, 11590.826137607773, 11670.94164529172, 11907.880921190677, 12320.630381088582, 11694.590689325832, 11698.590549330733, 11995.340256285988, 12059.457324420599, 12261.526232114538, 11459.885401145882, 11549.942250288696, 11857.703557410789, 11882.940585297363, 12069.0, 9620.278479114235, 9843.409395436296, 10202.489875506344, 10254.948725256327, 10285.2800303981, 8601.827963440572, 9894.950525247328, 9895.406275623485, 9912.702618921156, 9943.950280248553], "value": 7632.241055516357, "unit": "score"}], "name": "dromaeo_css", "value": 7332.5050153304755, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 131.0, 120.0, 164.0, 123.0, 113.0, 114.0, 112.0, 113.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 128.0, 161.0, 109.0, 128.0, 130.0, 108.0, 160.0, 108.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 182.0, 180.0, 184.0, 184.0, 182.0, 183.0, 182.0, 181.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 75.0, 152.0, 73.0, 83.0, 80.0, 84.0, 147.0, 58.0], "value": 90.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 134.0, 70.0, 70.0, 80.0, 117.0, 68.0, 71.0, 116.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 89.0, 85.0, 87.0, 84.0, 89.0, 129.0, 90.0, 86.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 95.0, 96.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 92.0, 89.0, 86.0, 85.0, 103.0, 141.0, 87.0, 85.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 54.0, 53.0, 53.0, 55.0, 53.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 66.0, 76.0, 74.0, 78.0, 73.0, 69.0, 70.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 84.0, 86.0, 87.0, 86.0, 83.0, 84.0, 86.0, 85.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 138.0, 138.0, 140.0, 127.0, 142.0, 155.0, 143.0, 177.0, 140.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 170.0, 169.0, 170.0, 170.0, 166.0, 180.0, 173.0, 171.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 66.0, 65.0, 59.0, 72.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1483.7, "alertThreshold": 2.0}]} 09:18:48 INFO - Return code: 0 09:18:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:18:48 INFO - # TBPL SUCCESS # 09:18:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:48 INFO - 2016-08-26 09:18:48.808 screenresolution[1664:10273] starting screenresolution argv=screenresolution get 09:18:48 INFO - 2016-08-26 09:18:48.811 screenresolution[1664:10273] Display 0: 1600x1200x32@60 09:18:48 INFO - 2016-08-26 09:18:48.817 screenresolution[1665:10276] starting screenresolution argv=screenresolution list 09:18:48 INFO - Available Modes on Display 0 09:18:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:49 INFO - Intel Iris: 09:18:49 INFO - Chipset Model: Intel Iris 09:18:49 INFO - Type: GPU 09:18:49 INFO - Bus: Built-In 09:18:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:49 INFO - Vendor: Intel (0x8086) 09:18:49 INFO - Device ID: 0x0a2e 09:18:49 INFO - Revision ID: 0x0009 09:18:49 INFO - Displays: 09:18:49 INFO - Display: 09:18:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:49 INFO - Display Serial Number: 1600x1200 60 09:18:49 INFO - Main Display: Yes 09:18:49 INFO - Mirror: Off 09:18:49 INFO - Online: Yes 09:18:49 INFO - Rotation: Supported 09:18:49 INFO - Return code: 0 09:18:49 INFO - Running post-action listener: _resource_record_post_action 09:18:49 INFO - [mozharness: 2016-08-26 16:18:49.053455Z] Finished run-tests step (success) 09:18:49 INFO - Running post-run listener: _resource_record_post_run 09:18:49 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37270528; Write bytes: 707674112; Read time: 238; Write time: 2028 09:18:49 INFO - TinderboxPrint: CPU usage
53.5% 09:18:49 INFO - TinderboxPrint: I/O read bytes / time
37,270,528 / 238 09:18:49 INFO - TinderboxPrint: I/O write bytes / time
707,674,112 / 2,028 09:18:49 INFO - TinderboxPrint: CPU idle
2,255.9 (46.5%) 09:18:49 INFO - TinderboxPrint: CPU system
967.7 (19.9%) 09:18:49 INFO - TinderboxPrint: CPU user
1,630.1 (33.6%) 09:18:49 INFO - TinderboxPrint: Swap in / out
444,026,880 / 0 09:18:49 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389280256; Write bytes: 384276480; Read time: 29418; Write time: 834 09:18:49 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36307968; Write bytes: 297162752; Read time: 226; Write time: 1117 09:18:49 INFO - Running post-run listener: _upload_blobber_files 09:18:49 INFO - Blob upload gear active. 09:18:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:18:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:18:49 INFO - (blobuploader) - INFO - Open directory for files ... 09:18:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:18:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:18:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:51 INFO - (blobuploader) - INFO - Done attempting. 09:18:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:18:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:18:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:52 INFO - (blobuploader) - INFO - Done attempting. 09:18:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:18:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:57 INFO - (blobuploader) - INFO - Done attempting. 09:18:57 INFO - (blobuploader) - INFO - Iteration through files over. 09:18:57 INFO - Return code: 0 09:18:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:18:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:18:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/505cbda5881325c704c3325b900351b0a500ee53af0dd873d6cf92320f8abf28f239dc203be09bde0f510feb3aed630b0a2ad4706c8b85f43434a32c49a5948f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b70c1ad4a61034f89907258850afbc37aaa5bde60033c16007e903ec7c11d131142ca5a0f70b58d28a1e8e7f73fad315135683fb70450e8a47f461975aa3c347", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d847a6342de44de7308a1c1c91622ad80722b58af0219e531b43362a78f183b76d03814a5396bdef0559a4563eaf0795af025d05ddc93c1f268efb3d38cf6f3"} 09:18:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:18:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:18:57 INFO - Contents: 09:18:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/505cbda5881325c704c3325b900351b0a500ee53af0dd873d6cf92320f8abf28f239dc203be09bde0f510feb3aed630b0a2ad4706c8b85f43434a32c49a5948f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b70c1ad4a61034f89907258850afbc37aaa5bde60033c16007e903ec7c11d131142ca5a0f70b58d28a1e8e7f73fad315135683fb70450e8a47f461975aa3c347", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d847a6342de44de7308a1c1c91622ad80722b58af0219e531b43362a78f183b76d03814a5396bdef0559a4563eaf0795af025d05ddc93c1f268efb3d38cf6f3"} 09:18:57 INFO - Running post-run listener: copy_logs_to_upload_dir 09:18:57 INFO - Copying logs to upload dir... 09:18:57 INFO - mkdir: /builds/slave/test/build/upload/logs 09:18:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.078444 ========= master_lag: 2.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-08-26 09:18:59.627565) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-26 09:18:59.633360) ========= 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.HNlToO1u4C/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.RlsYrENLUv/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/505cbda5881325c704c3325b900351b0a500ee53af0dd873d6cf92320f8abf28f239dc203be09bde0f510feb3aed630b0a2ad4706c8b85f43434a32c49a5948f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b70c1ad4a61034f89907258850afbc37aaa5bde60033c16007e903ec7c11d131142ca5a0f70b58d28a1e8e7f73fad315135683fb70450e8a47f461975aa3c347", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d847a6342de44de7308a1c1c91622ad80722b58af0219e531b43362a78f183b76d03814a5396bdef0559a4563eaf0795af025d05ddc93c1f268efb3d38cf6f3"} build_url:https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011157 build_url: 'https://queue.taskcluster.net/v1/task/fU3JL409TSa0FKyzLGuDxA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/505cbda5881325c704c3325b900351b0a500ee53af0dd873d6cf92320f8abf28f239dc203be09bde0f510feb3aed630b0a2ad4706c8b85f43434a32c49a5948f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b70c1ad4a61034f89907258850afbc37aaa5bde60033c16007e903ec7c11d131142ca5a0f70b58d28a1e8e7f73fad315135683fb70450e8a47f461975aa3c347", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d847a6342de44de7308a1c1c91622ad80722b58af0219e531b43362a78f183b76d03814a5396bdef0559a4563eaf0795af025d05ddc93c1f268efb3d38cf6f3"}' ========= master_lag: 1.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-26 09:19:00.922544) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:19:00.922875) ========= 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.HNlToO1u4C/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.RlsYrENLUv/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005101 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:19:01.070710) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:19:01.075121) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:19:01.075662) ========= ========= Total master_lag: 3.89 =========