builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0251 starttime: 1472134957.43 results: success (0) buildid: 20160825051324 builduid: e3c9cd48a0554d64901b7e1202cfc323 revision: 9467a563a01b466fdca07c0495de7bcfd0a66215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.435277) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.435836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.436231) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.460889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.461551) ========= 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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-25 07:22:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.10M=0.001s 2016-08-25 07:22:37 (8.10 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135284 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.621715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.622092) ========= 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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.020979 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:37.676728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 07:22:37.677846) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9467a563a01b466fdca07c0495de7bcfd0a66215 --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 9467a563a01b466fdca07c0495de7bcfd0a66215 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0x8b5ogS0O/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.BQlGOrCNcf/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-25 07:22:37,765 truncating revision to first 12 chars 2016-08-25 07:22:37,766 Setting DEBUG logging. 2016-08-25 07:22:37,766 attempt 1/10 2016-08-25 07:22:37,766 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9467a563a01b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 07:22:39,009 unpacking tar archive at: mozilla-central-9467a563a01b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.505075 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 07:22:39.209228) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:39.209580) ========= script_repo_revision: 9467a563a01b466fdca07c0495de7bcfd0a66215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:39.214099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:39.214415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 07:22:39.237124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-25 07:22:39.237518) ========= /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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:22:39 INFO - MultiFileLogger online at 20160825 07:22:39 in /builds/slave/test 07:22: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 07:22:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:22:39 INFO - {'append_to_log': False, 07:22:39 INFO - 'base_work_dir': '/builds/slave/test', 07:22:39 INFO - 'blob_upload_branch': 'Firefox', 07:22:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:22:39 INFO - 'branch': 'Firefox', 07:22:39 INFO - 'buildbot_json_path': 'buildprops.json', 07:22:39 INFO - 'config_files': ('talos/mac_config.py',), 07:22:39 INFO - 'default_actions': ('clobber', 07:22:39 INFO - 'read-buildbot-config', 07:22:39 INFO - 'download-and-extract', 07:22:39 INFO - 'populate-webroot', 07:22:39 INFO - 'create-virtualenv', 07:22:39 INFO - 'install', 07:22:39 INFO - 'run-tests'), 07:22:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:22:39 INFO - 'download_minidump_stackwalk': True, 07:22:39 INFO - 'download_symbols': 'ondemand', 07:22:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:22:39 INFO - 'tooltool.py': '/tools/tooltool.py', 07:22:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:22:39 INFO - '/tools/misc-python/virtualenv.py')}, 07:22:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:22:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:22:39 INFO - 'installer_path': 'installer.exe', 07:22:39 INFO - 'log_level': 'info', 07:22:39 INFO - 'log_name': 'talos', 07:22:39 INFO - 'log_to_console': True, 07:22:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:22:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:22:39 INFO - 'opt_config_files': (), 07:22:39 INFO - 'pip_index': False, 07:22:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:22:39 INFO - 'cmd': ('bash', 07:22:39 INFO - '-c', 07:22:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:22:39 INFO - 'enabled': True, 07:22:39 INFO - 'halt_on_failure': False, 07:22:39 INFO - 'name': 'check_screen_resolution'},), 07:22:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:22:39 INFO - 'cmd': ('bash', 07:22:39 INFO - '-c', 07:22:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:22:39 INFO - 'enabled': True, 07:22:39 INFO - 'halt_on_failure': False, 07:22:39 INFO - 'name': 'check_screen_resolution'},), 07:22:39 INFO - 'run_cmd_checks_enabled': True, 07:22:39 INFO - 'sps_profile': False, 07:22:39 INFO - 'sps_profile_interval': 0, 07:22:39 INFO - 'suite': 'dromaeojs', 07:22:39 INFO - 'system_bits': '32', 07:22:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:22:39 INFO - 'title': 't-yosemite-r7-0251', 07:22:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:22:39 INFO - 'use_talos_json': True, 07:22:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:22:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:22:39 INFO - 'work_dir': 'build'} 07:22:39 INFO - [mozharness: 2016-08-25 14:22:39.433508Z] Running clobber step. 07:22:39 INFO - Running pre-action listener: _resource_record_pre_action 07:22:39 INFO - Running main action method: clobber 07:22:39 INFO - rmtree: /builds/slave/test/build 07:22:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:22:40 INFO - Running post-action listener: _resource_record_post_action 07:22:40 INFO - [mozharness: 2016-08-25 14:22:40.839138Z] Finished clobber step (success) 07:22:40 INFO - [mozharness: 2016-08-25 14:22:40.839337Z] Running read-buildbot-config step. 07:22:40 INFO - Running pre-action listener: _resource_record_pre_action 07:22:40 INFO - Running main action method: read_buildbot_config 07:22:40 INFO - Using buildbot properties: 07:22:40 INFO - { 07:22:40 INFO - "project": "", 07:22:40 INFO - "product": "firefox", 07:22:40 INFO - "script_repo_revision": "production", 07:22:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:22:40 INFO - "repository": "", 07:22:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:22:40 INFO - "buildid": "20160825051324", 07:22:40 INFO - "pgo_build": "False", 07:22:40 INFO - "basedir": "/builds/slave/test", 07:22:40 INFO - "buildnumber": 31, 07:22:40 INFO - "slavename": "t-yosemite-r7-0251", 07:22:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:22:40 INFO - "platform": "yosemite_r7", 07:22:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:22:40 INFO - "branch": "mozilla-central", 07:22:40 INFO - "repo_path": "mozilla-central", 07:22:40 INFO - "revision": "9467a563a01b466fdca07c0495de7bcfd0a66215", 07:22:40 INFO - "stage_platform": "macosx64", 07:22:40 INFO - "builduid": "e3c9cd48a0554d64901b7e1202cfc323", 07:22:40 INFO - "slavebuilddir": "test" 07:22:40 INFO - } 07:22:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:22:40 INFO - Running post-action listener: _resource_record_post_action 07:22:40 INFO - [mozharness: 2016-08-25 14:22:40.842806Z] Finished read-buildbot-config step (success) 07:22:40 INFO - [mozharness: 2016-08-25 14:22:40.842928Z] Running download-and-extract step. 07:22:40 INFO - Running pre-action listener: _resource_record_pre_action 07:22:40 INFO - Running main action method: download_and_extract 07:22:40 INFO - mkdir: /builds/slave/test/build/tests 07:22:40 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')]} 07:22:40 INFO - trying https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:22:40 INFO - Downloading https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:42 INFO - Downloaded 1199 bytes. 07:22:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:22:42 INFO - Using the following test package requirements: 07:22:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:22:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:22:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:22:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'jsshell-mac.zip'], 07:22:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:22:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:22:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:22:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:22:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:22:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:22:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:22:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:22: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')]} 07:22:42 INFO - trying https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:22:42 INFO - Downloading https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:43 INFO - Downloaded 16345903 bytes. 07:22: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 07:22: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 07:22: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')]} 07:22:50 INFO - trying https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:22:50 INFO - Downloading https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:51 INFO - Downloaded 16345903 bytes. 07:22: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 07:22: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')]} 07:22:58 INFO - trying https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:22:58 INFO - Downloading https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:23:00 INFO - Downloaded 14413981 bytes. 07:23:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 07:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:00 INFO - trying https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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 07:23:05 INFO - Downloaded 111558857 bytes. 07:23:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:23:05 INFO - mkdir: /builds/slave/test/properties 07:23:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:23:05 INFO - Writing to file /builds/slave/test/properties/build_url 07:23:05 INFO - Contents: 07:23:05 INFO - build_url:https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:23:06 INFO - Running post-action listener: _resource_record_post_action 07:23:06 INFO - Running post-action listener: set_extra_try_arguments 07:23:06 INFO - [mozharness: 2016-08-25 14:23:06.538488Z] Finished download-and-extract step (success) 07:23:06 INFO - [mozharness: 2016-08-25 14:23:06.538606Z] Running populate-webroot step. 07:23:06 INFO - Running pre-action listener: _resource_record_pre_action 07:23:06 INFO - Running main action method: populate_webroot 07:23:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:23:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:23:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:23:06 INFO - '--tppagecycles', 07:23:06 INFO - '7'], 07:23:06 INFO - 'tests': ['tsvgm']}}, 07:23:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:23:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:23:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:23:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:23:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:23:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:23:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['damp', 'tps']}, 07:23:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'tests': ['damp', 'tps']}, 07:23:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['dromaeo_dom']}, 07:23:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:23:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['basic_compositor_video']}, 07:23:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:23:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['a11yr', 07:23:06 INFO - 'ts_paint', 07:23:06 INFO - 'tpaint', 07:23:06 INFO - 'sessionrestore', 07:23:06 INFO - 'sessionrestore_no_auto_restore', 07:23:06 INFO - 'tabpaint']}, 07:23:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:23:06 INFO - 'ts_paint', 07:23:06 INFO - 'tpaint', 07:23:06 INFO - 'sessionrestore', 07:23:06 INFO - 'sessionrestore_no_auto_restore', 07:23:06 INFO - 'tabpaint']}, 07:23:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:23:06 INFO - 'ts_paint', 07:23:06 INFO - 'tpaint', 07:23:06 INFO - 'sessionrestore', 07:23:06 INFO - 'sessionrestore_no_auto_restore', 07:23:06 INFO - 'tabpaint']}, 07:23:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:23:06 INFO - 'ts_paint', 07:23:06 INFO - 'tpaint', 07:23:06 INFO - 'sessionrestore', 07:23:06 INFO - 'sessionrestore_no_auto_restore', 07:23:06 INFO - 'tabpaint']}, 07:23:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['a11yr', 07:23:06 INFO - 'ts_paint', 07:23:06 INFO - 'tpaint', 07:23:06 INFO - 'sessionrestore', 07:23:06 INFO - 'sessionrestore_no_auto_restore', 07:23:06 INFO - 'tabpaint']}, 07:23:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['a11yr', 07:23:06 INFO - 'ts_paint', 07:23:06 INFO - 'tpaint', 07:23:06 INFO - 'sessionrestore', 07:23:06 INFO - 'sessionrestore_no_auto_restore', 07:23:06 INFO - 'tabpaint']}, 07:23:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['tsvgx', 07:23:06 INFO - 'tsvgr_opacity', 07:23:06 INFO - 'tart', 07:23:06 INFO - 'tscrollx', 07:23:06 INFO - 'cart']}, 07:23:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:23:06 INFO - 'tsvgr_opacity', 07:23:06 INFO - 'tart', 07:23:06 INFO - 'tscrollx', 07:23:06 INFO - 'cart']}, 07:23:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'talos_options': ['--disable-e10s'], 07:23:06 INFO - 'tests': ['tp5o']}, 07:23:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'tests': ['tp5o']}, 07:23:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'talos_options': ['--disable-e10s', 07:23:06 INFO - '--xperf_path', 07:23:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:23:06 INFO - 'tests': ['tp5n']}, 07:23:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:23:06 INFO - 'talos_options': ['--xperf_path', 07:23:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:23:06 INFO - 'tests': ['tp5n']}}, 07:23:06 INFO - 'talos.zip': {'path': '', 07:23:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:23:06 INFO - Running post-action listener: _resource_record_post_action 07:23:06 INFO - [mozharness: 2016-08-25 14:23:06.556999Z] Finished populate-webroot step (success) 07:23:06 INFO - [mozharness: 2016-08-25 14:23:06.557104Z] Running create-virtualenv step. 07:23:06 INFO - Running pre-action listener: _resource_record_pre_action 07:23:06 INFO - Running main action method: create_virtualenv 07:23:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:23:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:23:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:23:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:23:06 INFO - Using real prefix '/tools/python27' 07:23:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:23:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:23:10 INFO - Installing pip.................done. 07:23:10 INFO - Return code: 0 07:23:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:10 INFO - 'HOME': '/Users/cltbld', 07:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:10 INFO - 'LOGNAME': 'cltbld', 07:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:10 INFO - 'MOZ_NO_REMOTE': '1', 07:23:10 INFO - 'NO_EM_RESTART': '1', 07:23:10 INFO - 'PAGER': '/bin/cat', 07:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:10 INFO - 'PWD': '/builds/slave/test', 07:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:10 INFO - 'SHELL': '/bin/bash', 07:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:10 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:10 INFO - 'USER': 'cltbld', 07:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:10 INFO - 'XPC_FLAGS': '0x0', 07:23:10 INFO - 'XPC_SERVICE_NAME': '0', 07:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:11 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 07:23:11 INFO - Cleaning up... 07:23:11 INFO - Return code: 0 07:23:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:11 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 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:11 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 07:23:11 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 07:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:11 INFO - 'HOME': '/Users/cltbld', 07:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:11 INFO - 'LOGNAME': 'cltbld', 07:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:11 INFO - 'MOZ_NO_REMOTE': '1', 07:23:11 INFO - 'NO_EM_RESTART': '1', 07:23:11 INFO - 'PAGER': '/bin/cat', 07:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:11 INFO - 'PWD': '/builds/slave/test', 07:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:11 INFO - 'SHELL': '/bin/bash', 07:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:11 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:11 INFO - 'USER': 'cltbld', 07:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:11 INFO - 'XPC_FLAGS': '0x0', 07:23:11 INFO - 'XPC_SERVICE_NAME': '0', 07:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:11 INFO - Downloading/unpacking psutil>=3.1.1 07:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:23:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:23:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:23:14 INFO - Installing collected packages: psutil 07:23:14 INFO - Running setup.py install for psutil 07:23:14 INFO - building 'psutil._psutil_osx' extension 07:23:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:23:15 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 07:23:15 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 07:23:15 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 07:23:15 INFO - building 'psutil._psutil_posix' extension 07:23:15 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 07:23:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:23:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:23:15 INFO - ^ 07:23:15 INFO - 1 warning generated. 07:23:15 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 07:23:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:23:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:23:15 INFO - Successfully installed psutil 07:23:15 INFO - Cleaning up... 07:23:15 INFO - Return code: 0 07:23:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:23: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')]} 07:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23: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')]} 07:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:23:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:23:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:15 INFO - 'HOME': '/Users/cltbld', 07:23:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:15 INFO - 'LOGNAME': 'cltbld', 07:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:15 INFO - 'MOZ_NO_REMOTE': '1', 07:23:15 INFO - 'NO_EM_RESTART': '1', 07:23:15 INFO - 'PAGER': '/bin/cat', 07:23:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:15 INFO - 'PWD': '/builds/slave/test', 07:23:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:15 INFO - 'SHELL': '/bin/bash', 07:23:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:15 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:15 INFO - 'USER': 'cltbld', 07:23:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:15 INFO - 'XPC_FLAGS': '0x0', 07:23:15 INFO - 'XPC_SERVICE_NAME': '0', 07:23:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:23:18 INFO - Installing collected packages: mozsystemmonitor 07:23:18 INFO - Running setup.py install for mozsystemmonitor 07:23:18 INFO - Successfully installed mozsystemmonitor 07:23:18 INFO - Cleaning up... 07:23:18 INFO - Return code: 0 07:23:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:18 INFO - 'HOME': '/Users/cltbld', 07:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:18 INFO - 'LOGNAME': 'cltbld', 07:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:18 INFO - 'MOZ_NO_REMOTE': '1', 07:23:18 INFO - 'NO_EM_RESTART': '1', 07:23:18 INFO - 'PAGER': '/bin/cat', 07:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:18 INFO - 'PWD': '/builds/slave/test', 07:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:18 INFO - 'SHELL': '/bin/bash', 07:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:18 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:18 INFO - 'USER': 'cltbld', 07:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:18 INFO - 'XPC_FLAGS': '0x0', 07:23:18 INFO - 'XPC_SERVICE_NAME': '0', 07:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:18 INFO - Downloading/unpacking jsonschema==2.5.1 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:23:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:23:21 INFO - warning: no files found matching '*.txt' 07:23:21 INFO - no previously-included directories found matching 'build' 07:23:21 INFO - no previously-included directories found matching 'dist' 07:23:21 INFO - no previously-included directories found matching '.git*' 07:23:21 INFO - Installing collected packages: jsonschema, functools32 07:23:21 INFO - Running setup.py install for functools32 07:23:21 INFO - warning: no files found matching '*.txt' 07:23:21 INFO - no previously-included directories found matching 'build' 07:23:21 INFO - no previously-included directories found matching 'dist' 07:23:21 INFO - no previously-included directories found matching '.git*' 07:23:22 INFO - Successfully installed jsonschema functools32 07:23:22 INFO - Cleaning up... 07:23:22 INFO - Return code: 0 07:23:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:23:22 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')]} 07:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:22 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')]} 07:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:22 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 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:22 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 07:23:22 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 07:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:22 INFO - 'HOME': '/Users/cltbld', 07:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:22 INFO - 'LOGNAME': 'cltbld', 07:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:22 INFO - 'MOZ_NO_REMOTE': '1', 07:23:22 INFO - 'NO_EM_RESTART': '1', 07:23:22 INFO - 'PAGER': '/bin/cat', 07:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:22 INFO - 'PWD': '/builds/slave/test', 07:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:22 INFO - 'SHELL': '/bin/bash', 07:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:22 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:22 INFO - 'USER': 'cltbld', 07:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:22 INFO - 'XPC_FLAGS': '0x0', 07:23:22 INFO - 'XPC_SERVICE_NAME': '0', 07:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:23:22 INFO - Cleaning up... 07:23:22 INFO - Return code: 0 07:23:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:23:22 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')]} 07:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:22 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')]} 07:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:22 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 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:22 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 07:23:22 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 07:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:22 INFO - 'HOME': '/Users/cltbld', 07:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:22 INFO - 'LOGNAME': 'cltbld', 07:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:22 INFO - 'MOZ_NO_REMOTE': '1', 07:23:22 INFO - 'NO_EM_RESTART': '1', 07:23:22 INFO - 'PAGER': '/bin/cat', 07:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:22 INFO - 'PWD': '/builds/slave/test', 07:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:22 INFO - 'SHELL': '/bin/bash', 07:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:22 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:22 INFO - 'USER': 'cltbld', 07:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:22 INFO - 'XPC_FLAGS': '0x0', 07:23:22 INFO - 'XPC_SERVICE_NAME': '0', 07:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:22 INFO - Downloading/unpacking blobuploader==1.2.4 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23: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 07:23:24 INFO - Downloading blobuploader-1.2.4.tar.gz 07:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:23:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:23:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:26 INFO - Downloading docopt-0.6.1.tar.gz 07:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:23:26 INFO - Installing collected packages: blobuploader, requests, docopt 07:23:26 INFO - Running setup.py install for blobuploader 07:23:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:23:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:23:26 INFO - Running setup.py install for requests 07:23:26 INFO - Running setup.py install for docopt 07:23:27 INFO - Successfully installed blobuploader requests docopt 07:23:27 INFO - Cleaning up... 07:23:27 INFO - Return code: 0 07:23:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:27 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')]} 07:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:27 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')]} 07:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:27 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 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:27 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 07:23:27 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 07:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:27 INFO - 'HOME': '/Users/cltbld', 07:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:27 INFO - 'LOGNAME': 'cltbld', 07:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:27 INFO - 'MOZ_NO_REMOTE': '1', 07:23:27 INFO - 'NO_EM_RESTART': '1', 07:23:27 INFO - 'PAGER': '/bin/cat', 07:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:27 INFO - 'PWD': '/builds/slave/test', 07:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:27 INFO - 'SHELL': '/bin/bash', 07:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:27 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:27 INFO - 'USER': 'cltbld', 07:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:27 INFO - 'XPC_FLAGS': '0x0', 07:23:27 INFO - 'XPC_SERVICE_NAME': '0', 07:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:23:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-gm4Rc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:23:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-g1YAgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:23:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-OokGnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:23:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-X9bS3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:23:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8O5AX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:23:27 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-gZSP5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-IASfUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-iN3BH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Tt2uxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-x9B8wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-qg9_ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-1QRDms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-xkGB16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-h5GEJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:28 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-nHJi_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:23:29 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-pIEAvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:23:29 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-HJrWUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:23:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:23:29 INFO - Running setup.py install for manifestparser 07:23:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:23:29 INFO - Running setup.py install for mozcrash 07:23:29 INFO - Running setup.py install for mozdebug 07:23:29 INFO - Running setup.py install for mozdevice 07:23:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:23:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:23:29 INFO - Running setup.py install for mozfile 07:23:29 INFO - Running setup.py install for mozhttpd 07:23:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Running setup.py install for mozinfo 07:23:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Running setup.py install for mozInstall 07:23:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Running setup.py install for mozleak 07:23:30 INFO - Running setup.py install for mozlog 07:23:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Running setup.py install for moznetwork 07:23:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:23:30 INFO - Running setup.py install for mozprocess 07:23:31 INFO - Running setup.py install for mozprofile 07:23:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:23:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:23:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:23:31 INFO - Running setup.py install for mozrunner 07:23:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:23:31 INFO - Running setup.py install for mozscreenshot 07:23:31 INFO - Running setup.py install for moztest 07:23:31 INFO - Running setup.py install for mozversion 07:23:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:23:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:23:31 INFO - Cleaning up... 07:23:31 INFO - Return code: 0 07:23:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:31 INFO - 'HOME': '/Users/cltbld', 07:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:31 INFO - 'LOGNAME': 'cltbld', 07:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:31 INFO - 'MOZ_NO_REMOTE': '1', 07:23:31 INFO - 'NO_EM_RESTART': '1', 07:23:31 INFO - 'PAGER': '/bin/cat', 07:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:31 INFO - 'PWD': '/builds/slave/test', 07:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:31 INFO - 'SHELL': '/bin/bash', 07:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:31 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:31 INFO - 'USER': 'cltbld', 07:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:31 INFO - 'XPC_FLAGS': '0x0', 07:23:31 INFO - 'XPC_SERVICE_NAME': '0', 07:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-FPkCc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:23:32 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)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-_5h2XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:23:32 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)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-osd1xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:23:32 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)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-f98xHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:23:32 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)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-9KF3Dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-9Bnt1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-VpAh5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Rrn1u5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:23:32 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8ZcTOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:23:33 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)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ZfJpU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:23:33 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)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-_UK1Z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:23:33 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)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-NDuyFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:23:33 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)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-BTce0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:23:33 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)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-BbkSDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-2gxzua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-iKvOjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:23:33 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Sfa1aL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:23:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:23:36 INFO - Downloading blessings-1.6.tar.gz 07:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:23:36 INFO - Installing collected packages: blessings 07:23:36 INFO - Running setup.py install for blessings 07:23:36 INFO - Successfully installed blessings 07:23:36 INFO - Cleaning up... 07:23:36 INFO - Return code: 0 07:23:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:23:37 INFO - Reading from file tmpfile_stdout 07:23:37 INFO - Current package versions: 07:23:37 INFO - blessings == 1.6 07:23:37 INFO - blobuploader == 1.2.4 07:23:37 INFO - docopt == 0.6.1 07:23:37 INFO - functools32 == 3.2.3-2 07:23:37 INFO - jsonschema == 2.5.1 07:23:37 INFO - manifestparser == 1.1 07:23:37 INFO - mozInstall == 1.12 07:23:37 INFO - mozcrash == 0.17 07:23:37 INFO - mozdebug == 0.1 07:23:37 INFO - mozdevice == 0.48 07:23:37 INFO - mozfile == 1.2 07:23:37 INFO - mozhttpd == 0.7 07:23:37 INFO - mozinfo == 0.9 07:23:37 INFO - mozleak == 0.1 07:23:37 INFO - mozlog == 3.3 07:23:37 INFO - moznetwork == 0.27 07:23:37 INFO - mozprocess == 0.23 07:23:37 INFO - mozprofile == 0.28 07:23:37 INFO - mozrunner == 6.12 07:23:37 INFO - mozscreenshot == 0.1 07:23:37 INFO - mozsystemmonitor == 0.3 07:23:37 INFO - moztest == 0.7 07:23:37 INFO - mozversion == 1.4 07:23:37 INFO - psutil == 3.1.1 07:23:37 INFO - requests == 1.2.3 07:23:37 INFO - wsgiref == 0.1.2 07:23:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:37 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 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:37 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 07:23:37 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 07:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:37 INFO - 'HOME': '/Users/cltbld', 07:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:37 INFO - 'LOGNAME': 'cltbld', 07:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:37 INFO - 'MOZ_NO_REMOTE': '1', 07:23:37 INFO - 'NO_EM_RESTART': '1', 07:23:37 INFO - 'PAGER': '/bin/cat', 07:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:37 INFO - 'PWD': '/builds/slave/test', 07:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:37 INFO - 'SHELL': '/bin/bash', 07:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:37 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:37 INFO - 'USER': 'cltbld', 07:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:37 INFO - 'XPC_FLAGS': '0x0', 07:23:37 INFO - 'XPC_SERVICE_NAME': '0', 07:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 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)) 07:23:37 INFO - Cleaning up... 07:23:37 INFO - Return code: 0 07:23:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:23:37 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 0x103c02a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c97618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88cb59b420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, '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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 07:23:37 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 07:23:37 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 07:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:37 INFO - 'HOME': '/Users/cltbld', 07:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:37 INFO - 'LOGNAME': 'cltbld', 07:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:37 INFO - 'MOZ_NO_REMOTE': '1', 07:23:37 INFO - 'NO_EM_RESTART': '1', 07:23:37 INFO - 'PAGER': '/bin/cat', 07:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:37 INFO - 'PWD': '/builds/slave/test', 07:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:37 INFO - 'SHELL': '/bin/bash', 07:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:23:37 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:37 INFO - 'USER': 'cltbld', 07:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:37 INFO - 'XPC_FLAGS': '0x0', 07:23:37 INFO - 'XPC_SERVICE_NAME': '0', 07:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:23:37 INFO - Cleaning up... 07:23:37 INFO - Return code: 0 07:23:37 INFO - Running post-action listener: _resource_record_post_action 07:23:37 INFO - Running post-action listener: _start_resource_monitoring 07:23:37 INFO - Starting resource monitoring. 07:23:37 INFO - [mozharness: 2016-08-25 14:23:37.604348Z] Finished create-virtualenv step (success) 07:23:37 INFO - [mozharness: 2016-08-25 14:23:37.605382Z] Running install step. 07:23:37 INFO - Running pre-action listener: _resource_record_pre_action 07:23:37 INFO - Running main action method: install 07:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:23:37 INFO - Reading from file tmpfile_stdout 07:23:37 INFO - Detecting whether we're running mozinstall >=1.0... 07:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:23:37 INFO - Reading from file tmpfile_stdout 07:23:37 INFO - Output received: 07:23:37 INFO - Usage: mozinstall [options] installer 07:23:37 INFO - Options: 07:23:37 INFO - -h, --help show this help message and exit 07:23:37 INFO - -d DEST, --destination=DEST 07:23:37 INFO - Directory to install application into. [default: 07:23:37 INFO - "/builds/slave/test"] 07:23:37 INFO - --app=APP Application being installed. [default: firefox] 07:23:37 INFO - mkdir: /builds/slave/test/build/application 07:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:24:12 INFO - Reading from file tmpfile_stdout 07:24:12 INFO - Output received: 07:24:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:24:12 INFO - Running post-action listener: _resource_record_post_action 07:24:12 INFO - [mozharness: 2016-08-25 14:24:12.472067Z] Finished install step (success) 07:24:12 INFO - [mozharness: 2016-08-25 14:24:12.472193Z] Running run-tests step. 07:24:12 INFO - Running pre-action listener: _resource_record_pre_action 07:24:12 INFO - Running main action method: run_tests 07:24:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:24:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:24:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:24:12 INFO - 2016-08-25 07:24:12.486 screenresolution[2191:34855] starting screenresolution argv=screenresolution get 07:24:12 INFO - 2016-08-25 07:24:12.489 screenresolution[2191:34855] Display 0: 1600x1200x32@60 07:24:12 INFO - 2016-08-25 07:24:12.495 screenresolution[2192:34857] starting screenresolution argv=screenresolution list 07:24:12 INFO - Available Modes on Display 0 07:24:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:24:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:24:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:24:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:24:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:24:12 INFO - Intel Iris: 07:24:12 INFO - Chipset Model: Intel Iris 07:24:12 INFO - Type: GPU 07:24:12 INFO - Bus: Built-In 07:24:12 INFO - VRAM (Dynamic, Max): 1536 MB 07:24:12 INFO - Vendor: Intel (0x8086) 07:24:12 INFO - Device ID: 0x0a2e 07:24:12 INFO - Revision ID: 0x0009 07:24:12 INFO - Displays: 07:24:12 INFO - Display: 07:24:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:24:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:24:12 INFO - Display Serial Number: 1600x1200 60 07:24:12 INFO - Main Display: Yes 07:24:12 INFO - Mirror: Off 07:24:12 INFO - Online: Yes 07:24:12 INFO - Rotation: Supported 07:24:12 INFO - Return code: 0 07:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:24:12 INFO - Python 2.7.3 07:24:12 INFO - Return code: 0 07:24:12 INFO - grabbing minidump binary from tooltool 07:24: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')]} 07:24:12 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 0x103b66d30>, '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 0x1038cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b5eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:24:12 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 07:24:12 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 07:24:12 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 07:24:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:24:12 INFO - Return code: 0 07:24:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:24:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:24:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:24:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:24:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:24:12 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/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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 07:24:12 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/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --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 07:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0x8b5ogS0O/Render', 07:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:12 INFO - 'HOME': '/Users/cltbld', 07:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:12 INFO - 'LOGNAME': 'cltbld', 07:24:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:24:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:12 INFO - 'MOZ_NO_REMOTE': '1', 07:24:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:24:12 INFO - 'NO_EM_RESTART': '1', 07:24:12 INFO - 'PAGER': '/bin/cat', 07:24:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:12 INFO - 'PWD': '/builds/slave/test', 07:24:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:12 INFO - 'SHELL': '/bin/bash', 07:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BQlGOrCNcf/Listeners', 07:24:12 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 07:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:12 INFO - 'USER': 'cltbld', 07:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:12 INFO - 'XPC_FLAGS': '0x0', 07:24:12 INFO - 'XPC_SERVICE_NAME': '0', 07:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:12 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/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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 07:24:13 INFO - mozversion application_buildid: 20160825051324 07:24:13 INFO - mozversion application_changeset: 9467a563a01b466fdca07c0495de7bcfd0a66215 07:24:13 INFO - mozversion application_display_name: Nightly 07:24:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:24:13 INFO - mozversion application_name: Firefox 07:24:13 INFO - mozversion application_remotingname: firefox 07:24:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:24:13 INFO - mozversion application_vendor: Mozilla 07:24:13 INFO - mozversion application_version: 51.0a1 07:24:13 INFO - mozversion platform_buildid: 20160825051324 07:24:13 INFO - mozversion platform_changeset: 9467a563a01b466fdca07c0495de7bcfd0a66215 07:24:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:24:13 INFO - mozversion platform_version: 51.0a1 07:24:13 INFO - using testdate: 1472135053 07:24:13 INFO - actual date: 1472135053 07:24:13 INFO - starting webserver on 'localhost:64207' 07:24:13 INFO - SUITE-START | Running 2 tests 07:24:13 INFO - TEST-START | dromaeo_css 07:24:13 INFO - operating with platform_type : mac_ 07:24:13 INFO - Initialising browser for dromaeo_css test... 07:24:13 INFO - TEST-INFO | started process 2202 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp1XaTd2/profile http://localhost:64207/getInfo.html) 07:24:15 INFO - PROCESS | 2202 | __metrics Screen width/height:1600/1200 07:24:15 INFO - PROCESS | 2202 | colorDepth:24 07:24:15 INFO - PROCESS | 2202 | Browser inner width/height: 1024/689 07:24:15 INFO - PROCESS | 2202 | __metrics 07:24:15 INFO - PROCESS | 2202 | 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" 07:24:15 INFO - PROCESS | 2202 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:24:16 INFO - TEST-INFO | 2202: exit 0 07:24:16 INFO - Browser initialized. 07:24:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:24:16 INFO - TEST-INFO | started process 2204 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp1XaTd2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:24:28 INFO - PROCESS | 2204 | RSS: Main: 232062976 07:24:28 INFO - PROCESS | 2204 | 07:25:20 INFO - PROCESS | 2204 | 1472135120467 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] 07:27:31 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:64207/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64207/tests/dromaeo/cssquery-ext.html) 07:27:32 INFO - PROCESS | 2204 | RSS: Main: 349282304 07:27:32 INFO - PROCESS | 2204 | 07:30:35 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:64207/tests/dromaeo/cssquery-ext.html (next: http://localhost:64207/tests/dromaeo/cssquery-jquery.html) 07:30:36 INFO - PROCESS | 2204 | RSS: Main: 382828544 07:30:36 INFO - PROCESS | 2204 | 07:33:38 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:64207/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64207/tests/dromaeo/cssquery-mootools.html) 07:33:39 INFO - PROCESS | 2204 | RSS: Main: 358809600 07:33:39 INFO - PROCESS | 2204 | 07:36:41 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:64207/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64207/tests/dromaeo/cssquery-prototype.html) 07:36:42 INFO - PROCESS | 2204 | RSS: Main: 328736768 07:36:42 INFO - PROCESS | 2204 | 07:39:45 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:64207/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64207/tests/dromaeo/cssquery-yui.html) 07:39:46 INFO - PROCESS | 2204 | RSS: Main: 324571136 07:39:46 INFO - PROCESS | 2204 | 07:42:49 INFO - PROCESS | 2204 | Cycle 1(1): loaded http://localhost:64207/tests/dromaeo/cssquery-yui.html (next: http://localhost:64207/tests/dromaeo/cssquery-dojo.html) 07:42:50 INFO - PROCESS | 2204 | RSS: Main: 596283392 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | __start_tp_report 07:42:50 INFO - PROCESS | 2204 | _x_x_mozilla_page_load 07:42:50 INFO - PROCESS | 2204 | _x_x_mozilla_page_load_details 07:42:50 INFO - PROCESS | 2204 | |i|pagename|runs| 07:42:50 INFO - PROCESS | 2204 | |0;dojo.html;3299.538064670946;3320.086976081577;3345.464725643896;3365.0914253151664;3417.1798768295603;5640.4641559051925;5768.221290125832;5785.421457854212;5880.764769409224;5914.940850591493;5823.941760582393;5824.883502329951;5899.292084949801;5958.404159584039;5966.254218222723;5172.9224061639015;5181.222816577506;5271.604629652772;5284.656497327662;5367.865803354908;5317.813876514323;5370.758315875795;5371.7582708778;5384.40771515133;5407.540359069484;5171.3277273954145;5239.004589128053;5243.973780131113;5255.921161182604;5295.735213239361;5216.243644671539;5229.294045303874;5318.627696061278;5319.973400132974;5365.517103460707;5179.378474583074;5217.139172036609;5263.157894736862;5280.155175171993;5280.815171469018;5908.320543137551;5910.67491287979;5911.438413350767;5922.170896074553;5934.970325148347;6105.267367915834;6126.303932855845;6196.504279657616;6202;6203.31763506014;5360.563492696944;6218.968905155491;6239.500839932794;6285.371462853678;6290.182276304051;5988.461038506555;5991.191189189447;5996.5202783777195;5997.580169388189;6001.819945401645;6130.826205142326;6203.131561581382;6205.2553693557065;6268;6286.717097730613;6062.50279429517;6117.969410153009;6136.54971344134;6177.444030037228;6195.380461953769;6197.442230199304;6214.130021796952;6219.222597175353;6237.126802247689;6283.277423096402;6150.415710507584;6216;6223.875522489616;6237.095621135;6238.376162383816;6134.19168101403;6162.938370616236;6182.28903676074;6191.380861913773;6203.4416902479;5040.067587496325;5050.292958985745;5052.873678158002;5052.9747351263;5067.847964561069;4392.714473559208;4484.529124441952;4503.954960450419;4525.140223357552;4532.0256144929;5782.87233989368;5818.8254352369495;5853.7365818538265;5890.823275301748;5929.6145750526075;6203.193584833943;6213.844653883689;6222.471089957405;6227.034809604518;6227.439530442282;5855.560832937546;5867.970660146672;5872.5301975842685;5896.911546326808;5947.316058653224;5401.000814849265;5464.9180262296095;5472.069748142825;5513.3108361454815;5527.723613819293;6361.586496877688;6370.1718776558755;6373.362663733682;6375.617462952052;6398.584092034189;6109;6123.387661233841;6188.659623720559;6203.720832562455;6227.470664993345;6138.6336396323695;6268.341824108586;6281.811545653638;6321.620702757849;6375.298717141203;6184.938150618436;6189.442950134509;6201.10084037803;6209.0686397039;6214.471769899609;6103.246756470893;6223.973044447615;6224.782132625336;6239.251289845248;6267.717952692231;6103.511719062376;6131.468419844996;6144.341027922525;6170.660613666112;6180.320164781961;6106.084087386927;6154.969225153845;6157.4458298753325;6161.383861613803;6181.227346581677;5739.6269242500775;5977.282726072899;5993.280806303272;6125.203723516092;6128.754849805958;6098.79532419082;6156.907646385218;6170.938290617037;6171.444569988723;6173.969130154321;6013.579049466495;6048.765938408874;6063.848403789764;6068.150458935665;6101.572889897665 07:42:50 INFO - PROCESS | 2204 | |1;ext.html;477.92114301140305;496.82114438802;519.8154655097441;528.1206790693495;529.9231611416345;30617.540736888965;39999.99999999998;41153;41192.58807411925;42937.570624293825;42525;42953;42986.140277194434;43010.784946075226;43272.783636081855;8760.299176065899;9672.56473458696;9862.901370986307;9864;9889.950550247238;7829.804254893616;8803.339749518811;8968.461892286483;8984.460932344047;11849.111316651286;10702.785944281148;11900.04799616033;12198.207116537442;12239.510419583206;12243.877561224408;12027.939860300687;12203.267803931703;12213.755724885541;12215.26708397499;12229.143959922809;10232.488375581192;12013.219140755822;12036.699082522919;12064.939675301655;12069.155159138863;40265.79867100675;40551.39172912409;40591;40620.18759624791;40639.593604064175;3343.749218808575;3383.4924761285756;3526.806025668587;3600.747947643666;3862.864799732024;29954.400911981855;30719.846400768078;31858.522122168186;31861;31993.360132797447;2821.915342539727;3226.4837625979967;3328.9500657490157;3386.0011296667544;3413.9487907681405;474829;483523;483909.38498382573;484871;485194;58297.417025830044;58327.708361457924;58541.4145858536;58607;58713.119303210486;61851;61916.69041654763;62011.68994155;62079.689601551705;62114.37885621086;16248.431304904272;16429;16461.83538164627;16463.259153338127;16476.83523164777;12714.427850746739;12764.425600847984;12769.489220431264;12772.361381930867;12782.29697366635;14391.208483533297;14817.70364592702;16129.51611451635;16543.6691266174;16598.75101873473;5959.195508606369;5974.940250597525;6278.434940855253;7296.452766042568;7411.703531858773;915.9908400916039;969.1616751509894;1045.8222102242637;1075.4783929794044;1102.9283096598695;29842.701572984428;30035.249118771768;30138.19170849756;30612.92854750073;31031.68968310333;945.4610871803144;1030.6959446963165;1085.8696956365286;1093.4587379247323;1133.5069244878505;14818.369979302268;15518.379264829506;15775.526734197992;15776.763348549552;15928.522144335688;644036;649017;649212;650162;650485;58275;58477.70761146167;58514.70742646345;58658;58676.7066164675;1252.273681264864;1374.8006539051983;1375.4360712107987;1408.8872890168761;1409.2249262905361;28639.14082577526;29053.273668158465;30627;31704.841475792473;32098.51852222172;49008.75495622642;49660;49713.50286497233;49718.50281497139;49741.25388119253;523614;524167;526430;626132;633337;26001.349966250997;26260.474790504464;26283.737162628127;26380.8680956594;26419.471610568067;46020.30969535527;46383.76808115938;47331;47877.76061119672;47915.76042119767;36874;38683.80658096691;39456.01359966024;40823.59176408317;41279.81931022014;30971;40306;40571;40711;40719;38274;38809;38943.22113557656;38989;39118.608813911495;29526.67129979113;31938.68061319357;34234.97295081151;35150.29699405946;35171.82414087913;24637.87681061583;25671.871640642425;25723;26029.869850650626;26339.604905926808 07:42:50 INFO - PROCESS | 2204 | |2;jquery.html;355.8256454337375;371.7732183368145;373.1845916672072;385.31606398642407;406.54466996963407;15461.072335659854;16045;20186.49533761653;20744.792552074476;20786.376408707743;20569.897150514265;20834.166633334647;21209.257675981346;21864.89067554664;22045.779542204575;5218.895622087556;5675.375708672053;5690.174924635925;5940.2277703898435;6069.21100256966;6025.728842202089;6047.969760151215;6084.330723620398;6085.786997455068;6088.117223002662;4185.560516145806;5363.410024897258;6444.774432894849;6477.83805404864;6601.66991650418;5686.147077938343;5699.515541179005;6047.637141771508;6590.439812615933;6592.637404942726;5864.560157988125;5876.001079816441;5883.588148829584;5886.087656413263;5889.234399528034;13433.261170635611;13454;22977.655335169984;24213.394665133335;25296.747032529624;2191.2002119226527;2250.22367283287;2287.5424915016897;2608.1914606471864;2657.601359796027;8328.958355208186;9883.90116098837;10339.79320413595;12949.417276222499;14562.635934101732;1769.177332540362;1803.7399467144717;1895.6208758248267;1899.8765080269739;1995.5609765851489;89663;103801;112497;128188;129479;9300;11121.22151449407;11267.830982535104;11335.943320283346;11635.243709158878;14255.21596312192;14271.857281427052;14299.142051476945;14326.713465730836;14344.282785860665;8165.714200002972;8485.278751306209;8493.702720404755;8646.956765216259;8696.521691306913;8453;8463.915360846313;8519.190676885686;8644.481331120152;8651.264642505457;4380.6933514653665;4464.843730469418;4475.820967161277;4556.336937017964;4572.359869618256;1857.7213417987139;1915.9233630654896;1919.2610844824842;1935.94192174235;1946.922123115088;852.9445586036887;928.8838895138108;934.9859752103724;948.369334392635;1043.9269251152346;20808.79191208099;21341.253056143272;21369.252076177258;21380.358589242347;21382.14471421157;865.2861389353808;898.4294972692256;948.5921053946869;980.1325826643472;986.753311672082;10252.846207306897;10748;11028.2280240384;11163.330200188015;11168.050715689094;449883;453864;455327;472267;512730;136329;143492;143508;143584;145556.27221863822;951.6954574536082;992.5880759484733;1011.0243614911645;1048.4967215736337;1049.071571659087;16379.5086147411;19207.933646590773;19690.803091968897;19774.30789922346;19825.30611428593;53391.73304133455;54072.45927540831;54081.72959135179;55310.44689553053;55618.60953476033;55050;77150.2284977143;79438.6028069856;79495;80184;7625.123110842324;7956.6419511123295;8001;8298.917010830057;8299.419040667337;12560.43478043472;13213.207207567577;13351.866481335452;13914;13939.581812545639;11433.714157146138;11519.942400287944;11939.522419103487;11957;12046;9887.703368898945;11212.83180752273;11304.547818087512;11365.318080914842;11933.985611222795;11687.240329378563;11721;12037.157398981988;12056.75886482283;12067.456964436446;7097.290270972861;8180.46826956246;8252.504849709037;8377.204165604258;8397.622107005078;8778.780530486534;10842.891571084188;11242.831357529807;11565.7108572283;11970.102242331686 07:42:50 INFO - PROCESS | 2204 | |3;mootools.html;1356.5794603672855;1513.1072667126393;1520.7110648976695;1675.4973507947614;1833.4957886581196;7667.654955527;8443.746687599374;8775.561221938893;8793.472391656496;8832.690855820048;8584.270337021346;8665.046844847078;8668.39321247513;8671.17623825737;8694.17405346491;2374.845635033726;2403.7476065013184;2405.050005247926;2407.650890620868;2408.289554581394;2343.8124950003958;2380.654805053266;2385.1771138957033;2388.9641655375094;2389.5220955808823;2347.1432926981615;2367.5028244068767;2374.9787591335717;2381.226101517005;2381.749916258785;2321.756215597372;2345.8475199112004;2350.4476448034698;2358.7287461941837;2363.645453182019;2292.495650956787;2344.1326709117566;2347.7769611886843;2352.3413444235644;2396.1852969990287;8771.859658244452;8833.602487887996;8836.8674469883;8840.38117331787;8842.071582483875;5211.687298762086;5380.461953804588;5381.004514164892;5385.676859388449;5389.568834493231;3425.1950791564213;3584.2652256287397;3602.963970360289;3684.797336146511;3687.1888184599347;1086.3101930274254;1098.8022156011914;1103.624767579027;1115.6318414923137;1121;9611.759706007337;9929.453880036595;11428.771424571461;11481.311121332663;11505.769884602261;4347.260965635849;4412.867613971586;4605.769711514411;4608.5621865922685;4615.653825963066;9101.863472047924;9104.635814567344;9116.088391160964;9116.498592577473;9116.498592577473;5237.580993520509;5244.580433565306;5259;5261.342332208474;5277.815276465382;4243.5119961204855;4333.934990975138;4391.736495810262;4556.521565235603;4927.0884886295435;3800.5439347278502;3823.885283441501;3903.1022864741267;3917.2557214129224;3924.5879182685976;1160.3328086350382;1165.5454372794618;1179.5753528729651;1183.7272436921132;1194.2563856769214;815.176671561727;838.5262326785347;896.2068569316202;916.1479823763964;936.9156775890202;5236.659617124875;5629.887402251932;5711.143328500758;5918.704064796743;6117.174181485442;890.6259371064168;956.7416797464646;978.2223132609593;994.8607194992707;996.4519514267124;3875.7093218008763;3897.941530877039;3932.941005884914;4032.471587361059;4052.3516237402464;10037.7954645443;10199.54102065409;10693.144548436107;10694.144468442506;10701.411422371844;9909.603615855285;10594.205434592282;10596.788064238672;10605.84091238632;10607.098396636218;7548.773536793905;7979.601019949096;7990.760277191694;8063.354931605575;8118.83762324762;5825.184474173704;5867.325257595431;5934.376890426615;6063.454289114001;6064.363241859542;7631.503952243086;10017.899821001696;10021.649242276486;10033.498325083863;10034.498275086362;9116.1339672731;9865.210783137476;9975.052370024836;10051.69844904654;10288.176945844165;6300.7291236758565;7329.083864516935;7540.283673051052;7612.200718924655;7637.770866874002;8077.990251218597;8208.548529830916;8242.958785206036;8246.340292776444;8261.421700481149;7402.851942961218;7448.739294124896;7527.924720752722;7580.54516728998;7593.810154745955;7061.293870612898;7249.637518123967;7267.164276108315;7290.234525374759;7332.486725929347;6778.457723382019;6880.0711903894335;6963.895541566779;6979.267176946348;6989.161300643956;4898.338724272177;4946.950530494649;5001.27481515171;5041.092603331435;5177.456367081553;4965.82619608312;4975.950240497548;5053.469385714447;5070.569001634903;5164.147915593885 07:42:50 INFO - PROCESS | 2204 | |4;prototype.html;284.9829010259383;297.7960097333327;302.6821837071075;311.15521359508926;314.2207325831937;4376.387305777193;4808.677613656242;5119.897602047957;5221.791128354861;5523.972380138103;4479.686421950465;5033;5169.508896654821;5400.351957765074;5484.122540393538;3552.6624970627877;3801.334766415873;3804.353259945816;3821.5796262411113;4099.856505022325;3886.378179491282;3896.902577435558;4107.774072426016;4112.239235741397;4157.729747566398;3424.280901010791;3447.7589566781544;4009.2582872168596;4064.1871625674835;4106.8562600309;4019.618136277078;4048.2308361411237;4058.8376464941366;4077.245709543744;4081;3497.9825100874586;3518.8563716792146;3521.6126226115107;3546.6985306249;3563.3407819553468;3942.546607140187;4004.619561141687;5166.509181627739;5183.611229157828;5200.713960732158;3993;4173.789601015702;4403.2166972376235;4636.721796692209;4708.905821883577;5217.565169578358;5607.635503692247;5652.406497317764;5681.9431805681825;5706.115552089392;3081.938361232763;3764.8117594120185;3783.356829339019;3827.220342540732;3882.4664257618365;5148.407933087706;5840.620359676607;5880.470757631833;5924.9111263331515;5948.286205655262;3806.306193743813;3883.4466463185868;3932.960670393317;3937.153511994935;3938.625830546151;3794.2790869734663;3906.8865373368453;3966.643002129822;3987.960120398759;3996.540397854226;3685.4287585424304;3857.672097871656;4295.677824163137;4349.804258808361;4609.861704148881;5665.5467562594895;5686.744096515667;5704.372519022987;5709.571782116275;5712.914306285409;1132.592266783957;1337.4382759241141;1344.3076815440056;1349.6963183283683;1350.2303686898374;789.3251270164042;808.6401551309729;817.2440492544373;820.7414664380701;829.4152622401193;3961.3067713150085;4319.524852266248;4363.2364336241035;4375.409319741858;4398.032432864765;4203.789810509462;4272;4741.056166971594;4749.2929030452315;4896.877578060506;3973.5033120859894;4033.9394909076386;4304.913901721948;4307.698461107692;4337.219300525936;2061.371281759078;2527.0902475108946;2528.089887640448;2548.6746891616626;2557.207265747587;4187.937180942288;4562.178807814559;4870.586000189953;4967.5032496750755;4987.925181122286;4395.834893999538;4456;4532.682712210114;4886.389201349832;4963.8262660806695;4665.600319903947;4918.2376731606655;4957.7769000395065;5012.273220383097;5012.348394708664;4398.812320673336;4556.635469162526;4734.863632728052;4793;4851.636127290397;4361.5202327744555;4699.3420921071765;4701.647376446712;4764.8332308369045;4772.761361931959;4189.72766770159;4782.715099814654;4811.230203167476;4909.926351104665;4927.630427718007;2884.812487188326;2922.722341377566;2981.985090074536;3336.1492819330915;3442.845071971717;3934.5835499219684;4086.300754132771;4545.499995000613;4635.443746750277;4838.491958344323;5250.579953603627;5647.209390685225;5650.0959846424375;5665.915011274917;5675.772969081356;4802.399700037496;5165.66423182492;5591.6365436246515;5635.295588051494;5658.434156584309;5195.68825870449;5497.587680923867;5587.385387607441;5594.4125866785025;5662.915056274077;4831.4202295724735;5557.666540007613;5592.720363981866;5621.184928185472;5674.602777805514;5507.421720719427;5534.1975413565615;5545.251391062155;5549.889002219852;5623.316727543465 07:42:50 INFO - PROCESS | 2204 | |5;yui.html;721.8881073433618;736.2306389822635;783.8432313537294;786.9488483248584;825.3232349473433;15264.160471174095;16310.673786524292;16325.755113673304;16352.264148113349;16362.263698133584;14830.184339861304;15001;15013.549593512185;15016.699666006672;15022.399104035852;661.9735210591571;685.3557655803554;688.7623769799411;689.8827199376093;833.7040350675513;681.7477533312657;829.2288172000037;856.1908995998783;857.8327226190895;860.9225169734723;906.995465022674;909.4725059465527;919.4529255093242;919.5448253114685;921.5392303848076;913.2876356441986;915.1946287267228;916.422653728147;916.6654171227455;917.4724533393259;515.5694994679448;649.9252585952629;675.1189697444833;675.8715843989626;675.9560628559128;14915.850841491663;15011.399544018226;15035.323410446446;15061.924690376587;15062.698746025018;13867.653308667263;13970.93014534931;13982.790258146035;14295.213763243017;14384.784228236585;12766.297853618047;12852.55016074433;12910.54813081547;12987.220766754024;13088.083834131614;4009.578994205596;4534.863954081383;4668.066386722635;4693.108309421199;4789.473157952622;114596;119239.40380298044;119348.8065119355;121308.39345803215;121403.3929830354;8994.640214391351;10973.83539246912;11228.831567526493;11409.885901141048;11701.531938722355;21650;22119.336419907428;22282.331530054125;22330.10679572799;22345.994430250677;7650.081990161217;7816.765497035098;7959.8408031839035;7982.161873003367;8087.433879628487;3821.178446633987;4245.066085461193;4478.126765280804;4495.977520112444;4627.722336659811;3452.3613131570237;3529.294141171776;3559.021269151004;3561.1097225693575;3563.3051554947056;554.9223108764776;589.5990726306068;611.6299638718511;622.3341025103097;624.7407325959675;510.9361329833771;519.7920831667287;598.5425488934371;644.2816259870211;691.1671435919704;8040.959795200986;8981.910180898107;9038.728838134735;9874.654387096418;10153.446293797111;571.9342275638271;644.1593720195191;670.936261055199;675.5845155229498;689.4587748617312;4279.186954478639;4447.310666846644;4725.243960966298;4743.644226682944;4754.548317909793;134738;142882.2855885714;147012.26493867463;150147;152517.3958250012;85160;85737.57131214305;90075;94407.52796235975;119453.40273298578;90740.09259907449;103004;106300.46849765701;107231.46384268341;111485.44257278662;9099.727008189764;9747.8302603688;9892;9905.900940990497;9924.503774811375;53284.934301312984;55820.44179558152;55823;58130.83738325295;59630;76177;91434.54282728543;91685.54157229439;92552.53723731339;95445;11047.729511106325;12705.17416367933;13062.477500899857;13258.337083145998;13563.932180339036;12644.936775316064;14676.706465870837;14954.626134346729;15124.470643527424;15721.528354149394;13756.65608359799;14013.089149205676;14063.929680351534;14185.645358865699;14556.708865822413;13741.931290343484;13745.381457834621;13900.16599004063;14097.718045638825;14519.419223230952;13547.729045419233;13884.444622215402;13912.443502259795;14202.351028017061;14285.785713214102;11267.774644507228;11334.73575325335;11407.942960285145;11502;11596.594119205787;10052.346597471142;11044.944775276072;11091.001809837153;11337.958374628344;11553.7111572208 07:42:50 INFO - PROCESS | 2204 | __end_tp_report 07:42:50 INFO - PROCESS | 2204 | __start_cc_report 07:42:50 INFO - PROCESS | 2204 | _x_x_mozilla_cycle_collect,1137 07:42:50 INFO - PROCESS | 2204 | __end_cc_report 07:42:50 INFO - PROCESS | 2204 | __startTimestamp1472136170198__endTimestamp 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | ------- Summary: start ------- 07:42:50 INFO - PROCESS | 2204 | Number of tests: 6 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | [#0] dojo.html Cycles:165 Average:5796.81 Median:6063.85 stddev:622.43 (10.3%) stddev-sans-first:592.71 07:42:50 INFO - PROCESS | 2204 | Values: 3299.5 3320.1 3345.5 3365.1 3417.2 5640.5 5768.2 5785.4 5880.8 5914.9 5823.9 5824.9 5899.3 5958.4 5966.3 5172.9 5181.2 5271.6 5284.7 5367.9 5317.8 5370.8 5371.8 5384.4 5407.5 5171.3 5239.0 5244.0 5255.9 5295.7 5216.2 5229.3 5318.6 5320.0 5365.5 5179.4 5217.1 5263.2 5280.2 5280.8 5908.3 5910.7 5911.4 5922.2 5935.0 6105.3 6126.3 6196.5 6202.0 6203.3 5360.6 6219.0 6239.5 6285.4 6290.2 5988.5 5991.2 5996.5 5997.6 6001.8 6130.8 6203.1 6205.3 6268.0 6286.7 6062.5 6118.0 6136.5 6177.4 6195.4 6197.4 6214.1 6219.2 6237.1 6283.3 6150.4 6216.0 6223.9 6237.1 6238.4 6134.2 6162.9 6182.3 6191.4 6203.4 5040.1 5050.3 5052.9 5053.0 5067.8 4392.7 4484.5 4504.0 4525.1 4532.0 5782.9 5818.8 5853.7 5890.8 5929.6 6203.2 6213.8 6222.5 6227.0 6227.4 5855.6 5868.0 5872.5 5896.9 5947.3 5401.0 5464.9 5472.1 5513.3 5527.7 6361.6 6370.2 6373.4 6375.6 6398.6 6109.0 6123.4 6188.7 6203.7 6227.5 6138.6 6268.3 6281.8 6321.6 6375.3 6184.9 6189.4 6201.1 6209.1 6214.5 6103.2 6224.0 6224.8 6239.3 6267.7 6103.5 6131.5 6144.3 6170.7 6180.3 6106.1 6155.0 6157.4 6161.4 6181.2 5739.6 5977.3 5993.3 6125.2 6128.8 6098.8 6156.9 6170.9 6171.4 6174.0 6013.6 6048.8 6063.8 6068.2 6101.6 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | [#1] ext.html Cycles:180 Average:70072.12 Median:21068.43 stddev:152483.73 (723.8%) stddev-sans-first:152821.96 07:42:50 INFO - PROCESS | 2204 | Values: 477.9 496.8 519.8 528.1 529.9 30617.5 40000.0 41153.0 41192.6 42937.6 42525.0 42953.0 42986.1 43010.8 43272.8 8760.3 9672.6 9862.9 9864.0 9890.0 7829.8 8803.3 8968.5 8984.5 11849.1 10702.8 11900.0 12198.2 12239.5 12243.9 12027.9 12203.3 12213.8 12215.3 12229.1 10232.5 12013.2 12036.7 12064.9 12069.2 40265.8 40551.4 40591.0 40620.2 40639.6 3343.7 3383.5 3526.8 3600.7 3862.9 29954.4 30719.8 31858.5 31861.0 31993.4 2821.9 3226.5 3329.0 3386.0 3413.9 474829.0 483523.0 483909.4 484871.0 485194.0 58297.4 58327.7 58541.4 58607.0 58713.1 61851.0 61916.7 62011.7 62079.7 62114.4 16248.4 16429.0 16461.8 16463.3 16476.8 12714.4 12764.4 12769.5 12772.4 12782.3 14391.2 14817.7 16129.5 16543.7 16598.8 5959.2 5974.9 6278.4 7296.5 7411.7 916.0 969.2 1045.8 1075.5 1102.9 29842.7 30035.2 30138.2 30612.9 31031.7 945.5 1030.7 1085.9 1093.5 1133.5 14818.4 15518.4 15775.5 15776.8 15928.5 644036.0 649017.0 649212.0 650162.0 650485.0 58275.0 58477.7 58514.7 58658.0 58676.7 1252.3 1374.8 1375.4 1408.9 1409.2 28639.1 29053.3 30627.0 31704.8 32098.5 49008.8 49660.0 49713.5 49718.5 49741.3 523614.0 524167.0 526430.0 626132.0 633337.0 26001.3 26260.5 26283.7 26380.9 26419.5 46020.3 46383.8 47331.0 47877.8 47915.8 36874.0 38683.8 39456.0 40823.6 41279.8 30971.0 40306.0 40571.0 40711.0 40719.0 38274.0 38809.0 38943.2 38989.0 39118.6 29526.7 31938.7 34235.0 35150.3 35171.8 24637.9 25671.9 25723.0 26029.9 26339.6 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | [#2] jquery.html Cycles:180 Average:31554.53 Median:13952.47 stddev:80174.41 (574.6%) stddev-sans-first:80365.09 07:42:50 INFO - PROCESS | 2204 | Values: 355.8 371.8 373.2 385.3 406.5 15461.1 16045.0 20186.5 20744.8 20786.4 20569.9 20834.2 21209.3 21864.9 22045.8 5218.9 5675.4 5690.2 5940.2 6069.2 6025.7 6048.0 6084.3 6085.8 6088.1 4185.6 5363.4 6444.8 6477.8 6601.7 5686.1 5699.5 6047.6 6590.4 6592.6 5864.6 5876.0 5883.6 5886.1 5889.2 13433.3 13454.0 22977.7 24213.4 25296.7 2191.2 2250.2 2287.5 2608.2 2657.6 8329.0 9883.9 10339.8 12949.4 14562.6 1769.2 1803.7 1895.6 1899.9 1995.6 89663.0 103801.0 112497.0 128188.0 129479.0 9300.0 11121.2 11267.8 11335.9 11635.2 14255.2 14271.9 14299.1 14326.7 14344.3 8165.7 8485.3 8493.7 8647.0 8696.5 8453.0 8463.9 8519.2 8644.5 8651.3 4380.7 4464.8 4475.8 4556.3 4572.4 1857.7 1915.9 1919.3 1935.9 1946.9 852.9 928.9 935.0 948.4 1043.9 20808.8 21341.3 21369.3 21380.4 21382.1 865.3 898.4 948.6 980.1 986.8 10252.8 10748.0 11028.2 11163.3 11168.1 449883.0 453864.0 455327.0 472267.0 512730.0 136329.0 143492.0 143508.0 143584.0 145556.3 951.7 992.6 1011.0 1048.5 1049.1 16379.5 19207.9 19690.8 19774.3 19825.3 53391.7 54072.5 54081.7 55310.4 55618.6 55050.0 77150.2 79438.6 79495.0 80184.0 7625.1 7956.6 8001.0 8298.9 8299.4 12560.4 13213.2 13351.9 13914.0 13939.6 11433.7 11519.9 11939.5 11957.0 12046.0 9887.7 11212.8 11304.5 11365.3 11934.0 11687.2 11721.0 12037.2 12056.8 12067.5 7097.3 8180.5 8252.5 8377.2 8397.6 8778.8 10842.9 11242.8 11565.7 11970.1 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | [#3] mootools.html Cycles:180 Average:5601.30 Median:5117.36 stddev:3078.80 (60.2%) stddev-sans-first:3070.90 07:42:50 INFO - PROCESS | 2204 | Values: 1356.6 1513.1 1520.7 1675.5 1833.5 7667.7 8443.7 8775.6 8793.5 8832.7 8584.3 8665.0 8668.4 8671.2 8694.2 2374.8 2403.7 2405.1 2407.7 2408.3 2343.8 2380.7 2385.2 2389.0 2389.5 2347.1 2367.5 2375.0 2381.2 2381.7 2321.8 2345.8 2350.4 2358.7 2363.6 2292.5 2344.1 2347.8 2352.3 2396.2 8771.9 8833.6 8836.9 8840.4 8842.1 5211.7 5380.5 5381.0 5385.7 5389.6 3425.2 3584.3 3603.0 3684.8 3687.2 1086.3 1098.8 1103.6 1115.6 1121.0 9611.8 9929.5 11428.8 11481.3 11505.8 4347.3 4412.9 4605.8 4608.6 4615.7 9101.9 9104.6 9116.1 9116.5 9116.5 5237.6 5244.6 5259.0 5261.3 5277.8 4243.5 4333.9 4391.7 4556.5 4927.1 3800.5 3823.9 3903.1 3917.3 3924.6 1160.3 1165.5 1179.6 1183.7 1194.3 815.2 838.5 896.2 916.1 936.9 5236.7 5629.9 5711.1 5918.7 6117.2 890.6 956.7 978.2 994.9 996.5 3875.7 3897.9 3932.9 4032.5 4052.4 10037.8 10199.5 10693.1 10694.1 10701.4 9909.6 10594.2 10596.8 10605.8 10607.1 7548.8 7979.6 7990.8 8063.4 8118.8 5825.2 5867.3 5934.4 6063.5 6064.4 7631.5 10017.9 10021.6 10033.5 10034.5 9116.1 9865.2 9975.1 10051.7 10288.2 6300.7 7329.1 7540.3 7612.2 7637.8 8078.0 8208.5 8243.0 8246.3 8261.4 7402.9 7448.7 7527.9 7580.5 7593.8 7061.3 7249.6 7267.2 7290.2 7332.5 6778.5 6880.1 6963.9 6979.3 6989.2 4898.3 4947.0 5001.3 5041.1 5177.5 4965.8 4976.0 5053.5 5070.6 5164.1 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | [#4] prototype.html Cycles:180 Average:4231.92 Median:4506.18 stddev:1306.95 (29.0%) stddev-sans-first:1276.60 07:42:50 INFO - PROCESS | 2204 | Values: 285.0 297.8 302.7 311.2 314.2 4376.4 4808.7 5119.9 5221.8 5524.0 4479.7 5033.0 5169.5 5400.4 5484.1 3552.7 3801.3 3804.4 3821.6 4099.9 3886.4 3896.9 4107.8 4112.2 4157.7 3424.3 3447.8 4009.3 4064.2 4106.9 4019.6 4048.2 4058.8 4077.2 4081.0 3498.0 3518.9 3521.6 3546.7 3563.3 3942.5 4004.6 5166.5 5183.6 5200.7 3993.0 4173.8 4403.2 4636.7 4708.9 5217.6 5607.6 5652.4 5681.9 5706.1 3081.9 3764.8 3783.4 3827.2 3882.5 5148.4 5840.6 5880.5 5924.9 5948.3 3806.3 3883.4 3933.0 3937.2 3938.6 3794.3 3906.9 3966.6 3988.0 3996.5 3685.4 3857.7 4295.7 4349.8 4609.9 5665.5 5686.7 5704.4 5709.6 5712.9 1132.6 1337.4 1344.3 1349.7 1350.2 789.3 808.6 817.2 820.7 829.4 3961.3 4319.5 4363.2 4375.4 4398.0 4203.8 4272.0 4741.1 4749.3 4896.9 3973.5 4033.9 4304.9 4307.7 4337.2 2061.4 2527.1 2528.1 2548.7 2557.2 4187.9 4562.2 4870.6 4967.5 4987.9 4395.8 4456.0 4532.7 4886.4 4963.8 4665.6 4918.2 4957.8 5012.3 5012.3 4398.8 4556.6 4734.9 4793.0 4851.6 4361.5 4699.3 4701.6 4764.8 4772.8 4189.7 4782.7 4811.2 4909.9 4927.6 2884.8 2922.7 2982.0 3336.1 3442.8 3934.6 4086.3 4545.5 4635.4 4838.5 5250.6 5647.2 5650.1 5665.9 5675.8 4802.4 5165.7 5591.6 5635.3 5658.4 5195.7 5497.6 5587.4 5594.4 5662.9 4831.4 5557.7 5592.7 5621.2 5674.6 5507.4 5534.2 5545.3 5549.9 5623.3 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - PROCESS | 2204 | [#5] yui.html Cycles:180 Average:23828.14 Median:3915.38 stddev:37160.99 (949.1%) stddev-sans-first:37224.74 07:42:50 INFO - PROCESS | 2204 | Values: 721.9 736.2 783.8 786.9 825.3 15264.2 16310.7 16325.8 16352.3 16362.3 14830.2 15001.0 15013.5 15016.7 15022.4 662.0 685.4 688.8 689.9 833.7 681.7 829.2 856.2 857.8 860.9 907.0 909.5 919.5 919.5 921.5 913.3 915.2 916.4 916.7 917.5 515.6 649.9 675.1 675.9 676.0 14915.9 15011.4 15035.3 15061.9 15062.7 13867.7 13970.9 13982.8 14295.2 14384.8 12766.3 12852.6 12910.5 12987.2 13088.1 4009.6 4534.9 4668.1 4693.1 4789.5 114596.0 119239.4 119348.8 121308.4 121403.4 8994.6 10973.8 11228.8 11409.9 11701.5 21650.0 22119.3 22282.3 22330.1 22346.0 7650.1 7816.8 7959.8 7982.2 8087.4 3821.2 4245.1 4478.1 4496.0 4627.7 3452.4 3529.3 3559.0 3561.1 3563.3 554.9 589.6 611.6 622.3 624.7 510.9 519.8 598.5 644.3 691.2 8041.0 8981.9 9038.7 9874.7 10153.4 571.9 644.2 670.9 675.6 689.5 4279.2 4447.3 4725.2 4743.6 4754.5 134738.0 142882.3 147012.3 150147.0 152517.4 85160.0 85737.6 90075.0 94407.5 119453.4 90740.1 103004.0 106300.5 107231.5 111485.4 9099.7 9747.8 9892.0 9905.9 9924.5 53284.9 55820.4 55823.0 58130.8 59630.0 76177.0 91434.5 91685.5 92552.5 95445.0 11047.7 12705.2 13062.5 13258.3 13563.9 12644.9 14676.7 14954.6 15124.5 15721.5 13756.7 14013.1 14063.9 14185.6 14556.7 13741.9 13745.4 13900.2 14097.7 14519.4 13547.7 13884.4 13912.4 14202.4 14285.8 11267.8 11334.7 11407.9 11502.0 11596.6 10052.3 11044.9 11091.0 11338.0 11553.7 07:42:50 INFO - PROCESS | 2204 | -------- Summary: end -------- 07:42:50 INFO - PROCESS | 2204 | 07:42:50 INFO - TEST-INFO | 2204: exit 0 07:42:50 INFO - TEST-OK | dromaeo_css | took 1117755ms 07:42:50 INFO - TEST-START | kraken 07:42:50 INFO - operating with platform_type : mac_ 07:42:50 INFO - Initialising browser for kraken test... 07:42:50 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmptUJErA/profile http://localhost:64207/getInfo.html) 07:42:53 INFO - PROCESS | 2208 | __metrics Screen width/height:1600/1200 07:42:53 INFO - PROCESS | 2208 | colorDepth:24 07:42:53 INFO - PROCESS | 2208 | Browser inner width/height: 1024/689 07:42:53 INFO - PROCESS | 2208 | __metrics 07:42:53 INFO - PROCESS | 2208 | 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" 07:42:53 INFO - PROCESS | 2208 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:42:53 INFO - TEST-INFO | 2208: exit 0 07:42:53 INFO - Browser initialized. 07:42:53 INFO - Running cycle 1/1 for kraken test... 07:42:53 INFO - TEST-INFO | started process 2209 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmptUJErA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:43:05 INFO - PROCESS | 2209 | RSS: Main: 232103936 07:43:05 INFO - PROCESS | 2209 | 07:43:08 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64207/tests/kraken/driver.html?testName=audio-beat-detection) 07:43:09 INFO - PROCESS | 2209 | RSS: Main: 289624064 07:43:09 INFO - PROCESS | 2209 | 07:43:12 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64207/tests/kraken/driver.html?testName=audio-dft) 07:43:12 INFO - PROCESS | 2209 | RSS: Main: 284307456 07:43:12 INFO - PROCESS | 2209 | 07:43:16 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64207/tests/kraken/driver.html?testName=audio-fft) 07:43:16 INFO - PROCESS | 2209 | RSS: Main: 371204096 07:43:16 INFO - PROCESS | 2209 | 07:43:18 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64207/tests/kraken/driver.html?testName=audio-oscillator) 07:43:19 INFO - PROCESS | 2209 | RSS: Main: 278827008 07:43:19 INFO - PROCESS | 2209 | 07:43:21 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64207/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:43:22 INFO - PROCESS | 2209 | RSS: Main: 277520384 07:43:22 INFO - PROCESS | 2209 | 07:43:25 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64207/tests/kraken/driver.html?testName=imaging-darkroom) 07:43:25 INFO - PROCESS | 2209 | RSS: Main: 318353408 07:43:25 INFO - PROCESS | 2209 | 07:43:28 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64207/tests/kraken/driver.html?testName=imaging-desaturate) 07:43:29 INFO - PROCESS | 2209 | RSS: Main: 319152128 07:43:29 INFO - PROCESS | 2209 | 07:43:32 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64207/tests/kraken/driver.html?testName=json-parse-financial) 07:43:32 INFO - PROCESS | 2209 | RSS: Main: 318861312 07:43:32 INFO - PROCESS | 2209 | 07:43:34 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64207/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:43:35 INFO - PROCESS | 2209 | RSS: Main: 296280064 07:43:35 INFO - PROCESS | 2209 | 07:43:36 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:43:37 INFO - PROCESS | 2209 | RSS: Main: 301899776 07:43:37 INFO - PROCESS | 2209 | 07:43:39 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:43:40 INFO - PROCESS | 2209 | RSS: Main: 307109888 07:43:40 INFO - PROCESS | 2209 | 07:43:43 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:43:44 INFO - PROCESS | 2209 | RSS: Main: 311664640 07:43:44 INFO - PROCESS | 2209 | 07:43:47 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:43:47 INFO - PROCESS | 2209 | RSS: Main: 313159680 07:43:47 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:64207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64207/tests/kraken/driver.html?testName=ai-astar) 07:43:49 INFO - PROCESS | 2209 | RSS: Main: 356593664 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | __start_tp_report 07:43:49 INFO - PROCESS | 2209 | _x_x_mozilla_page_load 07:43:49 INFO - PROCESS | 2209 | _x_x_mozilla_page_load_details 07:43:49 INFO - PROCESS | 2209 | |i|pagename|runs| 07:43:49 INFO - PROCESS | 2209 | |0;ai-astar;120;165;131;112;111;113;111;111;112;112 07:43:49 INFO - PROCESS | 2209 | |1;audio-beat-detection;177;132;130;161;108;128;127;107;161;107 07:43:49 INFO - PROCESS | 2209 | |2;audio-dft;186;186;187;180;182;186;186;184;182;183 07:43:49 INFO - PROCESS | 2209 | |3;audio-fft;78;75;80;154;58;58;58;65;141;58 07:43:49 INFO - PROCESS | 2209 | |4;audio-oscillator;83;84;137;71;70;84;120;91;95;135 07:43:49 INFO - PROCESS | 2209 | |5;imaging-gaussian-blur;87;83;87;84;87;83;86;83;87;85 07:43:49 INFO - PROCESS | 2209 | |6;imaging-darkroom;92;94;92;94;99;143;130;146;97;103 07:43:49 INFO - PROCESS | 2209 | |7;imaging-desaturate;86;84;84;85;84;85;87;85;84;82 07:43:49 INFO - PROCESS | 2209 | |8;json-parse-financial;55;56;57;58;56;53;56;54;54;53 07:43:49 INFO - PROCESS | 2209 | |9;json-stringify-tinderbox;66;66;73;74;75;73;69;70;71;71 07:43:49 INFO - PROCESS | 2209 | |10;stanford-crypto-aes;85;88;88;85;86;84;88;89;88;110 07:43:49 INFO - PROCESS | 2209 | |11;stanford-crypto-ccm;140;140;136;140;145;144;145;127;169;150 07:43:49 INFO - PROCESS | 2209 | |12;stanford-crypto-pbkdf2;172;166;223;214;170;170;171;168;168;169 07:43:49 INFO - PROCESS | 2209 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;67;67;67;65;66 07:43:49 INFO - PROCESS | 2209 | __end_tp_report 07:43:49 INFO - PROCESS | 2209 | __start_cc_report 07:43:49 INFO - PROCESS | 2209 | _x_x_mozilla_cycle_collect,2096 07:43:49 INFO - PROCESS | 2209 | __end_cc_report 07:43:49 INFO - PROCESS | 2209 | __startTimestamp1472136229765__endTimestamp 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | ------- Summary: start ------- 07:43:49 INFO - PROCESS | 2209 | Number of tests: 14 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#0] ai-astar Cycles:10 Average:119.80 Median:112.50 stddev:17.09 (15.2%) stddev-sans-first:18.13 07:43:49 INFO - PROCESS | 2209 | Values: 120.0 165.0 131.0 112.0 111.0 113.0 111.0 111.0 112.0 112.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:131.00 stddev:24.82 (18.9%) stddev-sans-first:20.83 07:43:49 INFO - PROCESS | 2209 | Values: 177.0 132.0 130.0 161.0 108.0 128.0 127.0 107.0 161.0 107.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#2] audio-dft Cycles:10 Average:184.20 Median:186.00 stddev:2.35 (1.3%) stddev-sans-first:2.40 07:43:49 INFO - PROCESS | 2209 | Values: 186.0 186.0 187.0 180.0 182.0 186.0 186.0 184.0 182.0 183.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#3] audio-fft Cycles:10 Average:82.50 Median:61.50 stddev:35.47 (57.7%) stddev-sans-first:37.59 07:43:49 INFO - PROCESS | 2209 | Values: 78.0 75.0 80.0 154.0 58.0 58.0 58.0 65.0 141.0 58.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:83.50 stddev:24.84 (29.7%) stddev-sans-first:25.82 07:43:49 INFO - PROCESS | 2209 | Values: 83.0 84.0 137.0 71.0 70.0 84.0 120.0 91.0 95.0 135.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.50 stddev:1.81 (2.1%) stddev-sans-first:1.80 07:43:49 INFO - PROCESS | 2209 | Values: 87.0 83.0 87.0 84.0 87.0 83.0 86.0 83.0 87.0 85.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:93.00 stddev:21.79 (23.4%) stddev-sans-first:22.23 07:43:49 INFO - PROCESS | 2209 | Values: 92.0 94.0 92.0 94.0 99.0 143.0 130.0 146.0 97.0 103.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.33 07:43:49 INFO - PROCESS | 2209 | Values: 86.0 84.0 84.0 85.0 84.0 85.0 87.0 85.0 84.0 82.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.69 (3.0%) stddev-sans-first:1.79 07:43:49 INFO - PROCESS | 2209 | Values: 55.0 56.0 57.0 58.0 56.0 53.0 56.0 54.0 54.0 53.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:72.00 stddev:3.12 (4.3%) stddev-sans-first:2.78 07:43:49 INFO - PROCESS | 2209 | Values: 66.0 66.0 73.0 74.0 75.0 73.0 69.0 70.0 71.0 71.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:88.00 stddev:7.53 (8.6%) stddev-sans-first:7.84 07:43:49 INFO - PROCESS | 2209 | Values: 85.0 88.0 88.0 85.0 86.0 84.0 88.0 89.0 88.0 110.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:144.50 stddev:10.86 (7.5%) stddev-sans-first:11.45 07:43:49 INFO - PROCESS | 2209 | Values: 140.0 140.0 136.0 140.0 145.0 144.0 145.0 127.0 169.0 150.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:170.50 stddev:20.94 (12.3%) stddev-sans-first:22.05 07:43:49 INFO - PROCESS | 2209 | Values: 172.0 166.0 223.0 214.0 170.0 170.0 171.0 168.0 168.0 169.0 07:43:49 INFO - PROCESS | 2209 | 07:43:49 INFO - PROCESS | 2209 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 07:43:49 INFO - PROCESS | 2209 | Values: 67.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 65.0 66.0 07:43:49 INFO - PROCESS | 2209 | -------- Summary: end -------- 07:43:49 INFO - PROCESS | 2209 | 07:43:50 INFO - TEST-INFO | 2209: exit 0 07:43:50 INFO - TEST-OK | kraken | took 59877ms 07:43:50 INFO - SUITE-END | took 1177s 07:43:50 INFO - Completed test suite (00:19:37) 07:43:50 INFO - javascript benchmark 07:43:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.538064670946, 3320.086976081577, 3345.464725643896, 3365.0914253151664, 3417.1798768295603, 5640.4641559051925, 5768.221290125832, 5785.421457854212, 5880.764769409224, 5914.940850591493, 5823.941760582393, 5824.883502329951, 5899.292084949801, 5958.404159584039, 5966.254218222723, 5172.9224061639015, 5181.222816577506, 5271.604629652772, 5284.656497327662, 5367.865803354908, 5317.813876514323, 5370.758315875795, 5371.7582708778, 5384.40771515133, 5407.540359069484, 5171.3277273954145, 5239.004589128053, 5243.973780131113, 5255.921161182604, 5295.735213239361, 5216.243644671539, 5229.294045303874, 5318.627696061278, 5319.973400132974, 5365.517103460707, 5179.378474583074, 5217.139172036609, 5263.157894736862, 5280.155175171993, 5280.815171469018, 5908.320543137551, 5910.67491287979, 5911.438413350767, 5922.170896074553, 5934.970325148347, 6105.267367915834, 6126.303932855845, 6196.504279657616, 6202.0, 6203.31763506014, 5360.563492696944, 6218.968905155491, 6239.500839932794, 6285.371462853678, 6290.182276304051, 5988.461038506555, 5991.191189189447, 5996.5202783777195, 5997.580169388189, 6001.819945401645, 6130.826205142326, 6203.131561581382, 6205.2553693557065, 6268.0, 6286.717097730613, 6062.50279429517, 6117.969410153009, 6136.54971344134, 6177.444030037228, 6195.380461953769, 6197.442230199304, 6214.130021796952, 6219.222597175353, 6237.126802247689, 6283.277423096402, 6150.415710507584, 6216.0, 6223.875522489616, 6237.095621135, 6238.376162383816, 6134.19168101403, 6162.938370616236, 6182.28903676074, 6191.380861913773, 6203.4416902479, 5040.067587496325, 5050.292958985745, 5052.873678158002, 5052.9747351263, 5067.847964561069, 4392.714473559208, 4484.529124441952, 4503.954960450419, 4525.140223357552, 4532.0256144929, 5782.87233989368, 5818.8254352369495, 5853.7365818538265, 5890.823275301748, 5929.6145750526075, 6203.193584833943, 6213.844653883689, 6222.471089957405, 6227.034809604518, 6227.439530442282, 5855.560832937546, 5867.970660146672, 5872.5301975842685, 5896.911546326808, 5947.316058653224, 5401.000814849265, 5464.9180262296095, 5472.069748142825, 5513.3108361454815, 5527.723613819293, 6361.586496877688, 6370.1718776558755, 6373.362663733682, 6375.617462952052, 6398.584092034189, 6109.0, 6123.387661233841, 6188.659623720559, 6203.720832562455, 6227.470664993345, 6138.6336396323695, 6268.341824108586, 6281.811545653638, 6321.620702757849, 6375.298717141203, 6184.938150618436, 6189.442950134509, 6201.10084037803, 6209.0686397039, 6214.471769899609, 6103.246756470893, 6223.973044447615, 6224.782132625336, 6239.251289845248, 6267.717952692231, 6103.511719062376, 6131.468419844996, 6144.341027922525, 6170.660613666112, 6180.320164781961, 6106.084087386927, 6154.969225153845, 6157.4458298753325, 6161.383861613803, 6181.227346581677, 5739.6269242500775, 5977.282726072899, 5993.280806303272, 6125.203723516092, 6128.754849805958, 6098.79532419082, 6156.907646385218, 6170.938290617037, 6171.444569988723, 6173.969130154321, 6013.579049466495, 6048.765938408874, 6063.848403789764, 6068.150458935665, 6101.572889897665], "value": 5756.888633043121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.92114301140305, 496.82114438802, 519.8154655097441, 528.1206790693495, 529.9231611416345, 30617.540736888965, 39999.99999999998, 41153.0, 41192.58807411925, 42937.570624293825, 42525.0, 42953.0, 42986.140277194434, 43010.784946075226, 43272.783636081855, 8760.299176065899, 9672.56473458696, 9862.901370986307, 9864.0, 9889.950550247238, 7829.804254893616, 8803.339749518811, 8968.461892286483, 8984.460932344047, 11849.111316651286, 10702.785944281148, 11900.04799616033, 12198.207116537442, 12239.510419583206, 12243.877561224408, 12027.939860300687, 12203.267803931703, 12213.755724885541, 12215.26708397499, 12229.143959922809, 10232.488375581192, 12013.219140755822, 12036.699082522919, 12064.939675301655, 12069.155159138863, 40265.79867100675, 40551.39172912409, 40591.0, 40620.18759624791, 40639.593604064175, 3343.749218808575, 3383.4924761285756, 3526.806025668587, 3600.747947643666, 3862.864799732024, 29954.400911981855, 30719.846400768078, 31858.522122168186, 31861.0, 31993.360132797447, 2821.915342539727, 3226.4837625979967, 3328.9500657490157, 3386.0011296667544, 3413.9487907681405, 474829.0, 483523.0, 483909.38498382573, 484871.0, 485194.0, 58297.417025830044, 58327.708361457924, 58541.4145858536, 58607.0, 58713.119303210486, 61851.0, 61916.69041654763, 62011.68994155, 62079.689601551705, 62114.37885621086, 16248.431304904272, 16429.0, 16461.83538164627, 16463.259153338127, 16476.83523164777, 12714.427850746739, 12764.425600847984, 12769.489220431264, 12772.361381930867, 12782.29697366635, 14391.208483533297, 14817.70364592702, 16129.51611451635, 16543.6691266174, 16598.75101873473, 5959.195508606369, 5974.940250597525, 6278.434940855253, 7296.452766042568, 7411.703531858773, 915.9908400916039, 969.1616751509894, 1045.8222102242637, 1075.4783929794044, 1102.9283096598695, 29842.701572984428, 30035.249118771768, 30138.19170849756, 30612.92854750073, 31031.68968310333, 945.4610871803144, 1030.6959446963165, 1085.8696956365286, 1093.4587379247323, 1133.5069244878505, 14818.369979302268, 15518.379264829506, 15775.526734197992, 15776.763348549552, 15928.522144335688, 644036.0, 649017.0, 649212.0, 650162.0, 650485.0, 58275.0, 58477.70761146167, 58514.70742646345, 58658.0, 58676.7066164675, 1252.273681264864, 1374.8006539051983, 1375.4360712107987, 1408.8872890168761, 1409.2249262905361, 28639.14082577526, 29053.273668158465, 30627.0, 31704.841475792473, 32098.51852222172, 49008.75495622642, 49660.0, 49713.50286497233, 49718.50281497139, 49741.25388119253, 523614.0, 524167.0, 526430.0, 626132.0, 633337.0, 26001.349966250997, 26260.474790504464, 26283.737162628127, 26380.8680956594, 26419.471610568067, 46020.30969535527, 46383.76808115938, 47331.0, 47877.76061119672, 47915.76042119767, 36874.0, 38683.80658096691, 39456.01359966024, 40823.59176408317, 41279.81931022014, 30971.0, 40306.0, 40571.0, 40711.0, 40719.0, 38274.0, 38809.0, 38943.22113557656, 38989.0, 39118.608813911495, 29526.67129979113, 31938.68061319357, 34234.97295081151, 35150.29699405946, 35171.82414087913, 24637.87681061583, 25671.871640642425, 25723.0, 26029.869850650626, 26339.604905926808], "value": 20214.385288803325, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.8256454337375, 371.7732183368145, 373.1845916672072, 385.31606398642407, 406.54466996963407, 15461.072335659854, 16045.0, 20186.49533761653, 20744.792552074476, 20786.376408707743, 20569.897150514265, 20834.166633334647, 21209.257675981346, 21864.89067554664, 22045.779542204575, 5218.895622087556, 5675.375708672053, 5690.174924635925, 5940.2277703898435, 6069.21100256966, 6025.728842202089, 6047.969760151215, 6084.330723620398, 6085.786997455068, 6088.117223002662, 4185.560516145806, 5363.410024897258, 6444.774432894849, 6477.83805404864, 6601.66991650418, 5686.147077938343, 5699.515541179005, 6047.637141771508, 6590.439812615933, 6592.637404942726, 5864.560157988125, 5876.001079816441, 5883.588148829584, 5886.087656413263, 5889.234399528034, 13433.261170635611, 13454.0, 22977.655335169984, 24213.394665133335, 25296.747032529624, 2191.2002119226527, 2250.22367283287, 2287.5424915016897, 2608.1914606471864, 2657.601359796027, 8328.958355208186, 9883.90116098837, 10339.79320413595, 12949.417276222499, 14562.635934101732, 1769.177332540362, 1803.7399467144717, 1895.6208758248267, 1899.8765080269739, 1995.5609765851489, 89663.0, 103801.0, 112497.0, 128188.0, 129479.0, 9300.0, 11121.22151449407, 11267.830982535104, 11335.943320283346, 11635.243709158878, 14255.21596312192, 14271.857281427052, 14299.142051476945, 14326.713465730836, 14344.282785860665, 8165.714200002972, 8485.278751306209, 8493.702720404755, 8646.956765216259, 8696.521691306913, 8453.0, 8463.915360846313, 8519.190676885686, 8644.481331120152, 8651.264642505457, 4380.6933514653665, 4464.843730469418, 4475.820967161277, 4556.336937017964, 4572.359869618256, 1857.7213417987139, 1915.9233630654896, 1919.2610844824842, 1935.94192174235, 1946.922123115088, 852.9445586036887, 928.8838895138108, 934.9859752103724, 948.369334392635, 1043.9269251152346, 20808.79191208099, 21341.253056143272, 21369.252076177258, 21380.358589242347, 21382.14471421157, 865.2861389353808, 898.4294972692256, 948.5921053946869, 980.1325826643472, 986.753311672082, 10252.846207306897, 10748.0, 11028.2280240384, 11163.330200188015, 11168.050715689094, 449883.0, 453864.0, 455327.0, 472267.0, 512730.0, 136329.0, 143492.0, 143508.0, 143584.0, 145556.27221863822, 951.6954574536082, 992.5880759484733, 1011.0243614911645, 1048.4967215736337, 1049.071571659087, 16379.5086147411, 19207.933646590773, 19690.803091968897, 19774.30789922346, 19825.30611428593, 53391.73304133455, 54072.45927540831, 54081.72959135179, 55310.44689553053, 55618.60953476033, 55050.0, 77150.2284977143, 79438.6028069856, 79495.0, 80184.0, 7625.123110842324, 7956.6419511123295, 8001.0, 8298.917010830057, 8299.419040667337, 12560.43478043472, 13213.207207567577, 13351.866481335452, 13914.0, 13939.581812545639, 11433.714157146138, 11519.942400287944, 11939.522419103487, 11957.0, 12046.0, 9887.703368898945, 11212.83180752273, 11304.547818087512, 11365.318080914842, 11933.985611222795, 11687.240329378563, 11721.0, 12037.157398981988, 12056.75886482283, 12067.456964436446, 7097.290270972861, 8180.46826956246, 8252.504849709037, 8377.204165604258, 8397.622107005078, 8778.780530486534, 10842.891571084188, 11242.831357529807, 11565.7108572283, 11970.102242331686], "value": 9547.356955649906, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.5794603672855, 1513.1072667126393, 1520.7110648976695, 1675.4973507947614, 1833.4957886581196, 7667.654955527, 8443.746687599374, 8775.561221938893, 8793.472391656496, 8832.690855820048, 8584.270337021346, 8665.046844847078, 8668.39321247513, 8671.17623825737, 8694.17405346491, 2374.845635033726, 2403.7476065013184, 2405.050005247926, 2407.650890620868, 2408.289554581394, 2343.8124950003958, 2380.654805053266, 2385.1771138957033, 2388.9641655375094, 2389.5220955808823, 2347.1432926981615, 2367.5028244068767, 2374.9787591335717, 2381.226101517005, 2381.749916258785, 2321.756215597372, 2345.8475199112004, 2350.4476448034698, 2358.7287461941837, 2363.645453182019, 2292.495650956787, 2344.1326709117566, 2347.7769611886843, 2352.3413444235644, 2396.1852969990287, 8771.859658244452, 8833.602487887996, 8836.8674469883, 8840.38117331787, 8842.071582483875, 5211.687298762086, 5380.461953804588, 5381.004514164892, 5385.676859388449, 5389.568834493231, 3425.1950791564213, 3584.2652256287397, 3602.963970360289, 3684.797336146511, 3687.1888184599347, 1086.3101930274254, 1098.8022156011914, 1103.624767579027, 1115.6318414923137, 1121.0, 9611.759706007337, 9929.453880036595, 11428.771424571461, 11481.311121332663, 11505.769884602261, 4347.260965635849, 4412.867613971586, 4605.769711514411, 4608.5621865922685, 4615.653825963066, 9101.863472047924, 9104.635814567344, 9116.088391160964, 9116.498592577473, 9116.498592577473, 5237.580993520509, 5244.580433565306, 5259.0, 5261.342332208474, 5277.815276465382, 4243.5119961204855, 4333.934990975138, 4391.736495810262, 4556.521565235603, 4927.0884886295435, 3800.5439347278502, 3823.885283441501, 3903.1022864741267, 3917.2557214129224, 3924.5879182685976, 1160.3328086350382, 1165.5454372794618, 1179.5753528729651, 1183.7272436921132, 1194.2563856769214, 815.176671561727, 838.5262326785347, 896.2068569316202, 916.1479823763964, 936.9156775890202, 5236.659617124875, 5629.887402251932, 5711.143328500758, 5918.704064796743, 6117.174181485442, 890.6259371064168, 956.7416797464646, 978.2223132609593, 994.8607194992707, 996.4519514267124, 3875.7093218008763, 3897.941530877039, 3932.941005884914, 4032.471587361059, 4052.3516237402464, 10037.7954645443, 10199.54102065409, 10693.144548436107, 10694.144468442506, 10701.411422371844, 9909.603615855285, 10594.205434592282, 10596.788064238672, 10605.84091238632, 10607.098396636218, 7548.773536793905, 7979.601019949096, 7990.760277191694, 8063.354931605575, 8118.83762324762, 5825.184474173704, 5867.325257595431, 5934.376890426615, 6063.454289114001, 6064.363241859542, 7631.503952243086, 10017.899821001696, 10021.649242276486, 10033.498325083863, 10034.498275086362, 9116.1339672731, 9865.210783137476, 9975.052370024836, 10051.69844904654, 10288.176945844165, 6300.7291236758565, 7329.083864516935, 7540.283673051052, 7612.200718924655, 7637.770866874002, 8077.990251218597, 8208.548529830916, 8242.958785206036, 8246.340292776444, 8261.421700481149, 7402.851942961218, 7448.739294124896, 7527.924720752722, 7580.54516728998, 7593.810154745955, 7061.293870612898, 7249.637518123967, 7267.164276108315, 7290.234525374759, 7332.486725929347, 6778.457723382019, 6880.0711903894335, 6963.895541566779, 6979.267176946348, 6989.161300643956, 4898.338724272177, 4946.950530494649, 5001.27481515171, 5041.092603331435, 5177.456367081553, 4965.82619608312, 4975.950240497548, 5053.469385714447, 5070.569001634903, 5164.147915593885], "value": 4529.5841601546645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.9829010259383, 297.7960097333327, 302.6821837071075, 311.15521359508926, 314.2207325831937, 4376.387305777193, 4808.677613656242, 5119.897602047957, 5221.791128354861, 5523.972380138103, 4479.686421950465, 5033.0, 5169.508896654821, 5400.351957765074, 5484.122540393538, 3552.6624970627877, 3801.334766415873, 3804.353259945816, 3821.5796262411113, 4099.856505022325, 3886.378179491282, 3896.902577435558, 4107.774072426016, 4112.239235741397, 4157.729747566398, 3424.280901010791, 3447.7589566781544, 4009.2582872168596, 4064.1871625674835, 4106.8562600309, 4019.618136277078, 4048.2308361411237, 4058.8376464941366, 4077.245709543744, 4081.0, 3497.9825100874586, 3518.8563716792146, 3521.6126226115107, 3546.6985306249, 3563.3407819553468, 3942.546607140187, 4004.619561141687, 5166.509181627739, 5183.611229157828, 5200.713960732158, 3993.0, 4173.789601015702, 4403.2166972376235, 4636.721796692209, 4708.905821883577, 5217.565169578358, 5607.635503692247, 5652.406497317764, 5681.9431805681825, 5706.115552089392, 3081.938361232763, 3764.8117594120185, 3783.356829339019, 3827.220342540732, 3882.4664257618365, 5148.407933087706, 5840.620359676607, 5880.470757631833, 5924.9111263331515, 5948.286205655262, 3806.306193743813, 3883.4466463185868, 3932.960670393317, 3937.153511994935, 3938.625830546151, 3794.2790869734663, 3906.8865373368453, 3966.643002129822, 3987.960120398759, 3996.540397854226, 3685.4287585424304, 3857.672097871656, 4295.677824163137, 4349.804258808361, 4609.861704148881, 5665.5467562594895, 5686.744096515667, 5704.372519022987, 5709.571782116275, 5712.914306285409, 1132.592266783957, 1337.4382759241141, 1344.3076815440056, 1349.6963183283683, 1350.2303686898374, 789.3251270164042, 808.6401551309729, 817.2440492544373, 820.7414664380701, 829.4152622401193, 3961.3067713150085, 4319.524852266248, 4363.2364336241035, 4375.409319741858, 4398.032432864765, 4203.789810509462, 4272.0, 4741.056166971594, 4749.2929030452315, 4896.877578060506, 3973.5033120859894, 4033.9394909076386, 4304.913901721948, 4307.698461107692, 4337.219300525936, 2061.371281759078, 2527.0902475108946, 2528.089887640448, 2548.6746891616626, 2557.207265747587, 4187.937180942288, 4562.178807814559, 4870.586000189953, 4967.5032496750755, 4987.925181122286, 4395.834893999538, 4456.0, 4532.682712210114, 4886.389201349832, 4963.8262660806695, 4665.600319903947, 4918.2376731606655, 4957.7769000395065, 5012.273220383097, 5012.348394708664, 4398.812320673336, 4556.635469162526, 4734.863632728052, 4793.0, 4851.636127290397, 4361.5202327744555, 4699.3420921071765, 4701.647376446712, 4764.8332308369045, 4772.761361931959, 4189.72766770159, 4782.715099814654, 4811.230203167476, 4909.926351104665, 4927.630427718007, 2884.812487188326, 2922.722341377566, 2981.985090074536, 3336.1492819330915, 3442.845071971717, 3934.5835499219684, 4086.300754132771, 4545.499995000613, 4635.443746750277, 4838.491958344323, 5250.579953603627, 5647.209390685225, 5650.0959846424375, 5665.915011274917, 5675.772969081356, 4802.399700037496, 5165.66423182492, 5591.6365436246515, 5635.295588051494, 5658.434156584309, 5195.68825870449, 5497.587680923867, 5587.385387607441, 5594.4125866785025, 5662.915056274077, 4831.4202295724735, 5557.666540007613, 5592.720363981866, 5621.184928185472, 5674.602777805514, 5507.421720719427, 5534.1975413565615, 5545.251391062155, 5549.889002219852, 5623.316727543465], "value": 3822.8586588589624, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.8881073433618, 736.2306389822635, 783.8432313537294, 786.9488483248584, 825.3232349473433, 15264.160471174095, 16310.673786524292, 16325.755113673304, 16352.264148113349, 16362.263698133584, 14830.184339861304, 15001.0, 15013.549593512185, 15016.699666006672, 15022.399104035852, 661.9735210591571, 685.3557655803554, 688.7623769799411, 689.8827199376093, 833.7040350675513, 681.7477533312657, 829.2288172000037, 856.1908995998783, 857.8327226190895, 860.9225169734723, 906.995465022674, 909.4725059465527, 919.4529255093242, 919.5448253114685, 921.5392303848076, 913.2876356441986, 915.1946287267228, 916.422653728147, 916.6654171227455, 917.4724533393259, 515.5694994679448, 649.9252585952629, 675.1189697444833, 675.8715843989626, 675.9560628559128, 14915.850841491663, 15011.399544018226, 15035.323410446446, 15061.924690376587, 15062.698746025018, 13867.653308667263, 13970.93014534931, 13982.790258146035, 14295.213763243017, 14384.784228236585, 12766.297853618047, 12852.55016074433, 12910.54813081547, 12987.220766754024, 13088.083834131614, 4009.578994205596, 4534.863954081383, 4668.066386722635, 4693.108309421199, 4789.473157952622, 114596.0, 119239.40380298044, 119348.8065119355, 121308.39345803215, 121403.3929830354, 8994.640214391351, 10973.83539246912, 11228.831567526493, 11409.885901141048, 11701.531938722355, 21650.0, 22119.336419907428, 22282.331530054125, 22330.10679572799, 22345.994430250677, 7650.081990161217, 7816.765497035098, 7959.8408031839035, 7982.161873003367, 8087.433879628487, 3821.178446633987, 4245.066085461193, 4478.126765280804, 4495.977520112444, 4627.722336659811, 3452.3613131570237, 3529.294141171776, 3559.021269151004, 3561.1097225693575, 3563.3051554947056, 554.9223108764776, 589.5990726306068, 611.6299638718511, 622.3341025103097, 624.7407325959675, 510.9361329833771, 519.7920831667287, 598.5425488934371, 644.2816259870211, 691.1671435919704, 8040.959795200986, 8981.910180898107, 9038.728838134735, 9874.654387096418, 10153.446293797111, 571.9342275638271, 644.1593720195191, 670.936261055199, 675.5845155229498, 689.4587748617312, 4279.186954478639, 4447.310666846644, 4725.243960966298, 4743.644226682944, 4754.548317909793, 134738.0, 142882.2855885714, 147012.26493867463, 150147.0, 152517.3958250012, 85160.0, 85737.57131214305, 90075.0, 94407.52796235975, 119453.40273298578, 90740.09259907449, 103004.0, 106300.46849765701, 107231.46384268341, 111485.44257278662, 9099.727008189764, 9747.8302603688, 9892.0, 9905.900940990497, 9924.503774811375, 53284.934301312984, 55820.44179558152, 55823.0, 58130.83738325295, 59630.0, 76177.0, 91434.54282728543, 91685.54157229439, 92552.53723731339, 95445.0, 11047.729511106325, 12705.17416367933, 13062.477500899857, 13258.337083145998, 13563.932180339036, 12644.936775316064, 14676.706465870837, 14954.626134346729, 15124.470643527424, 15721.528354149394, 13756.65608359799, 14013.089149205676, 14063.929680351534, 14185.645358865699, 14556.708865822413, 13741.931290343484, 13745.381457834621, 13900.16599004063, 14097.718045638825, 14519.419223230952, 13547.729045419233, 13884.444622215402, 13912.443502259795, 14202.351028017061, 14285.785713214102, 11267.774644507228, 11334.73575325335, 11407.942960285145, 11502.0, 11596.594119205787, 10052.346597471142, 11044.944775276072, 11091.001809837153, 11337.958374628344, 11553.7111572208], "value": 7768.002419231748, "unit": "score"}], "name": "dromaeo_css", "value": 7284.888767895226, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 165.0, 131.0, 112.0, 111.0, 113.0, 111.0, 111.0, 112.0, 112.0], "value": 119.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 132.0, 130.0, 161.0, 108.0, 128.0, 127.0, 107.0, 161.0, 107.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 187.0, 180.0, 182.0, 186.0, 186.0, 184.0, 182.0, 183.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 80.0, 154.0, 58.0, 58.0, 58.0, 65.0, 141.0, 58.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 137.0, 71.0, 70.0, 84.0, 120.0, 91.0, 95.0, 135.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 84.0, 87.0, 83.0, 86.0, 83.0, 87.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 94.0, 99.0, 143.0, 130.0, 146.0, 97.0, 103.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 84.0, 85.0, 87.0, 85.0, 84.0, 82.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 58.0, 56.0, 53.0, 56.0, 54.0, 54.0, 53.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 73.0, 74.0, 75.0, 73.0, 69.0, 70.0, 71.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 88.0, 85.0, 86.0, 84.0, 88.0, 89.0, 88.0, 110.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 136.0, 140.0, 145.0, 144.0, 145.0, 127.0, 169.0, 150.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 166.0, 223.0, 214.0, 170.0, 170.0, 171.0, 168.0, 168.0, 169.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 65.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1500.6, "alertThreshold": 2.0}]} 07:43:51 INFO - Return code: 0 07:43:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:43:51 INFO - # TBPL SUCCESS # 07:43:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:43:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:43:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:43:51 INFO - 2016-08-25 07:43:51.101 screenresolution[2213:37063] starting screenresolution argv=screenresolution get 07:43:51 INFO - 2016-08-25 07:43:51.105 screenresolution[2213:37063] Display 0: 1600x1200x32@60 07:43:51 INFO - 2016-08-25 07:43:51.111 screenresolution[2214:37066] starting screenresolution argv=screenresolution list 07:43:51 INFO - Available Modes on Display 0 07:43:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:43:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:43:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:43:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:43:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:43:51 INFO - Intel Iris: 07:43:51 INFO - Chipset Model: Intel Iris 07:43:51 INFO - Type: GPU 07:43:51 INFO - Bus: Built-In 07:43:51 INFO - VRAM (Dynamic, Max): 1536 MB 07:43:51 INFO - Vendor: Intel (0x8086) 07:43:51 INFO - Device ID: 0x0a2e 07:43:51 INFO - Revision ID: 0x0009 07:43:51 INFO - Displays: 07:43:51 INFO - Display: 07:43:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:43:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:43:51 INFO - Display Serial Number: 1600x1200 60 07:43:51 INFO - Main Display: Yes 07:43:51 INFO - Mirror: Off 07:43:51 INFO - Online: Yes 07:43:51 INFO - Rotation: Supported 07:43:51 INFO - Return code: 0 07:43:51 INFO - Running post-action listener: _resource_record_post_action 07:43:51 INFO - [mozharness: 2016-08-25 14:43:51.352106Z] Finished run-tests step (success) 07:43:51 INFO - Running post-run listener: _resource_record_post_run 07:43:51 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 4096; Write bytes: 705267712; Read time: 0; Write time: 1955 07:43:51 INFO - TinderboxPrint: CPU usage
53.7% 07:43:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 0 07:43:51 INFO - TinderboxPrint: I/O write bytes / time
705,267,712 / 1,955 07:43:51 INFO - TinderboxPrint: CPU idle
2,248.3 (46.3%) 07:43:51 INFO - TinderboxPrint: CPU system
966.1 (19.9%) 07:43:51 INFO - TinderboxPrint: CPU user
1,639.2 (33.8%) 07:43:51 INFO - TinderboxPrint: Swap in / out
405,110,784 / 0 07:43:51 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388614656; Write bytes: 382297600; Read time: 29269; Write time: 772 07:43:51 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 4096; Write bytes: 297624064; Read time: 0; Write time: 1124 07:43:51 INFO - Running post-run listener: _upload_blobber_files 07:43:51 INFO - Blob upload gear active. 07:43:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:43:51 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 07:43:51 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'] 07:43:51 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 07:43:52 INFO - (blobuploader) - INFO - Open directory for files ... 07:43:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:43:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:53 INFO - (blobuploader) - INFO - Done attempting. 07:43:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:43:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:54 INFO - (blobuploader) - INFO - Done attempting. 07:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:43:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:43:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:43:56 INFO - (blobuploader) - INFO - Done attempting. 07:43:56 INFO - (blobuploader) - INFO - Iteration through files over. 07:43:56 INFO - Return code: 0 07:43:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:43:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:43:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bbd85cd0d7537a4ecff026d7496d00c5e04dc8f8728fdf79da3c8b2e31a75cf57dd73ecf8852c32e3bfa7b3e5755b2faeb68d8fe72173601c8827b1416c0ea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3365ed2e650b41363b7a7a2487b3d925b0c063371864916066e8886bc0a64144556046c472edd55b02d91495b6bb3644d8125986c7dd33562008a56616c1ec14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960842cddf8f5059f9dd107490e9bab328992ed230e9c357f01f25c512ba4f8485e9db20179784d5aefed7e1d226093d8242a4c3048d4c4d947c25acb91b0b40"} 07:43:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:43:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:43:56 INFO - Contents: 07:43:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bbd85cd0d7537a4ecff026d7496d00c5e04dc8f8728fdf79da3c8b2e31a75cf57dd73ecf8852c32e3bfa7b3e5755b2faeb68d8fe72173601c8827b1416c0ea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3365ed2e650b41363b7a7a2487b3d925b0c063371864916066e8886bc0a64144556046c472edd55b02d91495b6bb3644d8125986c7dd33562008a56616c1ec14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960842cddf8f5059f9dd107490e9bab328992ed230e9c357f01f25c512ba4f8485e9db20179784d5aefed7e1d226093d8242a4c3048d4c4d947c25acb91b0b40"} 07:43:56 INFO - Running post-run listener: copy_logs_to_upload_dir 07:43:56 INFO - Copying logs to upload dir... 07:43:56 INFO - mkdir: /builds/slave/test/build/upload/logs 07:43:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.105457 ========= master_lag: 1.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-25 07:43:57.592448) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-08-25 07:43:57.598068) ========= 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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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/9bbd85cd0d7537a4ecff026d7496d00c5e04dc8f8728fdf79da3c8b2e31a75cf57dd73ecf8852c32e3bfa7b3e5755b2faeb68d8fe72173601c8827b1416c0ea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3365ed2e650b41363b7a7a2487b3d925b0c063371864916066e8886bc0a64144556046c472edd55b02d91495b6bb3644d8125986c7dd33562008a56616c1ec14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960842cddf8f5059f9dd107490e9bab328992ed230e9c357f01f25c512ba4f8485e9db20179784d5aefed7e1d226093d8242a4c3048d4c4d947c25acb91b0b40"} build_url:https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/f0wgm4CrTZOcqj45XNEsAQ/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/9bbd85cd0d7537a4ecff026d7496d00c5e04dc8f8728fdf79da3c8b2e31a75cf57dd73ecf8852c32e3bfa7b3e5755b2faeb68d8fe72173601c8827b1416c0ea4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3365ed2e650b41363b7a7a2487b3d925b0c063371864916066e8886bc0a64144556046c472edd55b02d91495b6bb3644d8125986c7dd33562008a56616c1ec14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960842cddf8f5059f9dd107490e9bab328992ed230e9c357f01f25c512ba4f8485e9db20179784d5aefed7e1d226093d8242a4c3048d4c4d947c25acb91b0b40"}' ========= master_lag: 9.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-08-25 07:44:07.163232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-25 07:44:07.163529) ========= 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.0x8b5ogS0O/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.BQlGOrCNcf/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005342 ========= master_lag: 3.70 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-25 07:44:10.867712) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-08-25 07:44:10.868081) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-08-25 07:44:12.671019) ========= ========= Total master_lag: 14.59 =========