builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0061 starttime: 1471024192.82 results: success (0) buildid: 20160812084117 builduid: ab3186a8f2b74e349e2c936c9ceb90ca revision: a977a28fed2dfed886abd85ab641d83bb3a3a279 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:52.821839) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:52.822358) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:52.822665) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:52.849382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:52.849853) ========= 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.zeBXTpXvFk/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.ymur0pUn6T/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-12 10:49:52-- 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% 15.7M=0.001s 2016-08-12 10:49:52 (15.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133637 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:53.006259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:53.006607) ========= 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.zeBXTpXvFk/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.ymur0pUn6T/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.028811 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:53.056996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 10:49:53.057386) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a977a28fed2dfed886abd85ab641d83bb3a3a279 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a977a28fed2dfed886abd85ab641d83bb3a3a279 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zeBXTpXvFk/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.ymur0pUn6T/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-12 10:49:53,134 truncating revision to first 12 chars 2016-08-12 10:49:53,134 Setting DEBUG logging. 2016-08-12 10:49:53,134 attempt 1/10 2016-08-12 10:49:53,134 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a977a28fed2d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 10:49:53,871 unpacking tar archive at: autoland-a977a28fed2d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.012377 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 10:49:54.091916) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:54.092240) ========= script_repo_revision: a977a28fed2dfed886abd85ab641d83bb3a3a279 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:54.092647) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:54.092917) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 10:49:54.110949) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-12 10:49:54.111329) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zeBXTpXvFk/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.ymur0pUn6T/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:49:54 INFO - MultiFileLogger online at 20160812 10:49:54 in /builds/slave/test 10:49:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:49:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:49:54 INFO - {'append_to_log': False, 10:49:54 INFO - 'base_work_dir': '/builds/slave/test', 10:49:54 INFO - 'blob_upload_branch': 'Autoland', 10:49:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:49:54 INFO - 'branch': 'Autoland', 10:49:54 INFO - 'buildbot_json_path': 'buildprops.json', 10:49:54 INFO - 'config_files': ('talos/mac_config.py',), 10:49:54 INFO - 'default_actions': ('clobber', 10:49:54 INFO - 'read-buildbot-config', 10:49:54 INFO - 'download-and-extract', 10:49:54 INFO - 'populate-webroot', 10:49:54 INFO - 'create-virtualenv', 10:49:54 INFO - 'install', 10:49:54 INFO - 'run-tests'), 10:49:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:49:54 INFO - 'download_minidump_stackwalk': True, 10:49:54 INFO - 'download_symbols': 'ondemand', 10:49:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:49:54 INFO - 'tooltool.py': '/tools/tooltool.py', 10:49:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:49:54 INFO - '/tools/misc-python/virtualenv.py')}, 10:49:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:49:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:49:54 INFO - 'installer_path': 'installer.exe', 10:49:54 INFO - 'log_level': 'info', 10:49:54 INFO - 'log_name': 'talos', 10:49:54 INFO - 'log_to_console': True, 10:49:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:49:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:49:54 INFO - 'opt_config_files': (), 10:49:54 INFO - 'pip_index': False, 10:49:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:49:54 INFO - 'cmd': ('bash', 10:49:54 INFO - '-c', 10:49:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:49:54 INFO - 'enabled': True, 10:49:54 INFO - 'halt_on_failure': False, 10:49:54 INFO - 'name': 'check_screen_resolution'},), 10:49:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:49:54 INFO - 'cmd': ('bash', 10:49:54 INFO - '-c', 10:49:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:49:54 INFO - 'enabled': True, 10:49:54 INFO - 'halt_on_failure': False, 10:49:54 INFO - 'name': 'check_screen_resolution'},), 10:49:54 INFO - 'run_cmd_checks_enabled': True, 10:49:54 INFO - 'sps_profile': False, 10:49:54 INFO - 'sps_profile_interval': 0, 10:49:54 INFO - 'suite': 'dromaeojs', 10:49:54 INFO - 'system_bits': '32', 10:49:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:49:54 INFO - 'title': 't-yosemite-r7-0061', 10:49:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:49:54 INFO - 'use_talos_json': True, 10:49:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:49:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:49:54 INFO - 'work_dir': 'build'} 10:49:54 INFO - [mozharness: 2016-08-12 17:49:54.311667Z] Running clobber step. 10:49:54 INFO - Running pre-action listener: _resource_record_pre_action 10:49:54 INFO - Running main action method: clobber 10:49:54 INFO - rmtree: /builds/slave/test/build 10:49:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:49:56 INFO - Running post-action listener: _resource_record_post_action 10:49:56 INFO - [mozharness: 2016-08-12 17:49:56.386355Z] Finished clobber step (success) 10:49:56 INFO - [mozharness: 2016-08-12 17:49:56.386497Z] Running read-buildbot-config step. 10:49:56 INFO - Running pre-action listener: _resource_record_pre_action 10:49:56 INFO - Running main action method: read_buildbot_config 10:49:56 INFO - Using buildbot properties: 10:49:56 INFO - { 10:49:56 INFO - "project": "", 10:49:56 INFO - "product": "firefox", 10:49:56 INFO - "script_repo_revision": "production", 10:49:56 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:49:56 INFO - "repository": "", 10:49:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:49:56 INFO - "buildid": "20160812084117", 10:49:56 INFO - "pgo_build": "False", 10:49:56 INFO - "basedir": "/builds/slave/test", 10:49:56 INFO - "buildnumber": 108, 10:49:56 INFO - "slavename": "t-yosemite-r7-0061", 10:49:56 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:49:56 INFO - "platform": "yosemite_r7", 10:49:56 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:49:56 INFO - "branch": "autoland", 10:49:56 INFO - "repo_path": "integration/autoland", 10:49:56 INFO - "revision": "a977a28fed2dfed886abd85ab641d83bb3a3a279", 10:49:56 INFO - "stage_platform": "macosx64", 10:49:56 INFO - "builduid": "ab3186a8f2b74e349e2c936c9ceb90ca", 10:49:56 INFO - "slavebuilddir": "test" 10:49:56 INFO - } 10:49:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:49:56 INFO - Running post-action listener: _resource_record_post_action 10:49:56 INFO - [mozharness: 2016-08-12 17:49:56.389657Z] Finished read-buildbot-config step (success) 10:49:56 INFO - [mozharness: 2016-08-12 17:49:56.389791Z] Running download-and-extract step. 10:49:56 INFO - Running pre-action listener: _resource_record_pre_action 10:49:56 INFO - Running main action method: download_and_extract 10:49:56 INFO - mkdir: /builds/slave/test/build/tests 10:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:49:56 INFO - trying https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:49:56 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:49:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:49:57 INFO - Downloaded 1199 bytes. 10:49:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:49:57 INFO - Using the following test package requirements: 10:49:57 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:49:57 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:49:57 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:49:57 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'jsshell-mac.zip'], 10:49:57 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:49:57 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:49:57 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:49:57 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:49:57 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:49:57 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:49:57 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:49:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:49:57 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')]} 10:49:57 INFO - trying https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:49:57 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:49:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:49:58 INFO - Downloaded 16328714 bytes. 10:49:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:50:05 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 10:50:05 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')]} 10:50:05 INFO - trying https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:50:05 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:50:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:50:06 INFO - Downloaded 16328714 bytes. 10:50:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:13 INFO - trying https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:50:13 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:50:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:50:14 INFO - Downloaded 14413719 bytes. 10:50:14 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 10:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:14 INFO - trying https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:50:14 INFO - Downloading https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:50:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/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 10:50:19 INFO - Downloaded 110965372 bytes. 10:50:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:50:19 INFO - mkdir: /builds/slave/test/properties 10:50:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:50:19 INFO - Writing to file /builds/slave/test/properties/build_url 10:50:19 INFO - Contents: 10:50:19 INFO - build_url:https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:50:20 INFO - Running post-action listener: _resource_record_post_action 10:50:20 INFO - Running post-action listener: set_extra_try_arguments 10:50:20 INFO - [mozharness: 2016-08-12 17:50:20.197429Z] Finished download-and-extract step (success) 10:50:20 INFO - [mozharness: 2016-08-12 17:50:20.197587Z] Running populate-webroot step. 10:50:20 INFO - Running pre-action listener: _resource_record_pre_action 10:50:20 INFO - Running main action method: populate_webroot 10:50:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:50:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:50:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:50:20 INFO - '--tppagecycles', 10:50:20 INFO - '7'], 10:50:20 INFO - 'tests': ['tsvgm']}}, 10:50:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:50:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:50:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:50:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:50:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:50:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:50:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['damp', 'tps']}, 10:50:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'tests': ['damp', 'tps']}, 10:50:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['dromaeo_dom']}, 10:50:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:50:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['basic_compositor_video']}, 10:50:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:50:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['a11yr', 10:50:20 INFO - 'ts_paint', 10:50:20 INFO - 'tpaint', 10:50:20 INFO - 'sessionrestore', 10:50:20 INFO - 'sessionrestore_no_auto_restore', 10:50:20 INFO - 'tabpaint']}, 10:50:20 INFO - 'other-e10s': {'tests': ['a11yr', 10:50:20 INFO - 'ts_paint', 10:50:20 INFO - 'tpaint', 10:50:20 INFO - 'sessionrestore', 10:50:20 INFO - 'sessionrestore_no_auto_restore', 10:50:20 INFO - 'tabpaint']}, 10:50:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:50:20 INFO - 'ts_paint', 10:50:20 INFO - 'tpaint', 10:50:20 INFO - 'sessionrestore', 10:50:20 INFO - 'sessionrestore_no_auto_restore', 10:50:20 INFO - 'tabpaint']}, 10:50:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:50:20 INFO - 'ts_paint', 10:50:20 INFO - 'tpaint', 10:50:20 INFO - 'sessionrestore', 10:50:20 INFO - 'sessionrestore_no_auto_restore', 10:50:20 INFO - 'tabpaint']}, 10:50:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['a11yr', 10:50:20 INFO - 'ts_paint', 10:50:20 INFO - 'tpaint', 10:50:20 INFO - 'sessionrestore', 10:50:20 INFO - 'sessionrestore_no_auto_restore', 10:50:20 INFO - 'tabpaint']}, 10:50:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['a11yr', 10:50:20 INFO - 'ts_paint', 10:50:20 INFO - 'tpaint', 10:50:20 INFO - 'sessionrestore', 10:50:20 INFO - 'sessionrestore_no_auto_restore', 10:50:20 INFO - 'tabpaint']}, 10:50:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['tsvgx', 10:50:20 INFO - 'tsvgr_opacity', 10:50:20 INFO - 'tart', 10:50:20 INFO - 'tscrollx', 10:50:20 INFO - 'cart']}, 10:50:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:50:20 INFO - 'tsvgr_opacity', 10:50:20 INFO - 'tart', 10:50:20 INFO - 'tscrollx', 10:50:20 INFO - 'cart']}, 10:50:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'talos_options': ['--disable-e10s'], 10:50:20 INFO - 'tests': ['tp5o']}, 10:50:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'tests': ['tp5o']}, 10:50:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'talos_options': ['--disable-e10s', 10:50:20 INFO - '--xperf_path', 10:50:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:50:20 INFO - 'tests': ['tp5n']}, 10:50:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:20 INFO - 'talos_options': ['--xperf_path', 10:50:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:50:20 INFO - 'tests': ['tp5n']}}, 10:50:20 INFO - 'talos.zip': {'path': '', 10:50:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:50:20 INFO - Running post-action listener: _resource_record_post_action 10:50:20 INFO - [mozharness: 2016-08-12 17:50:20.216185Z] Finished populate-webroot step (success) 10:50:20 INFO - [mozharness: 2016-08-12 17:50:20.216293Z] Running create-virtualenv step. 10:50:20 INFO - Running pre-action listener: _resource_record_pre_action 10:50:20 INFO - Running main action method: create_virtualenv 10:50:20 INFO - Creating virtualenv /builds/slave/test/build/venv 10:50:20 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 10:50:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:50:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:50:20 INFO - Using real prefix '/tools/python27' 10:50:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:50:21 INFO - Installing distribute.............................................................................................................................................................................................done. 10:50:23 INFO - Installing pip.................done. 10:50:23 INFO - Return code: 0 10:50:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:50:23 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')]} 10:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:23 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')]} 10:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:23 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:23 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 10:50:23 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 10:50:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:23 INFO - 'HOME': '/Users/cltbld', 10:50:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:23 INFO - 'LOGNAME': 'cltbld', 10:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:23 INFO - 'MOZ_NO_REMOTE': '1', 10:50:23 INFO - 'NO_EM_RESTART': '1', 10:50:23 INFO - 'PAGER': '/bin/cat', 10:50:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:23 INFO - 'PWD': '/builds/slave/test', 10:50:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:23 INFO - 'SHELL': '/bin/bash', 10:50:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:23 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:23 INFO - 'USER': 'cltbld', 10:50:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:23 INFO - 'XPC_FLAGS': '0x0', 10:50:23 INFO - 'XPC_SERVICE_NAME': '0', 10:50:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:24 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 10:50:24 INFO - Cleaning up... 10:50:24 INFO - Return code: 0 10:50:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:24 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:24 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 10:50:24 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 10:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:24 INFO - 'HOME': '/Users/cltbld', 10:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:24 INFO - 'LOGNAME': 'cltbld', 10:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:24 INFO - 'MOZ_NO_REMOTE': '1', 10:50:24 INFO - 'NO_EM_RESTART': '1', 10:50:24 INFO - 'PAGER': '/bin/cat', 10:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:24 INFO - 'PWD': '/builds/slave/test', 10:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:24 INFO - 'SHELL': '/bin/bash', 10:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:24 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:24 INFO - 'USER': 'cltbld', 10:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:24 INFO - 'XPC_FLAGS': '0x0', 10:50:24 INFO - 'XPC_SERVICE_NAME': '0', 10:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:24 INFO - Downloading/unpacking psutil>=3.1.1 10:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:50:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:50:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:50:27 INFO - Installing collected packages: psutil 10:50:27 INFO - Running setup.py install for psutil 10:50:27 INFO - building 'psutil._psutil_osx' extension 10:50:27 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 10:50:28 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 10:50:28 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 10:50:28 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 10:50:28 INFO - building 'psutil._psutil_posix' extension 10:50:28 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 10:50:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:50:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:50:28 INFO - ^ 10:50:28 INFO - 1 warning generated. 10:50:28 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 10:50:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:50:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:50:29 INFO - Successfully installed psutil 10:50:29 INFO - Cleaning up... 10:50:29 INFO - Return code: 0 10:50:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:29 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:29 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 10:50:29 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 10:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:29 INFO - 'HOME': '/Users/cltbld', 10:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:29 INFO - 'LOGNAME': 'cltbld', 10:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:29 INFO - 'MOZ_NO_REMOTE': '1', 10:50:29 INFO - 'NO_EM_RESTART': '1', 10:50:29 INFO - 'PAGER': '/bin/cat', 10:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:29 INFO - 'PWD': '/builds/slave/test', 10:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:29 INFO - 'SHELL': '/bin/bash', 10:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:29 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:29 INFO - 'USER': 'cltbld', 10:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:29 INFO - 'XPC_FLAGS': '0x0', 10:50:29 INFO - 'XPC_SERVICE_NAME': '0', 10:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:50:29 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 10:50:29 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 10:50:29 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 10:50:29 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 10:50:29 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 10:50:29 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 10:50:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:50:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:50:31 INFO - Installing collected packages: mozsystemmonitor 10:50:31 INFO - Running setup.py install for mozsystemmonitor 10:50:31 INFO - Successfully installed mozsystemmonitor 10:50:31 INFO - Cleaning up... 10:50:32 INFO - Return code: 0 10:50:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:32 INFO - 'HOME': '/Users/cltbld', 10:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:32 INFO - 'LOGNAME': 'cltbld', 10:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:32 INFO - 'MOZ_NO_REMOTE': '1', 10:50:32 INFO - 'NO_EM_RESTART': '1', 10:50:32 INFO - 'PAGER': '/bin/cat', 10:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:32 INFO - 'PWD': '/builds/slave/test', 10:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:32 INFO - 'SHELL': '/bin/bash', 10:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:32 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:32 INFO - 'USER': 'cltbld', 10:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:32 INFO - 'XPC_FLAGS': '0x0', 10:50:32 INFO - 'XPC_SERVICE_NAME': '0', 10:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:32 INFO - Downloading/unpacking jsonschema==2.5.1 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:50:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:35 INFO - Downloading functools32-3.2.3-2.tar.gz 10:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:50:35 INFO - warning: no files found matching '*.txt' 10:50:35 INFO - no previously-included directories found matching 'build' 10:50:35 INFO - no previously-included directories found matching 'dist' 10:50:35 INFO - no previously-included directories found matching '.git*' 10:50:35 INFO - Installing collected packages: jsonschema, functools32 10:50:35 INFO - Running setup.py install for functools32 10:50:35 INFO - warning: no files found matching '*.txt' 10:50:35 INFO - no previously-included directories found matching 'build' 10:50:35 INFO - no previously-included directories found matching 'dist' 10:50:35 INFO - no previously-included directories found matching '.git*' 10:50:35 INFO - Successfully installed jsonschema functools32 10:50:35 INFO - Cleaning up... 10:50:35 INFO - Return code: 0 10:50:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:50:35 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')]} 10:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:35 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')]} 10:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:35 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:35 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 10:50:35 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 10:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:35 INFO - 'HOME': '/Users/cltbld', 10:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:35 INFO - 'LOGNAME': 'cltbld', 10:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:35 INFO - 'MOZ_NO_REMOTE': '1', 10:50:35 INFO - 'NO_EM_RESTART': '1', 10:50:35 INFO - 'PAGER': '/bin/cat', 10:50:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:35 INFO - 'PWD': '/builds/slave/test', 10:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:35 INFO - 'SHELL': '/bin/bash', 10:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:35 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:35 INFO - 'USER': 'cltbld', 10:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:35 INFO - 'XPC_FLAGS': '0x0', 10:50:35 INFO - 'XPC_SERVICE_NAME': '0', 10:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:50:35 INFO - Cleaning up... 10:50:35 INFO - Return code: 0 10:50:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:50:35 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')]} 10:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:35 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')]} 10:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:35 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:35 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 10:50:35 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 10:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:35 INFO - 'HOME': '/Users/cltbld', 10:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:35 INFO - 'LOGNAME': 'cltbld', 10:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:35 INFO - 'MOZ_NO_REMOTE': '1', 10:50:35 INFO - 'NO_EM_RESTART': '1', 10:50:35 INFO - 'PAGER': '/bin/cat', 10:50:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:35 INFO - 'PWD': '/builds/slave/test', 10:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:35 INFO - 'SHELL': '/bin/bash', 10:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:35 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:35 INFO - 'USER': 'cltbld', 10:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:35 INFO - 'XPC_FLAGS': '0x0', 10:50:35 INFO - 'XPC_SERVICE_NAME': '0', 10:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:36 INFO - Downloading/unpacking blobuploader==1.2.4 10:50:36 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 10:50:36 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 10:50:36 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 10:50:36 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 10:50:36 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 10:50:36 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 10:50:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:50:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:50:38 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 10:50:38 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 10:50:38 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 10:50:38 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 10:50:38 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 10:50:38 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 10:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:50:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:50:39 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 10:50:39 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 10:50:39 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 10:50:39 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 10:50:39 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 10:50:39 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 10:50:39 INFO - Downloading docopt-0.6.1.tar.gz 10:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:50:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:50:39 INFO - Running setup.py install for blobuploader 10:50:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:50:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:50:39 INFO - Running setup.py install for requests 10:50:40 INFO - Running setup.py install for docopt 10:50:40 INFO - Successfully installed blobuploader requests docopt 10:50:40 INFO - Cleaning up... 10:50:40 INFO - Return code: 0 10:50:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50: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')]} 10:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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')]} 10:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:40 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:40 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 10:50:40 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 10:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:40 INFO - 'HOME': '/Users/cltbld', 10:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:40 INFO - 'LOGNAME': 'cltbld', 10:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:40 INFO - 'MOZ_NO_REMOTE': '1', 10:50:40 INFO - 'NO_EM_RESTART': '1', 10:50:40 INFO - 'PAGER': '/bin/cat', 10:50:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:40 INFO - 'PWD': '/builds/slave/test', 10:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:40 INFO - 'SHELL': '/bin/bash', 10:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:40 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:40 INFO - 'USER': 'cltbld', 10:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:40 INFO - 'XPC_FLAGS': '0x0', 10:50:40 INFO - 'XPC_SERVICE_NAME': '0', 10:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:50:40 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-uta_R7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:50:40 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Gx4PlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-6jfGVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-QhsHDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-q60MPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-7z8fLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ckIHcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-NITDlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-SUOrjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ti7yxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-jIDbjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:50:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:50:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-YTA5yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:50:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-EdvREC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:50:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-1I6BAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-stXGch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:50:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-6BjZ78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:50:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-O_7oDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:50:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:50:42 INFO - Running setup.py install for manifestparser 10:50:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:50:42 INFO - Running setup.py install for mozcrash 10:50:42 INFO - Running setup.py install for mozdebug 10:50:43 INFO - Running setup.py install for mozdevice 10:50:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Running setup.py install for mozfile 10:50:43 INFO - Running setup.py install for mozhttpd 10:50:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Running setup.py install for mozinfo 10:50:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Running setup.py install for mozInstall 10:50:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:50:43 INFO - Running setup.py install for mozleak 10:50:43 INFO - Running setup.py install for mozlog 10:50:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:50:44 INFO - Running setup.py install for moznetwork 10:50:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:50:44 INFO - Running setup.py install for mozprocess 10:50:44 INFO - Running setup.py install for mozprofile 10:50:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:50:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:50:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:50:44 INFO - Running setup.py install for mozrunner 10:50:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:50:44 INFO - Running setup.py install for mozscreenshot 10:50:44 INFO - Running setup.py install for moztest 10:50:45 INFO - Running setup.py install for mozversion 10:50:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:50:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:50:45 INFO - Cleaning up... 10:50:45 INFO - Return code: 0 10:50:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50:45 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')]} 10:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:45 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')]} 10:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:45 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:45 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 10:50:45 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 10:50:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:45 INFO - 'HOME': '/Users/cltbld', 10:50:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:45 INFO - 'LOGNAME': 'cltbld', 10:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:45 INFO - 'MOZ_NO_REMOTE': '1', 10:50:45 INFO - 'NO_EM_RESTART': '1', 10:50:45 INFO - 'PAGER': '/bin/cat', 10:50:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:45 INFO - 'PWD': '/builds/slave/test', 10:50:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:45 INFO - 'SHELL': '/bin/bash', 10:50:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:45 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:45 INFO - 'USER': 'cltbld', 10:50:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:45 INFO - 'XPC_FLAGS': '0x0', 10:50:45 INFO - 'XPC_SERVICE_NAME': '0', 10:50:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:50:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-190hVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:50:45 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)) 10:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:50:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-VK1bUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:50:45 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)) 10:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:50:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-0ifYjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:50:45 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)) 10:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:50:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-__kARy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:50:45 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)) 10:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:50:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-T1oqTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:50:45 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)) 10:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:50:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-vzdMPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-utmx56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-TMNucu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-QuUqfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Iw7SYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-AspSQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Q4I6Ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-7oquV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-8ZwDdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:50:46 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)) 10:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-VV8RYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:47 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)) 10:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:50:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-hF4gVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:50:47 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)) 10:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:50:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-91JJ_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:50:47 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)) 10:50:47 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)) 10:50:47 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)) 10:50:47 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)) 10:50:47 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)) 10:50:47 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)) 10:50:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:49 INFO - Downloading blessings-1.6.tar.gz 10:50:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:50:49 INFO - Installing collected packages: blessings 10:50:49 INFO - Running setup.py install for blessings 10:50:49 INFO - Successfully installed blessings 10:50:49 INFO - Cleaning up... 10:50:50 INFO - Return code: 0 10:50:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:50:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:50:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:50:50 INFO - Reading from file tmpfile_stdout 10:50:50 INFO - Current package versions: 10:50:50 INFO - blessings == 1.6 10:50:50 INFO - blobuploader == 1.2.4 10:50:50 INFO - docopt == 0.6.1 10:50:50 INFO - functools32 == 3.2.3-2 10:50:50 INFO - jsonschema == 2.5.1 10:50:50 INFO - manifestparser == 1.1 10:50:50 INFO - mozInstall == 1.12 10:50:50 INFO - mozcrash == 0.17 10:50:50 INFO - mozdebug == 0.1 10:50:50 INFO - mozdevice == 0.48 10:50:50 INFO - mozfile == 1.2 10:50:50 INFO - mozhttpd == 0.7 10:50:50 INFO - mozinfo == 0.9 10:50:50 INFO - mozleak == 0.1 10:50:50 INFO - mozlog == 3.3 10:50:50 INFO - moznetwork == 0.27 10:50:50 INFO - mozprocess == 0.23 10:50:50 INFO - mozprofile == 0.28 10:50:50 INFO - mozrunner == 6.12 10:50:50 INFO - mozscreenshot == 0.1 10:50:50 INFO - mozsystemmonitor == 0.3 10:50:50 INFO - moztest == 0.7 10:50:50 INFO - mozversion == 1.4 10:50:50 INFO - psutil == 3.1.1 10:50:50 INFO - requests == 1.2.3 10:50:50 INFO - wsgiref == 0.1.2 10:50:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50: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')]} 10:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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')]} 10:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:50 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:50 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 10:50:50 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 10:50:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:50 INFO - 'HOME': '/Users/cltbld', 10:50:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:50 INFO - 'LOGNAME': 'cltbld', 10:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:50 INFO - 'MOZ_NO_REMOTE': '1', 10:50:50 INFO - 'NO_EM_RESTART': '1', 10:50:50 INFO - 'PAGER': '/bin/cat', 10:50:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:50 INFO - 'PWD': '/builds/slave/test', 10:50:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:50 INFO - 'SHELL': '/bin/bash', 10:50:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:50 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:50 INFO - 'USER': 'cltbld', 10:50:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:50 INFO - 'XPC_FLAGS': '0x0', 10:50:50 INFO - 'XPC_SERVICE_NAME': '0', 10:50:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 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)) 10:50:50 INFO - Cleaning up... 10:50:50 INFO - Return code: 0 10:50:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:50: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')]} 10:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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')]} 10:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:50 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 0x107779a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10780e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1d357c990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, '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.zeBXTpXvFk/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.ymur0pUn6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 10:50:50 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 10:50:50 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 10:50:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:50:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:50 INFO - 'HOME': '/Users/cltbld', 10:50:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:50 INFO - 'LOGNAME': 'cltbld', 10:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:50 INFO - 'MOZ_NO_REMOTE': '1', 10:50:50 INFO - 'NO_EM_RESTART': '1', 10:50:50 INFO - 'PAGER': '/bin/cat', 10:50:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:50 INFO - 'PWD': '/builds/slave/test', 10:50:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:50 INFO - 'SHELL': '/bin/bash', 10:50:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:50:50 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:50:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:50 INFO - 'USER': 'cltbld', 10:50:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:50 INFO - 'XPC_FLAGS': '0x0', 10:50:50 INFO - 'XPC_SERVICE_NAME': '0', 10:50:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:50:50 INFO - Cleaning up... 10:50:50 INFO - Return code: 0 10:50:50 INFO - Running post-action listener: _resource_record_post_action 10:50:50 INFO - Running post-action listener: _start_resource_monitoring 10:50:50 INFO - Starting resource monitoring. 10:50:50 INFO - [mozharness: 2016-08-12 17:50:50.925726Z] Finished create-virtualenv step (success) 10:50:50 INFO - [mozharness: 2016-08-12 17:50:50.926761Z] Running install step. 10:50:50 INFO - Running pre-action listener: _resource_record_pre_action 10:50:50 INFO - Running main action method: install 10:50:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:50:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:50:51 INFO - Reading from file tmpfile_stdout 10:50:51 INFO - Detecting whether we're running mozinstall >=1.0... 10:50:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:50:51 INFO - Reading from file tmpfile_stdout 10:50:51 INFO - Output received: 10:50:51 INFO - Usage: mozinstall [options] installer 10:50:51 INFO - Options: 10:50:51 INFO - -h, --help show this help message and exit 10:50:51 INFO - -d DEST, --destination=DEST 10:50:51 INFO - Directory to install application into. [default: 10:50:51 INFO - "/builds/slave/test"] 10:50:51 INFO - --app=APP Application being installed. [default: firefox] 10:50:51 INFO - mkdir: /builds/slave/test/build/application 10:50:51 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'] 10:50:51 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 10:51:25 INFO - Reading from file tmpfile_stdout 10:51:25 INFO - Output received: 10:51:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:51:25 INFO - Running post-action listener: _resource_record_post_action 10:51:25 INFO - [mozharness: 2016-08-12 17:51:25.313290Z] Finished install step (success) 10:51:25 INFO - [mozharness: 2016-08-12 17:51:25.313422Z] Running run-tests step. 10:51:25 INFO - Running pre-action listener: _resource_record_pre_action 10:51:25 INFO - Running main action method: run_tests 10:51:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:51:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:51:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:51:25 INFO - 2016-08-12 10:51:25.328 screenresolution[4913:22596] starting screenresolution argv=screenresolution get 10:51:25 INFO - 2016-08-12 10:51:25.330 screenresolution[4913:22596] Display 0: 1600x1200x32@60 10:51:25 INFO - 2016-08-12 10:51:25.336 screenresolution[4914:22598] starting screenresolution argv=screenresolution list 10:51:25 INFO - Available Modes on Display 0 10:51:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:51:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:51:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:51:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:51:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:51:25 INFO - Intel Iris: 10:51:25 INFO - Chipset Model: Intel Iris 10:51:25 INFO - Type: GPU 10:51:25 INFO - Bus: Built-In 10:51:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:51:25 INFO - Vendor: Intel (0x8086) 10:51:25 INFO - Device ID: 0x0a2e 10:51:25 INFO - Revision ID: 0x0009 10:51:25 INFO - Displays: 10:51:25 INFO - Display: 10:51:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:51:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:51:25 INFO - Display Serial Number: 1600x1200 60 10:51:25 INFO - Main Display: Yes 10:51:25 INFO - Mirror: Off 10:51:25 INFO - Online: Yes 10:51:25 INFO - Rotation: Supported 10:51:25 INFO - Return code: 0 10:51:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:51:25 INFO - Python 2.7.3 10:51:25 INFO - Return code: 0 10:51:25 INFO - grabbing minidump binary from tooltool 10:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:51:25 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 0x1076ddd30>, '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 0x107442db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:51:25 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 10:51:25 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 10:51:25 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 10:51:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:51:25 INFO - Return code: 0 10:51:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:51:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:51:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:51:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:51:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:51:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 10:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0061 --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 10:51:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeBXTpXvFk/Render', 10:51:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:25 INFO - 'HOME': '/Users/cltbld', 10:51:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:25 INFO - 'LOGNAME': 'cltbld', 10:51:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:51:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:25 INFO - 'MOZ_NO_REMOTE': '1', 10:51:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:51:25 INFO - 'NO_EM_RESTART': '1', 10:51:25 INFO - 'PAGER': '/bin/cat', 10:51:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:25 INFO - 'PWD': '/builds/slave/test', 10:51:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:51:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:25 INFO - 'SHELL': '/bin/bash', 10:51:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ymur0pUn6T/Listeners', 10:51:25 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 10:51:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:25 INFO - 'USER': 'cltbld', 10:51:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:25 INFO - 'XPC_FLAGS': '0x0', 10:51:25 INFO - 'XPC_SERVICE_NAME': '0', 10:51:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 10:51:25 INFO - mozversion application_buildid: 20160812084117 10:51:25 INFO - mozversion application_changeset: a977a28fed2dfed886abd85ab641d83bb3a3a279 10:51:25 INFO - mozversion application_display_name: Nightly 10:51:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:51:25 INFO - mozversion application_name: Firefox 10:51:25 INFO - mozversion application_remotingname: firefox 10:51:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:51:25 INFO - mozversion application_vendor: Mozilla 10:51:25 INFO - mozversion application_version: 51.0a1 10:51:25 INFO - mozversion platform_buildid: 20160812084117 10:51:25 INFO - mozversion platform_changeset: a977a28fed2dfed886abd85ab641d83bb3a3a279 10:51:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:51:25 INFO - mozversion platform_version: 51.0a1 10:51:25 INFO - using testdate: 1471024285 10:51:25 INFO - actual date: 1471024285 10:51:25 INFO - starting webserver on 'localhost:49353' 10:51:25 INFO - SUITE-START | Running 2 tests 10:51:25 INFO - TEST-START | dromaeo_css 10:51:25 INFO - operating with platform_type : mac_ 10:51:25 INFO - Initialising browser for dromaeo_css test... 10:51:25 INFO - TEST-INFO | started process 4924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmp36CI_I/profile http://localhost:49353/getInfo.html) 10:51:28 INFO - PROCESS | 4924 | __metrics Screen width/height:1600/1200 10:51:28 INFO - PROCESS | 4924 | colorDepth:24 10:51:28 INFO - PROCESS | 4924 | Browser inner width/height: 1024/689 10:51:28 INFO - PROCESS | 4924 | __metrics 10:51:28 INFO - PROCESS | 4924 | 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" 10:51:28 INFO - PROCESS | 4924 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:51:29 INFO - TEST-INFO | 4924: exit 0 10:51:29 INFO - Browser initialized. 10:51:29 INFO - Running cycle 1/1 for dromaeo_css test... 10:51:29 INFO - TEST-INFO | started process 4927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmp36CI_I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:41 INFO - PROCESS | 4927 | RSS: Main: 243593216 10:51:41 INFO - PROCESS | 4927 | 10:52:32 INFO - PROCESS | 4927 | 1471024352345 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] 10:54:44 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49353/tests/dromaeo/cssquery-ext.html) 10:54:45 INFO - PROCESS | 4927 | RSS: Main: 373395456 10:54:45 INFO - PROCESS | 4927 | 10:57:48 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-ext.html (next: http://localhost:49353/tests/dromaeo/cssquery-jquery.html) 10:57:49 INFO - PROCESS | 4927 | RSS: Main: 384290816 10:57:49 INFO - PROCESS | 4927 | 11:00:51 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49353/tests/dromaeo/cssquery-mootools.html) 11:00:52 INFO - PROCESS | 4927 | RSS: Main: 349261824 11:00:52 INFO - PROCESS | 4927 | 11:03:54 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49353/tests/dromaeo/cssquery-prototype.html) 11:03:55 INFO - PROCESS | 4927 | RSS: Main: 376705024 11:03:55 INFO - PROCESS | 4927 | 11:06:58 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49353/tests/dromaeo/cssquery-yui.html) 11:06:59 INFO - PROCESS | 4927 | RSS: Main: 346243072 11:06:59 INFO - PROCESS | 4927 | 11:10:02 INFO - PROCESS | 4927 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-yui.html (next: http://localhost:49353/tests/dromaeo/cssquery-dojo.html) 11:10:03 INFO - PROCESS | 4927 | RSS: Main: 482263040 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | __start_tp_report 11:10:03 INFO - PROCESS | 4927 | _x_x_mozilla_page_load 11:10:03 INFO - PROCESS | 4927 | _x_x_mozilla_page_load_details 11:10:03 INFO - PROCESS | 4927 | |i|pagename|runs| 11:10:03 INFO - PROCESS | 4927 | |0;dojo.html;3130.436521426143;3362.260302733398;3380.1380647934775;3387.1701433148883;3413.8805141820026;5556.305461817273;5636.15457681348;5661.801836935708;5708.628939118959;5746.913796293059;5154.458781827901;5630.605857589971;5758.049921762915;5766.855828604287;5775.075987841936;5126.897462050757;5165.793368265284;5213.191955246943;5234.345706786652;5309.681419114865;5302.787888484456;5314.123169676998;5332.093544097494;5350.652207606513;5363.061464243761;5156.587473002151;5172.353455818023;5205.609579281569;5234.450382709799;5250.159974404096;5187.636865419422;5203.557697595709;5205.349331333583;5212;5280.683159010472;5180.197069454203;5215.3741551013745;5239.187925871458;5269.525742683139;5269.552088072518;5592.86017849553;5622.718864056781;5745.224394706703;5748.396418376052;5774.5091667208335;6225.937740622581;6260.812175634738;6265.718042688044;6272.780452684134;6276.560640755149;5237.5548078413;6248.75004999804;6290;6291.496680265614;6305.873882522324;5674.460926212003;5762.164486149485;5830.2420685310635;5838.67887266196;5856.736446859902;6266.216722909637;6270.968645156836;6288.151099601587;6296.937030629727;6298.779542715983;6197.4732147767945;6200.720967556471;6210.844728881814;6214.9689251553455;6220.377962203744;6098.969505152446;6154.7230374633245;6194.845128871724;6247.437730604267;6275.309715931244;6194.783182588678;6229.127922090911;6238.407351301619;6246.656433896179;6261.060840873905;6234.2830574483605;6241.843953901189;6251.0623406488485;6274.717637706314;6283.842903927439;4759.0719809637485;4888.780004899788;5019.071471777733;5021.221710634784;5052.57053150479;4401.031773009932;4436.711613745161;4458.4426946631675;4462.486814016364;4465.4864690561;4689.6248300136585;5560.165975103686;5571.164325351148;5575.303087114111;5585.69278689676;6096.556923351615;6106.885399019288;6119.258838874149;6137.591499645514;6199;5716.542676585863;5718.513926316226;5731.828045158652;5764.942350576524;5778.768849245983;5423.457654234624;5428.131498960226;5440.3743569489225;5449.809256675997;5456.7544460499375;6192.792405481068;6200.534959878028;6236.126942228182;6356.745730170742;6367.395097465734;6248.968755156195;6277.5605707600025;6278.46633036197;6299.527535434952;6304.621722696653;6288.151099601495;6304.842378940563;6308.306086330592;6321.115043893767;6338.176037115146;6061.060535616986;6107.46191531438;6133.325334213144;6160.291566469913;6181.103739957771;6218.129461875251;6273.341299163704;6284.214473190851;6287.245530536365;6288.056791481132;6135.049067394561;6139.386061393825;6142.232220972379;6156.753729850756;6163.907541386793;5933.3769954154195;5980.5215582754145;6025.638461692312;6133.080037994337;6175.722092505758;5991.520678345635;5994.6403215807195;5995.340512543704;6023.36754640774;6041.667708276087;6098.420650038239;6123.632582045091;6138.907916381348;6150.446459818638;6167.784127555694;5800.622959507618;5885.058390657474;5928.2293301872205;5982.282126144891;5996.040633498619 11:10:03 INFO - PROCESS | 4927 | |1;ext.html;476.45683920330816;520.9036328279269;524.7271418862192;530.077664863138;575;31495.89764358245;41350.58649413505;42534.36198457022;42700.78649606748;44915.55084449155;43556.3466548002;43945;43948.12103757923;43991.78004109983;44184;8981.832361792958;9583.281253905985;9724.611015559369;9976.950115249414;9988.1510071644;9777.706668799912;9890.357126786736;9894.158996485306;9899.356541824794;9915.305928585001;9885.110340069392;9913.950430247838;9947.353422027581;9947.701568952943;9957.950210248939;9809.852852207223;9896.356736812084;9899.406035637812;9917.950410247939;9923.1069203772;9843.557039933148;9860.65487707934;9882.061204185591;9883.752906177331;9887.060729230712;41143.588564114274;41574.75275741702;41579.1684166318;41580.58419415797;41678;2987.2979849735293;3481.8085005324697;3629.6551827576336;3671.375866102769;3912.491376121086;31553.842230788698;32125.51811722826;32981.01056968295;33175.502367464505;33234.50148247778;3271.558339624144;3272.7381809455187;3289.19414743387;3338.6327503974544;3351.748618853596;508340;512811;514058;514894;515003.4249828727;33525.49711754326;60458;60653;60728.785424291265;60874.78250435055;34523.30953380918;35887.64112358843;35901.46147807785;36019;36022.63977360245;14830.40678372853;15031.549053528412;15035.398584056735;15058;15061.322240499203;12706.428210730539;12774.041946854024;12797.424115914806;12804.103712740205;12812.80780788289;7870.645820938072;7889.09275433321;7894.960525197337;7969.203079691984;7977.082635496876;3045.5433083450002;3058.464768665475;3225.3226822367565;3277.6066871975518;3324.5844269466315;889.2796834564024;952.8856537215579;971.6987733802544;974.8878878928872;1143.502576379261;27159.506227157697;30476.54285185724;30719.520452463494;31002.844985774926;31181.844090779854;938.2165891480554;1007.5969612155196;1036.3315661398356;1050.5902697947806;1098.1379617000616;12699;15551.30019149141;15937.282822273026;16002.75995860063;16612;192963;193939.03030484758;193992;710476;711966;60227;60776.69611652002;60835.69582152149;60934.695326523084;60957;1077.42357838556;1176.8352430659716;1228.2446295528364;1267.416988185445;1396.2390497179063;30128.698713013466;31551.684483154873;31822.045338639877;32307.838460807547;32885;58198.41801582099;59190;59250.11124833217;59264.81470370655;59341.109883350924;659302;665558;665816;666911.6654416696;667211;27525.72474275312;28306;28366.00718974826;29403;44309.11381772411;48508;48856;49314.75342623408;49788.75105624449;50068.749656251486;39657.00857478586;40162.598374015884;40921.38617920793;40973.59026409698;41068.06022147567;39476;39521.80239098786;40190.598094018686;41247.58752412438;41759;38959.41560876646;39509.40735888907;39607.60392396039;39969.40045899255;40070.79787606376;32229.677703223606;34388;34688.65311346854;34957;35160.29679406449;22105.557888842453;25830.45017298968;25990.480190396465;26283.342916426478;26328.868355658866 11:10:03 INFO - PROCESS | 4927 | |2;jquery.html;355.0520111302821;379.0731661088638;379.4933763425826;386.74281602734175;408.634272326268;14351.282435878204;14506.854931450682;19860.006999649995;20551;20617.793822061776;20699.17203311866;21005.36983890486;21026.317894568398;21057.05243264053;21103.894480527615;4908.361912951312;5969.432903874125;6008.2092363823685;6040.848978775522;6082.148499210114;5639.577031722617;5974.402559744012;5975.910361344583;5978.8804223915495;5989.3112292086535;5785.855353616173;5856.474569523258;5917.615355001933;5922.88154236915;5926.259217597801;5946.375630558794;5984;5986.2517185351835;5986.640801551899;5991.101334799815;4971.975140124312;5161.845144645667;5222.190560463135;5229.08491014071;5234.528892399702;26011.219663410127;26018;26104.60843087355;26135.869320653466;26411;1767.0722870492966;1831.6611426886068;2458.8770561471856;2684.3825920038503;3151.9054428367185;14950.70098598022;16241.2691428886;16257.83742162575;16626.168691565374;16662.083585402797;1647.9093649849253;1817.972730409045;1926.6146770645787;1968.271739456396;1981.1778112083468;94136;116206;116298.99999999916;121303;125191.12213316807;11346.48940797666;11482.942585287186;11724.120690948213;11759.941200294115;11965.40172991347;14970.476033338999;14982.475613353481;14983.250837458301;14990.550283491513;15040.473583424746;8491.405601607952;8756;8771.386002979858;8901.73294801157;8937.865932011026;8658.653653853775;8672.739817805475;8699.739007829776;8821.426607270636;8841.867371989425;4569.474510431343;4659.301104834302;4664.346991421204;4675.438947326343;4738.223166312633;1721.3544920654754;1816.5731053202487;1947.4449781812398;1949.541857663448;1949.7465329507356;730.9524880882726;849.7493239494364;901.693424235763;1015.3958394755109;1023.9283250172565;20364.28724994618;21412.785872141078;21518.892405537874;21544.245951391626;21626.567468650854;880.6873559886276;882.5366682491743;895.8118795052982;919.9862002069974;980.9411435313904;8722.520261385685;10875.456227188768;11279.041281491165;11326.830097548544;11338.43307834605;540296;600731;613158.9342053261;613397;614654;83326.583367084;124408;136454;136467.31766341304;143478;809.9473534220257;931.2270815223349;969.7817990951979;970.631160159135;1027.3219675014604;19438.611227775647;20002.081691522802;20112.195512179347;20146.731925158696;20211.494712632302;42158.78920605377;54327.185092222855;54874.902501950535;55679.16481252865;55777.44222557722;73745.89381159394;75802.24197758173;82789.17210827814;83731.16268837477;83849;8005.719799806979;8235.29999950011;8248.917510824815;8304.086550479564;8313.750587482386;10798;12795.040371972325;13143.539976101172;13151.473941042252;13208.603741887759;10593.735156621146;11052.281601695864;11181.832272516082;11237.494312755782;11331.490082946451;10981.725456863642;11009.724756881142;11201.383923884265;11351;11531.308121512737;9267.67563135287;11384.544618215179;11507.712307192387;11576.652700419001;11631.360275184947;6472.449841763503;7485.5508669480005;7569.47013709035;8089.2315230053055;8391.622376992927;11243.831342529706;11535;11681.06551475897;11707.88292117068;11724.355160465915 11:10:03 INFO - PROCESS | 4927 | |3;mootools.html;1496.319174775477;1532.1420004797315;1715.2538645689126;1863.3943968210335;1892.7823300320467;7868.803279917991;8093;8261.463004904677;8821.867671984925;8841.734747957555;8240.175982401772;8476.576171191431;8486.405951583376;8579.184977427134;8598.097199794025;2347.5187586544716;2381.333226696519;2384.2132096408145;2384.9165279215144;2386.0455817672896;2094.2251366994233;2173.56528694261;2337.4039619896903;2364.2316247219724;2398.832081754278;2154.0845140815168;2177.803997640212;2180.3349978256624;2302.182725132579;2372.41875740443;2324.32594547581;2332.521833024226;2334;2337.8714170720605;2340.4967931894553;2319.176692274252;2319.2346525646494;2356.5404746074523;2356.587597170488;2357.5049239659693;8718.389712720113;8782.736517904474;8790.912090879137;8797.47215167092;8797.736067917973;4722.126406614787;4923.433805112422;5030.421501527335;5276.604254680876;5282.920756188661;3302.2734998300334;3432.5022871683714;3582.265635544707;3589.0309616403424;3598.514200582914;995.9701208963743;1021.6730646193221;1049.0296475759892;1049.9895001049968;1057.6404022632264;11438.542458301657;11442;11446.942765286203;11470.139739519485;11478.885211147865;4572.725636461823;4635.327877457749;4747.715137091716;4810.4467986181335;4850.248211527219;9050.547472626342;9113.863292050624;9124.361294709439;9127.863082053775;9149.268058555299;5229.633925625246;5230.660007099526;5231.712255825966;5239.292695486137;5239.921401178985;3937.8424863005157;4700.130475861976;4841.927371089437;4913.852584422473;4923.212286034289;3233.8868139615;3463.5324231228965;3607.9434722208616;3613.0786649404236;3664.670179683841;1113.6158025481195;1118.9888101119047;1122.0350498571222;1130.5647325779635;1132.7168207948014;772.9806754831062;900.9459432434074;926.745145085107;949.4255975135083;955.9617615295449;5612.382637909826;5671.432856714296;5864.179014937912;6113;6132.8773424532155;451.95706407891197;459.4326007380882;460.53946053946055;1016.9694909152737;1042.9895701042892;3866.188100498927;3976.603517457527;4006.258842114218;4047.575004624472;4151.045259590251;10291.794164116676;10588.841167382496;10627.04356607909;10643.201759868041;10647.25469217163;10597;10605.363678179334;10606.363618182933;10624.096951759188;10628;7935.801604959922;8059.959700201579;8104.918950810416;8216.958915205385;8239.95880020596;5809.82570522885;5840.386759390203;5902.704864756831;5944.524438045032;5969.104634304716;9539.236861051239;9881.258905581966;9891.35706179096;10000.2999790014;10037.247206459399;9528.333016688764;10063.849042264226;10131.733533308336;10251.231157663056;10257.538410771682;7248.528845625017;7501.549907005598;7597.582132982739;7608.429367797548;7617.200193979553;8117.269445749911;8198.836023279382;8208.630611622611;8216.21945915137;8220.3834712398;7018.613976231356;7163.176234732864;7188.245234250538;7215.206327304098;7509.549427034395;7202.755315962989;7262.6731797070315;7271.563706177646;7455.403567714461;7474;6817.386435220656;6986.126734158231;6991.615461149686;6995.230524642184;7013.403860671696;4943.171026720137;4991.9500804991485;5018.046571151615;5031.471695472069;5137.306463627362;4303.462067241595;4926.433460152129;5000.349744686327;5201.531862132275;5230.581553475637 11:10:03 INFO - PROCESS | 4927 | |4;prototype.html;261.3622760464467;282.32524267001855;290.40032333231875;297.6799940064431;312.02025639492;4265.976165720228;4682.672212945095;4724.598409135219;5097.439281679017;5493.121100623901;5057.671251368659;5400.676834175632;5408.4050754417085;5410.295756836594;5421.457854214586;3512.666296701809;4028.529586700862;4055.3308704063793;4088.5093788745394;4093.263212621742;3518.47222916562;3896.707746918978;4110.362893751473;4111.259973204822;4129.339305711087;3498.982505087471;3613.7289703272227;3837.827297771607;4067.491563554556;4083.9387409188885;3504.6331930547235;3922.3135951208424;4053.7567745935044;4071.6946229032646;4072.2873497137884;3540.6636369544854;3543.8936831895085;3552.236269202134;3556.253186830743;3570.732195085379;4486.842960496399;4909.754512274372;5243.187305967543;5243.606729495303;5294.444083371268;4635.172544747701;4652.906941861143;4658.324542941255;4674.555917187861;4674.626029917633;5062;5336.359636843604;5617.4382561743905;5661.801836935729;5679.914801277984;3717.219383929378;3835.4055121456217;3853.903652408684;3880.47613572167;3883.359245724452;4905.5094490550655;5911.379305172938;5968.14987353926;5996.97001514994;6001.589626437783;3524.3479956208184;3798.5631652360335;3970.6029397060065;3972.1261322509004;3976.681865450757;3412.351653185887;3819.4652748615217;3974.900627484278;3976.3439032559304;3989.5863002883416;3292.522584225274;4430.778461076998;4486.865394038184;4566.246569316026;4706.458757242893;5611.102223644198;5625.859353516113;5635.731275859991;5653.180288858092;5682.602217844794;1326.8474125475693;1345.5425155447194;1350.8382790799903;1352.8106065150887;1364.5565191312862;726.6947881889619;802.4342838298985;821.1665159862744;825.9710910118238;827.7061643116607;3767.830447629863;3990.244292511286;4103.4710149271605;4388.912221755547;4411.470623525198;4469.374287599739;4637.536246375403;4868.318435419044;4892.339534162914;4898.877528061756;3506.878877798761;4375.431193944831;4411.029573493826;4413.492448368414;4414.690971631955;2116.8518203725594;2303.953920921606;2499.3631718537117;2561.462092960462;2589.948201035969;4120.546739858613;4290.742555446684;4323.162655871242;4452.041101915147;4580.1755683976535;4232.15961972476;4774.734695305747;4777.976110119427;4812.5668689818085;4917.754112294371;4511.458624964959;5070.923936140961;5713.228714123707;6148.815535533941;6174.320824709278;5565.5825813063375;5581.944180558305;5923.763049477972;5924.940750592611;5943.791967281298;5060.811688636136;5454.890902182014;5627.409122042291;5785.27684039495;5849.473547380756;4485.37204791323;5268.525832675077;5853.590248682552;6098.493366314298;6113.602615829956;3928.9214215716097;3967.8809635710977;3975.801209939549;4000.6799456044027;4007.6393124618926;5082.059818933509;5275.419703832498;5795.536357091507;5896.6756828374855;5988.700564971647;4454.26504626733;5058.9494105058475;5470.987867244572;5593.720313984203;5610.7194640268635;4858.3441235432765;5176.2494438306985;5188.507091826271;5687.459691329317;5720.713964301852;4397.868063958086;4727.763611819327;5351.705656188947;5577.235366127767;5594;4586.931196031996;5696.658200507983;5720.456556627114;5733.426657334233;5743.310802703702;5569.637973531708;5583.664980101207;5589.748461319169;5598.832035038955;5635 11:10:03 INFO - PROCESS | 4927 | |5;yui.html;721.2607077745314;723.598402886398;771.0015529888793;814.8451794159113;824.9628766705498;15033.548993530185;16423.671526569462;16447.753283700753;16449.506514804576;16478;14834.851651483483;14930.925345373258;14946.10323380598;14946.925265373686;14950.476733314335;673.3939454490949;697.4106879686682;830.2942498875938;840.1556435782046;858.4463021351256;838.0404436919723;855.5038077914826;856.7900864288227;857.237059017475;857.4512312120216;913.4154141349542;915.3363811236867;915.702396721065;916.3814425262955;916.4134953629682;720.1142594608608;744.1033554566734;748.6331697468256;909.7816524034217;911.0297533127232;553.2282465960003;554.0276814191069;651.1892693596456;672.4250765595394;674.5952428542835;14579.562613121623;14740.557783266519;14767.335469903881;14789;14838.258087095603;11252.606158784403;11859.229150105215;13685.657858553517;13763.036587438883;13811.309434528233;12575.622731318075;12747.490100396066;12777.361131943366;12861.935690321581;13368.598942031755;4146.460960075201;4541.364209010741;4706.22347312693;4753.04939012199;4788.497207793202;93853.5307323459;118608;118934.40532797367;122301.38849305785;123669;10708.518116684769;11035.724106897393;11194.66416007521;11447.885521144848;11475.827862582068;20891.37325880226;21236;21369.358919232447;21389.572208555743;21408.678869816966;7859.214078592095;8014.062204340157;8027.719029834045;8190.959045204736;8306.958465207756;4405.603495685404;4420.557944205618;4448.7330760153845;4598.31025346194;4661.137689527448;3383.1880348716622;3487.47687846825;3494.982525087409;3498.930021399558;3512.771669841452;613.4816080412045;622.9470495007885;639.5139693832721;641.7400952614199;652.7715299645087;567.8619995716132;582.9504492118133;626.7931582577738;630.3948209718629;646.4698946863526;8855.291576673852;8878.245349145265;8894.42186257891;9216.170544651013;9230.723078307661;563.3662130103634;564.5145175149369;578.6585914310493;623.9594426362272;625.2871726232099;4354.673399495051;4396.252637051709;4414.183376075436;4453.910921781546;4529.9547004530195;84085;94392.52803736075;123422;154832;162862;129317.70682293057;141126;143132.568674314;143846.2807685976;144030;110024.4498777501;110266;110658.44670776755;113286;115771;7824.41141068254;8267.29727973129;8502.574871256289;8520.360972926932;8613.784655383666;63165;63974.68012659907;64378.356216437234;64981;65341;90375;97131;99315.50342248242;99697;100106;11419.429028548706;11483.540658373571;11523.942380288045;11984.820227696417;12036.63890083299;11187.440627968732;12032.639020829389;12301.261924284572;12356.629301120622;12769.489220431078;11609.187356884937;11980.64058078259;12035.578754743541;12376.876231237573;12405.193662411913;8406.492395717594;13524.661883452993;13855.792163117763;14374.568762937128;14415.639609009439;13616.18302901829;13668.384922678304;13882;14124.858751412765;14247.501337453139;10720.445535397479;10903.65722285382;11428.82856757166;11708.47311870985;11965.581204658143;9757.707268781947;11006.889931100586;11054.723631909266;11367.829482557934;11510.53957841677 11:10:03 INFO - PROCESS | 4927 | __end_tp_report 11:10:03 INFO - PROCESS | 4927 | __start_cc_report 11:10:03 INFO - PROCESS | 4927 | _x_x_mozilla_cycle_collect,1257 11:10:03 INFO - PROCESS | 4927 | __end_cc_report 11:10:03 INFO - PROCESS | 4927 | __startTimestamp1471025403096__endTimestamp 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | ------- Summary: start ------- 11:10:03 INFO - PROCESS | 4927 | Number of tests: 6 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | [#0] dojo.html Cycles:165 Average:5740.69 Median:5991.52 stddev:645.04 (10.8%) stddev-sans-first:613.65 11:10:03 INFO - PROCESS | 4927 | Values: 3130.4 3362.3 3380.1 3387.2 3413.9 5556.3 5636.2 5661.8 5708.6 5746.9 5154.5 5630.6 5758.0 5766.9 5775.1 5126.9 5165.8 5213.2 5234.3 5309.7 5302.8 5314.1 5332.1 5350.7 5363.1 5156.6 5172.4 5205.6 5234.5 5250.2 5187.6 5203.6 5205.3 5212.0 5280.7 5180.2 5215.4 5239.2 5269.5 5269.6 5592.9 5622.7 5745.2 5748.4 5774.5 6225.9 6260.8 6265.7 6272.8 6276.6 5237.6 6248.8 6290.0 6291.5 6305.9 5674.5 5762.2 5830.2 5838.7 5856.7 6266.2 6271.0 6288.2 6296.9 6298.8 6197.5 6200.7 6210.8 6215.0 6220.4 6099.0 6154.7 6194.8 6247.4 6275.3 6194.8 6229.1 6238.4 6246.7 6261.1 6234.3 6241.8 6251.1 6274.7 6283.8 4759.1 4888.8 5019.1 5021.2 5052.6 4401.0 4436.7 4458.4 4462.5 4465.5 4689.6 5560.2 5571.2 5575.3 5585.7 6096.6 6106.9 6119.3 6137.6 6199.0 5716.5 5718.5 5731.8 5764.9 5778.8 5423.5 5428.1 5440.4 5449.8 5456.8 6192.8 6200.5 6236.1 6356.7 6367.4 6249.0 6277.6 6278.5 6299.5 6304.6 6288.2 6304.8 6308.3 6321.1 6338.2 6061.1 6107.5 6133.3 6160.3 6181.1 6218.1 6273.3 6284.2 6287.2 6288.1 6135.0 6139.4 6142.2 6156.8 6163.9 5933.4 5980.5 6025.6 6133.1 6175.7 5991.5 5994.6 5995.3 6023.4 6041.7 6098.4 6123.6 6138.9 6150.4 6167.8 5800.6 5885.1 5928.2 5982.3 5996.0 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | [#1] ext.html Cycles:180 Average:66250.97 Median:41020.83 stddev:149811.11 (365.2%) stddev-sans-first:150149.97 11:10:03 INFO - PROCESS | 4927 | Values: 476.5 520.9 524.7 530.1 575.0 31495.9 41350.6 42534.4 42700.8 44915.6 43556.3 43945.0 43948.1 43991.8 44184.0 8981.8 9583.3 9724.6 9977.0 9988.2 9777.7 9890.4 9894.2 9899.4 9915.3 9885.1 9914.0 9947.4 9947.7 9958.0 9809.9 9896.4 9899.4 9918.0 9923.1 9843.6 9860.7 9882.1 9883.8 9887.1 41143.6 41574.8 41579.2 41580.6 41678.0 2987.3 3481.8 3629.7 3671.4 3912.5 31553.8 32125.5 32981.0 33175.5 33234.5 3271.6 3272.7 3289.2 3338.6 3351.7 508340.0 512811.0 514058.0 514894.0 515003.4 33525.5 60458.0 60653.0 60728.8 60874.8 34523.3 35887.6 35901.5 36019.0 36022.6 14830.4 15031.5 15035.4 15058.0 15061.3 12706.4 12774.0 12797.4 12804.1 12812.8 7870.6 7889.1 7895.0 7969.2 7977.1 3045.5 3058.5 3225.3 3277.6 3324.6 889.3 952.9 971.7 974.9 1143.5 27159.5 30476.5 30719.5 31002.8 31181.8 938.2 1007.6 1036.3 1050.6 1098.1 12699.0 15551.3 15937.3 16002.8 16612.0 192963.0 193939.0 193992.0 710476.0 711966.0 60227.0 60776.7 60835.7 60934.7 60957.0 1077.4 1176.8 1228.2 1267.4 1396.2 30128.7 31551.7 31822.0 32307.8 32885.0 58198.4 59190.0 59250.1 59264.8 59341.1 659302.0 665558.0 665816.0 666911.7 667211.0 27525.7 28306.0 28366.0 29403.0 44309.1 48508.0 48856.0 49314.8 49788.8 50068.7 39657.0 40162.6 40921.4 40973.6 41068.1 39476.0 39521.8 40190.6 41247.6 41759.0 38959.4 39509.4 39607.6 39969.4 40070.8 32229.7 34388.0 34688.7 34957.0 35160.3 22105.6 25830.5 25990.5 26283.3 26328.9 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | [#2] jquery.html Cycles:180 Average:35052.33 Median:26273.43 stddev:99585.36 (379.0%) stddev-sans-first:99830.64 11:10:03 INFO - PROCESS | 4927 | Values: 355.1 379.1 379.5 386.7 408.6 14351.3 14506.9 19860.0 20551.0 20617.8 20699.2 21005.4 21026.3 21057.1 21103.9 4908.4 5969.4 6008.2 6040.8 6082.1 5639.6 5974.4 5975.9 5978.9 5989.3 5785.9 5856.5 5917.6 5922.9 5926.3 5946.4 5984.0 5986.3 5986.6 5991.1 4972.0 5161.8 5222.2 5229.1 5234.5 26011.2 26018.0 26104.6 26135.9 26411.0 1767.1 1831.7 2458.9 2684.4 3151.9 14950.7 16241.3 16257.8 16626.2 16662.1 1647.9 1818.0 1926.6 1968.3 1981.2 94136.0 116206.0 116299.0 121303.0 125191.1 11346.5 11482.9 11724.1 11759.9 11965.4 14970.5 14982.5 14983.3 14990.6 15040.5 8491.4 8756.0 8771.4 8901.7 8937.9 8658.7 8672.7 8699.7 8821.4 8841.9 4569.5 4659.3 4664.3 4675.4 4738.2 1721.4 1816.6 1947.4 1949.5 1949.7 731.0 849.7 901.7 1015.4 1023.9 20364.3 21412.8 21518.9 21544.2 21626.6 880.7 882.5 895.8 920.0 980.9 8722.5 10875.5 11279.0 11326.8 11338.4 540296.0 600731.0 613158.9 613397.0 614654.0 83326.6 124408.0 136454.0 136467.3 143478.0 809.9 931.2 969.8 970.6 1027.3 19438.6 20002.1 20112.2 20146.7 20211.5 42158.8 54327.2 54874.9 55679.2 55777.4 73745.9 75802.2 82789.2 83731.2 83849.0 8005.7 8235.3 8248.9 8304.1 8313.8 10798.0 12795.0 13143.5 13151.5 13208.6 10593.7 11052.3 11181.8 11237.5 11331.5 10981.7 11009.7 11201.4 11351.0 11531.3 9267.7 11384.5 11507.7 11576.7 11631.4 6472.4 7485.6 7569.5 8089.2 8391.6 11243.8 11535.0 11681.1 11707.9 11724.4 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | [#3] mootools.html Cycles:180 Average:5612.57 Median:4918.53 stddev:3154.17 (64.1%) stddev-sans-first:3147.85 11:10:03 INFO - PROCESS | 4927 | Values: 1496.3 1532.1 1715.3 1863.4 1892.8 7868.8 8093.0 8261.5 8821.9 8841.7 8240.2 8476.6 8486.4 8579.2 8598.1 2347.5 2381.3 2384.2 2384.9 2386.0 2094.2 2173.6 2337.4 2364.2 2398.8 2154.1 2177.8 2180.3 2302.2 2372.4 2324.3 2332.5 2334.0 2337.9 2340.5 2319.2 2319.2 2356.5 2356.6 2357.5 8718.4 8782.7 8790.9 8797.5 8797.7 4722.1 4923.4 5030.4 5276.6 5282.9 3302.3 3432.5 3582.3 3589.0 3598.5 996.0 1021.7 1049.0 1050.0 1057.6 11438.5 11442.0 11446.9 11470.1 11478.9 4572.7 4635.3 4747.7 4810.4 4850.2 9050.5 9113.9 9124.4 9127.9 9149.3 5229.6 5230.7 5231.7 5239.3 5239.9 3937.8 4700.1 4841.9 4913.9 4923.2 3233.9 3463.5 3607.9 3613.1 3664.7 1113.6 1119.0 1122.0 1130.6 1132.7 773.0 900.9 926.7 949.4 956.0 5612.4 5671.4 5864.2 6113.0 6132.9 452.0 459.4 460.5 1017.0 1043.0 3866.2 3976.6 4006.3 4047.6 4151.0 10291.8 10588.8 10627.0 10643.2 10647.3 10597.0 10605.4 10606.4 10624.1 10628.0 7935.8 8060.0 8104.9 8217.0 8240.0 5809.8 5840.4 5902.7 5944.5 5969.1 9539.2 9881.3 9891.4 10000.3 10037.2 9528.3 10063.8 10131.7 10251.2 10257.5 7248.5 7501.5 7597.6 7608.4 7617.2 8117.3 8198.8 8208.6 8216.2 8220.4 7018.6 7163.2 7188.2 7215.2 7509.5 7202.8 7262.7 7271.6 7455.4 7474.0 6817.4 6986.1 6991.6 6995.2 7013.4 4943.2 4992.0 5018.0 5031.5 5137.3 4303.5 4926.4 5000.3 5201.5 5230.6 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | [#4] prototype.html Cycles:180 Average:4377.68 Median:4655.62 stddev:1373.87 (29.5%) stddev-sans-first:1342.54 11:10:03 INFO - PROCESS | 4927 | Values: 261.4 282.3 290.4 297.7 312.0 4266.0 4682.7 4724.6 5097.4 5493.1 5057.7 5400.7 5408.4 5410.3 5421.5 3512.7 4028.5 4055.3 4088.5 4093.3 3518.5 3896.7 4110.4 4111.3 4129.3 3499.0 3613.7 3837.8 4067.5 4083.9 3504.6 3922.3 4053.8 4071.7 4072.3 3540.7 3543.9 3552.2 3556.3 3570.7 4486.8 4909.8 5243.2 5243.6 5294.4 4635.2 4652.9 4658.3 4674.6 4674.6 5062.0 5336.4 5617.4 5661.8 5679.9 3717.2 3835.4 3853.9 3880.5 3883.4 4905.5 5911.4 5968.1 5997.0 6001.6 3524.3 3798.6 3970.6 3972.1 3976.7 3412.4 3819.5 3974.9 3976.3 3989.6 3292.5 4430.8 4486.9 4566.2 4706.5 5611.1 5625.9 5635.7 5653.2 5682.6 1326.8 1345.5 1350.8 1352.8 1364.6 726.7 802.4 821.2 826.0 827.7 3767.8 3990.2 4103.5 4388.9 4411.5 4469.4 4637.5 4868.3 4892.3 4898.9 3506.9 4375.4 4411.0 4413.5 4414.7 2116.9 2304.0 2499.4 2561.5 2589.9 4120.5 4290.7 4323.2 4452.0 4580.2 4232.2 4774.7 4778.0 4812.6 4917.8 4511.5 5070.9 5713.2 6148.8 6174.3 5565.6 5581.9 5923.8 5924.9 5943.8 5060.8 5454.9 5627.4 5785.3 5849.5 4485.4 5268.5 5853.6 6098.5 6113.6 3928.9 3967.9 3975.8 4000.7 4007.6 5082.1 5275.4 5795.5 5896.7 5988.7 4454.3 5058.9 5471.0 5593.7 5610.7 4858.3 5176.2 5188.5 5687.5 5720.7 4397.9 4727.8 5351.7 5577.2 5594.0 4586.9 5696.7 5720.5 5733.4 5743.3 5569.6 5583.7 5589.7 5598.8 5635.0 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - PROCESS | 4927 | [#5] yui.html Cycles:180 Average:24761.44 Median:3829.62 stddev:39749.62 (1038.0%) stddev-sans-first:39820.14 11:10:03 INFO - PROCESS | 4927 | Values: 721.3 723.6 771.0 814.8 825.0 15033.5 16423.7 16447.8 16449.5 16478.0 14834.9 14930.9 14946.1 14946.9 14950.5 673.4 697.4 830.3 840.2 858.4 838.0 855.5 856.8 857.2 857.5 913.4 915.3 915.7 916.4 916.4 720.1 744.1 748.6 909.8 911.0 553.2 554.0 651.2 672.4 674.6 14579.6 14740.6 14767.3 14789.0 14838.3 11252.6 11859.2 13685.7 13763.0 13811.3 12575.6 12747.5 12777.4 12861.9 13368.6 4146.5 4541.4 4706.2 4753.0 4788.5 93853.5 118608.0 118934.4 122301.4 123669.0 10708.5 11035.7 11194.7 11447.9 11475.8 20891.4 21236.0 21369.4 21389.6 21408.7 7859.2 8014.1 8027.7 8191.0 8307.0 4405.6 4420.6 4448.7 4598.3 4661.1 3383.2 3487.5 3495.0 3498.9 3512.8 613.5 622.9 639.5 641.7 652.8 567.9 583.0 626.8 630.4 646.5 8855.3 8878.2 8894.4 9216.2 9230.7 563.4 564.5 578.7 624.0 625.3 4354.7 4396.3 4414.2 4453.9 4530.0 84085.0 94392.5 123422.0 154832.0 162862.0 129317.7 141126.0 143132.6 143846.3 144030.0 110024.4 110266.0 110658.4 113286.0 115771.0 7824.4 8267.3 8502.6 8520.4 8613.8 63165.0 63974.7 64378.4 64981.0 65341.0 90375.0 97131.0 99315.5 99697.0 100106.0 11419.4 11483.5 11523.9 11984.8 12036.6 11187.4 12032.6 12301.3 12356.6 12769.5 11609.2 11980.6 12035.6 12376.9 12405.2 8406.5 13524.7 13855.8 14374.6 14415.6 13616.2 13668.4 13882.0 14124.9 14247.5 10720.4 10903.7 11428.8 11708.5 11965.6 9757.7 11006.9 11054.7 11367.8 11510.5 11:10:03 INFO - PROCESS | 4927 | -------- Summary: end -------- 11:10:03 INFO - PROCESS | 4927 | 11:10:03 INFO - TEST-INFO | 4927: exit 0 11:10:03 INFO - TEST-OK | dromaeo_css | took 1117841ms 11:10:03 INFO - TEST-START | kraken 11:10:03 INFO - operating with platform_type : mac_ 11:10:03 INFO - Initialising browser for kraken test... 11:10:03 INFO - TEST-INFO | started process 4933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpSkLtLJ/profile http://localhost:49353/getInfo.html) 11:10:06 INFO - PROCESS | 4933 | __metrics Screen width/height:1600/1200 11:10:06 INFO - PROCESS | 4933 | colorDepth:24 11:10:06 INFO - PROCESS | 4933 | Browser inner width/height: 1024/689 11:10:06 INFO - PROCESS | 4933 | __metrics 11:10:06 INFO - PROCESS | 4933 | 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" 11:10:06 INFO - PROCESS | 4933 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:10:06 INFO - TEST-INFO | 4933: exit 0 11:10:06 INFO - Browser initialized. 11:10:06 INFO - Running cycle 1/1 for kraken test... 11:10:06 INFO - TEST-INFO | started process 4934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpSkLtLJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:18 INFO - PROCESS | 4934 | RSS: Main: 243785728 11:10:18 INFO - PROCESS | 4934 | 11:10:22 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-beat-detection) 11:10:22 INFO - PROCESS | 4934 | RSS: Main: 293523456 11:10:22 INFO - PROCESS | 4934 | 11:10:25 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-dft) 11:10:26 INFO - PROCESS | 4934 | RSS: Main: 304836608 11:10:26 INFO - PROCESS | 4934 | 11:10:29 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-fft) 11:10:30 INFO - PROCESS | 4934 | RSS: Main: 395411456 11:10:30 INFO - PROCESS | 4934 | 11:10:32 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-oscillator) 11:10:33 INFO - PROCESS | 4934 | RSS: Main: 297021440 11:10:33 INFO - PROCESS | 4934 | 11:10:35 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49353/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:10:35 INFO - PROCESS | 4934 | RSS: Main: 300617728 11:10:35 INFO - PROCESS | 4934 | 11:10:38 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49353/tests/kraken/driver.html?testName=imaging-darkroom) 11:10:39 INFO - PROCESS | 4934 | RSS: Main: 338313216 11:10:39 INFO - PROCESS | 4934 | 11:10:42 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49353/tests/kraken/driver.html?testName=imaging-desaturate) 11:10:42 INFO - PROCESS | 4934 | RSS: Main: 339111936 11:10:42 INFO - PROCESS | 4934 | 11:10:45 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49353/tests/kraken/driver.html?testName=json-parse-financial) 11:10:46 INFO - PROCESS | 4934 | RSS: Main: 338354176 11:10:46 INFO - PROCESS | 4934 | 11:10:47 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49353/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:10:48 INFO - PROCESS | 4934 | RSS: Main: 313491456 11:10:48 INFO - PROCESS | 4934 | 11:10:50 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:10:50 INFO - PROCESS | 4934 | RSS: Main: 318672896 11:10:50 INFO - PROCESS | 4934 | 11:10:52 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:10:53 INFO - PROCESS | 4934 | RSS: Main: 322211840 11:10:53 INFO - PROCESS | 4934 | 11:10:56 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:10:57 INFO - PROCESS | 4934 | RSS: Main: 330731520 11:10:57 INFO - PROCESS | 4934 | 11:11:00 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:11:00 INFO - PROCESS | 4934 | RSS: Main: 333389824 11:11:00 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49353/tests/kraken/driver.html?testName=ai-astar) 11:11:02 INFO - PROCESS | 4934 | RSS: Main: 376324096 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | __start_tp_report 11:11:02 INFO - PROCESS | 4934 | _x_x_mozilla_page_load 11:11:02 INFO - PROCESS | 4934 | _x_x_mozilla_page_load_details 11:11:02 INFO - PROCESS | 4934 | |i|pagename|runs| 11:11:02 INFO - PROCESS | 4934 | |0;ai-astar;93;101;153;168;159;113;113;113;112;112 11:11:02 INFO - PROCESS | 4934 | |1;audio-beat-detection;142;109;105;160;110;132;159;136;142;107 11:11:02 INFO - PROCESS | 4934 | |2;audio-dft;181;183;186;184;182;184;182;181;181;181 11:11:02 INFO - PROCESS | 4934 | |3;audio-fft;80;61;77;76;60;74;87;96;92;58 11:11:02 INFO - PROCESS | 4934 | |4;audio-oscillator;88;69;103;84;109;98;108;55;96;136 11:11:02 INFO - PROCESS | 4934 | |5;imaging-gaussian-blur;89;87;89;87;87;86;90;86;89;85 11:11:02 INFO - PROCESS | 4934 | |6;imaging-darkroom;93;93;94;94;94;94;94;94;102;94 11:11:02 INFO - PROCESS | 4934 | |7;imaging-desaturate;87;84;84;84;83;81;85;84;84;83 11:11:02 INFO - PROCESS | 4934 | |8;json-parse-financial;52;53;53;53;53;54;53;53;53;53 11:11:02 INFO - PROCESS | 4934 | |9;json-stringify-tinderbox;66;74;66;77;78;74;71;71;72;73 11:11:02 INFO - PROCESS | 4934 | |10;stanford-crypto-aes;86;83;82;81;86;83;83;88;84;96 11:11:02 INFO - PROCESS | 4934 | |11;stanford-crypto-ccm;139;152;152;148;131;140;142;146;212;135 11:11:02 INFO - PROCESS | 4934 | |12;stanford-crypto-pbkdf2;162;166;165;165;165;164;166;167;166;164 11:11:02 INFO - PROCESS | 4934 | |13;stanford-crypto-sha256-iterative;62;85;87;77;65;66;67;68;67;66 11:11:02 INFO - PROCESS | 4934 | __end_tp_report 11:11:02 INFO - PROCESS | 4934 | __start_cc_report 11:11:02 INFO - PROCESS | 4934 | _x_x_mozilla_cycle_collect,2097 11:11:02 INFO - PROCESS | 4934 | __end_cc_report 11:11:02 INFO - PROCESS | 4934 | __startTimestamp1471025462872__endTimestamp 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | ------- Summary: start ------- 11:11:02 INFO - PROCESS | 4934 | Number of tests: 14 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#0] ai-astar Cycles:10 Average:123.70 Median:133.00 stddev:26.12 (19.6%) stddev-sans-first:25.24 11:11:02 INFO - PROCESS | 4934 | Values: 93.0 101.0 153.0 168.0 159.0 113.0 113.0 113.0 112.0 112.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:139.00 stddev:21.25 (15.3%) stddev-sans-first:22.10 11:11:02 INFO - PROCESS | 4934 | Values: 142.0 109.0 105.0 160.0 110.0 132.0 159.0 136.0 142.0 107.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.50 stddev:1.72 (0.9%) stddev-sans-first:1.73 11:11:02 INFO - PROCESS | 4934 | Values: 181.0 183.0 186.0 184.0 182.0 184.0 182.0 181.0 181.0 181.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#3] audio-fft Cycles:10 Average:76.10 Median:78.50 stddev:13.35 (17.0%) stddev-sans-first:14.08 11:11:02 INFO - PROCESS | 4934 | Values: 80.0 61.0 77.0 76.0 60.0 74.0 87.0 96.0 92.0 58.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:76.50 stddev:22.52 (29.4%) stddev-sans-first:23.76 11:11:02 INFO - PROCESS | 4934 | Values: 88.0 69.0 103.0 84.0 109.0 98.0 108.0 55.0 96.0 136.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:1.65 (1.9%) stddev-sans-first:1.66 11:11:02 INFO - PROCESS | 4934 | Values: 89.0 87.0 89.0 87.0 87.0 86.0 90.0 86.0 89.0 85.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.00 stddev:2.63 (2.8%) stddev-sans-first:2.73 11:11:02 INFO - PROCESS | 4934 | Values: 93.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 102.0 94.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:1.52 (1.8%) stddev-sans-first:1.13 11:11:02 INFO - PROCESS | 4934 | Values: 87.0 84.0 84.0 84.0 83.0 81.0 85.0 84.0 84.0 83.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.47 (0.9%) stddev-sans-first:0.33 11:11:02 INFO - PROCESS | 4934 | Values: 52.0 53.0 53.0 53.0 53.0 54.0 53.0 53.0 53.0 53.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.50 stddev:3.99 (5.4%) stddev-sans-first:3.55 11:11:02 INFO - PROCESS | 4934 | Values: 66.0 74.0 66.0 77.0 78.0 74.0 71.0 71.0 72.0 73.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:4.34 (5.1%) stddev-sans-first:4.59 11:11:02 INFO - PROCESS | 4934 | Values: 86.0 83.0 82.0 81.0 86.0 83.0 83.0 88.0 84.0 96.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:147.00 stddev:22.95 (15.6%) stddev-sans-first:24.02 11:11:02 INFO - PROCESS | 4934 | Values: 139.0 152.0 152.0 148.0 131.0 140.0 142.0 146.0 212.0 135.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.50 stddev:1.41 (0.9%) stddev-sans-first:1.00 11:11:02 INFO - PROCESS | 4934 | Values: 162.0 166.0 165.0 165.0 165.0 164.0 166.0 167.0 166.0 164.0 11:11:02 INFO - PROCESS | 4934 | 11:11:02 INFO - PROCESS | 4934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:67.50 stddev:8.79 (13.0%) stddev-sans-first:8.70 11:11:02 INFO - PROCESS | 4934 | Values: 62.0 85.0 87.0 77.0 65.0 66.0 67.0 68.0 67.0 66.0 11:11:02 INFO - PROCESS | 4934 | -------- Summary: end -------- 11:11:02 INFO - PROCESS | 4934 | 11:11:03 INFO - TEST-INFO | 4934: exit 0 11:11:03 INFO - TEST-OK | kraken | took 59957ms 11:11:03 INFO - SUITE-END | took 1177s 11:11:04 INFO - Completed test suite (00:19:38) 11:11:04 INFO - javascript benchmark 11:11:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3130.436521426143, 3362.260302733398, 3380.1380647934775, 3387.1701433148883, 3413.8805141820026, 5556.305461817273, 5636.15457681348, 5661.801836935708, 5708.628939118959, 5746.913796293059, 5154.458781827901, 5630.605857589971, 5758.049921762915, 5766.855828604287, 5775.075987841936, 5126.897462050757, 5165.793368265284, 5213.191955246943, 5234.345706786652, 5309.681419114865, 5302.787888484456, 5314.123169676998, 5332.093544097494, 5350.652207606513, 5363.061464243761, 5156.587473002151, 5172.353455818023, 5205.609579281569, 5234.450382709799, 5250.159974404096, 5187.636865419422, 5203.557697595709, 5205.349331333583, 5212.0, 5280.683159010472, 5180.197069454203, 5215.3741551013745, 5239.187925871458, 5269.525742683139, 5269.552088072518, 5592.86017849553, 5622.718864056781, 5745.224394706703, 5748.396418376052, 5774.5091667208335, 6225.937740622581, 6260.812175634738, 6265.718042688044, 6272.780452684134, 6276.560640755149, 5237.5548078413, 6248.75004999804, 6290.0, 6291.496680265614, 6305.873882522324, 5674.460926212003, 5762.164486149485, 5830.2420685310635, 5838.67887266196, 5856.736446859902, 6266.216722909637, 6270.968645156836, 6288.151099601587, 6296.937030629727, 6298.779542715983, 6197.4732147767945, 6200.720967556471, 6210.844728881814, 6214.9689251553455, 6220.377962203744, 6098.969505152446, 6154.7230374633245, 6194.845128871724, 6247.437730604267, 6275.309715931244, 6194.783182588678, 6229.127922090911, 6238.407351301619, 6246.656433896179, 6261.060840873905, 6234.2830574483605, 6241.843953901189, 6251.0623406488485, 6274.717637706314, 6283.842903927439, 4759.0719809637485, 4888.780004899788, 5019.071471777733, 5021.221710634784, 5052.57053150479, 4401.031773009932, 4436.711613745161, 4458.4426946631675, 4462.486814016364, 4465.4864690561, 4689.6248300136585, 5560.165975103686, 5571.164325351148, 5575.303087114111, 5585.69278689676, 6096.556923351615, 6106.885399019288, 6119.258838874149, 6137.591499645514, 6199.0, 5716.542676585863, 5718.513926316226, 5731.828045158652, 5764.942350576524, 5778.768849245983, 5423.457654234624, 5428.131498960226, 5440.3743569489225, 5449.809256675997, 5456.7544460499375, 6192.792405481068, 6200.534959878028, 6236.126942228182, 6356.745730170742, 6367.395097465734, 6248.968755156195, 6277.5605707600025, 6278.46633036197, 6299.527535434952, 6304.621722696653, 6288.151099601495, 6304.842378940563, 6308.306086330592, 6321.115043893767, 6338.176037115146, 6061.060535616986, 6107.46191531438, 6133.325334213144, 6160.291566469913, 6181.103739957771, 6218.129461875251, 6273.341299163704, 6284.214473190851, 6287.245530536365, 6288.056791481132, 6135.049067394561, 6139.386061393825, 6142.232220972379, 6156.753729850756, 6163.907541386793, 5933.3769954154195, 5980.5215582754145, 6025.638461692312, 6133.080037994337, 6175.722092505758, 5991.520678345635, 5994.6403215807195, 5995.340512543704, 6023.36754640774, 6041.667708276087, 6098.420650038239, 6123.632582045091, 6138.907916381348, 6150.446459818638, 6167.784127555694, 5800.622959507618, 5885.058390657474, 5928.2293301872205, 5982.282126144891, 5996.040633498619], "value": 5698.960303508686, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.45683920330816, 520.9036328279269, 524.7271418862192, 530.077664863138, 575.0, 31495.89764358245, 41350.58649413505, 42534.36198457022, 42700.78649606748, 44915.55084449155, 43556.3466548002, 43945.0, 43948.12103757923, 43991.78004109983, 44184.0, 8981.832361792958, 9583.281253905985, 9724.611015559369, 9976.950115249414, 9988.1510071644, 9777.706668799912, 9890.357126786736, 9894.158996485306, 9899.356541824794, 9915.305928585001, 9885.110340069392, 9913.950430247838, 9947.353422027581, 9947.701568952943, 9957.950210248939, 9809.852852207223, 9896.356736812084, 9899.406035637812, 9917.950410247939, 9923.1069203772, 9843.557039933148, 9860.65487707934, 9882.061204185591, 9883.752906177331, 9887.060729230712, 41143.588564114274, 41574.75275741702, 41579.1684166318, 41580.58419415797, 41678.0, 2987.2979849735293, 3481.8085005324697, 3629.6551827576336, 3671.375866102769, 3912.491376121086, 31553.842230788698, 32125.51811722826, 32981.01056968295, 33175.502367464505, 33234.50148247778, 3271.558339624144, 3272.7381809455187, 3289.19414743387, 3338.6327503974544, 3351.748618853596, 508340.0, 512811.0, 514058.0, 514894.0, 515003.4249828727, 33525.49711754326, 60458.0, 60653.0, 60728.785424291265, 60874.78250435055, 34523.30953380918, 35887.64112358843, 35901.46147807785, 36019.0, 36022.63977360245, 14830.40678372853, 15031.549053528412, 15035.398584056735, 15058.0, 15061.322240499203, 12706.428210730539, 12774.041946854024, 12797.424115914806, 12804.103712740205, 12812.80780788289, 7870.645820938072, 7889.09275433321, 7894.960525197337, 7969.203079691984, 7977.082635496876, 3045.5433083450002, 3058.464768665475, 3225.3226822367565, 3277.6066871975518, 3324.5844269466315, 889.2796834564024, 952.8856537215579, 971.6987733802544, 974.8878878928872, 1143.502576379261, 27159.506227157697, 30476.54285185724, 30719.520452463494, 31002.844985774926, 31181.844090779854, 938.2165891480554, 1007.5969612155196, 1036.3315661398356, 1050.5902697947806, 1098.1379617000616, 12699.0, 15551.30019149141, 15937.282822273026, 16002.75995860063, 16612.0, 192963.0, 193939.03030484758, 193992.0, 710476.0, 711966.0, 60227.0, 60776.69611652002, 60835.69582152149, 60934.695326523084, 60957.0, 1077.42357838556, 1176.8352430659716, 1228.2446295528364, 1267.416988185445, 1396.2390497179063, 30128.698713013466, 31551.684483154873, 31822.045338639877, 32307.838460807547, 32885.0, 58198.41801582099, 59190.0, 59250.11124833217, 59264.81470370655, 59341.109883350924, 659302.0, 665558.0, 665816.0, 666911.6654416696, 667211.0, 27525.72474275312, 28306.0, 28366.00718974826, 29403.0, 44309.11381772411, 48508.0, 48856.0, 49314.75342623408, 49788.75105624449, 50068.749656251486, 39657.00857478586, 40162.598374015884, 40921.38617920793, 40973.59026409698, 41068.06022147567, 39476.0, 39521.80239098786, 40190.598094018686, 41247.58752412438, 41759.0, 38959.41560876646, 39509.40735888907, 39607.60392396039, 39969.40045899255, 40070.79787606376, 32229.677703223606, 34388.0, 34688.65311346854, 34957.0, 35160.29679406449, 22105.557888842453, 25830.45017298968, 25990.480190396465, 26283.342916426478, 26328.868355658866], "value": 18974.04363827355, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.0520111302821, 379.0731661088638, 379.4933763425826, 386.74281602734175, 408.634272326268, 14351.282435878204, 14506.854931450682, 19860.006999649995, 20551.0, 20617.793822061776, 20699.17203311866, 21005.36983890486, 21026.317894568398, 21057.05243264053, 21103.894480527615, 4908.361912951312, 5969.432903874125, 6008.2092363823685, 6040.848978775522, 6082.148499210114, 5639.577031722617, 5974.402559744012, 5975.910361344583, 5978.8804223915495, 5989.3112292086535, 5785.855353616173, 5856.474569523258, 5917.615355001933, 5922.88154236915, 5926.259217597801, 5946.375630558794, 5984.0, 5986.2517185351835, 5986.640801551899, 5991.101334799815, 4971.975140124312, 5161.845144645667, 5222.190560463135, 5229.08491014071, 5234.528892399702, 26011.219663410127, 26018.0, 26104.60843087355, 26135.869320653466, 26411.0, 1767.0722870492966, 1831.6611426886068, 2458.8770561471856, 2684.3825920038503, 3151.9054428367185, 14950.70098598022, 16241.2691428886, 16257.83742162575, 16626.168691565374, 16662.083585402797, 1647.9093649849253, 1817.972730409045, 1926.6146770645787, 1968.271739456396, 1981.1778112083468, 94136.0, 116206.0, 116298.99999999916, 121303.0, 125191.12213316807, 11346.48940797666, 11482.942585287186, 11724.120690948213, 11759.941200294115, 11965.40172991347, 14970.476033338999, 14982.475613353481, 14983.250837458301, 14990.550283491513, 15040.473583424746, 8491.405601607952, 8756.0, 8771.386002979858, 8901.73294801157, 8937.865932011026, 8658.653653853775, 8672.739817805475, 8699.739007829776, 8821.426607270636, 8841.867371989425, 4569.474510431343, 4659.301104834302, 4664.346991421204, 4675.438947326343, 4738.223166312633, 1721.3544920654754, 1816.5731053202487, 1947.4449781812398, 1949.541857663448, 1949.7465329507356, 730.9524880882726, 849.7493239494364, 901.693424235763, 1015.3958394755109, 1023.9283250172565, 20364.28724994618, 21412.785872141078, 21518.892405537874, 21544.245951391626, 21626.567468650854, 880.6873559886276, 882.5366682491743, 895.8118795052982, 919.9862002069974, 980.9411435313904, 8722.520261385685, 10875.456227188768, 11279.041281491165, 11326.830097548544, 11338.43307834605, 540296.0, 600731.0, 613158.9342053261, 613397.0, 614654.0, 83326.583367084, 124408.0, 136454.0, 136467.31766341304, 143478.0, 809.9473534220257, 931.2270815223349, 969.7817990951979, 970.631160159135, 1027.3219675014604, 19438.611227775647, 20002.081691522802, 20112.195512179347, 20146.731925158696, 20211.494712632302, 42158.78920605377, 54327.185092222855, 54874.902501950535, 55679.16481252865, 55777.44222557722, 73745.89381159394, 75802.24197758173, 82789.17210827814, 83731.16268837477, 83849.0, 8005.719799806979, 8235.29999950011, 8248.917510824815, 8304.086550479564, 8313.750587482386, 10798.0, 12795.040371972325, 13143.539976101172, 13151.473941042252, 13208.603741887759, 10593.735156621146, 11052.281601695864, 11181.832272516082, 11237.494312755782, 11331.490082946451, 10981.725456863642, 11009.724756881142, 11201.383923884265, 11351.0, 11531.308121512737, 9267.67563135287, 11384.544618215179, 11507.712307192387, 11576.652700419001, 11631.360275184947, 6472.449841763503, 7485.5508669480005, 7569.47013709035, 8089.2315230053055, 8391.622376992927, 11243.831342529706, 11535.0, 11681.06551475897, 11707.88292117068, 11724.355160465915], "value": 9685.525435329546, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.319174775477, 1532.1420004797315, 1715.2538645689126, 1863.3943968210335, 1892.7823300320467, 7868.803279917991, 8093.0, 8261.463004904677, 8821.867671984925, 8841.734747957555, 8240.175982401772, 8476.576171191431, 8486.405951583376, 8579.184977427134, 8598.097199794025, 2347.5187586544716, 2381.333226696519, 2384.2132096408145, 2384.9165279215144, 2386.0455817672896, 2094.2251366994233, 2173.56528694261, 2337.4039619896903, 2364.2316247219724, 2398.832081754278, 2154.0845140815168, 2177.803997640212, 2180.3349978256624, 2302.182725132579, 2372.41875740443, 2324.32594547581, 2332.521833024226, 2334.0, 2337.8714170720605, 2340.4967931894553, 2319.176692274252, 2319.2346525646494, 2356.5404746074523, 2356.587597170488, 2357.5049239659693, 8718.389712720113, 8782.736517904474, 8790.912090879137, 8797.47215167092, 8797.736067917973, 4722.126406614787, 4923.433805112422, 5030.421501527335, 5276.604254680876, 5282.920756188661, 3302.2734998300334, 3432.5022871683714, 3582.265635544707, 3589.0309616403424, 3598.514200582914, 995.9701208963743, 1021.6730646193221, 1049.0296475759892, 1049.9895001049968, 1057.6404022632264, 11438.542458301657, 11442.0, 11446.942765286203, 11470.139739519485, 11478.885211147865, 4572.725636461823, 4635.327877457749, 4747.715137091716, 4810.4467986181335, 4850.248211527219, 9050.547472626342, 9113.863292050624, 9124.361294709439, 9127.863082053775, 9149.268058555299, 5229.633925625246, 5230.660007099526, 5231.712255825966, 5239.292695486137, 5239.921401178985, 3937.8424863005157, 4700.130475861976, 4841.927371089437, 4913.852584422473, 4923.212286034289, 3233.8868139615, 3463.5324231228965, 3607.9434722208616, 3613.0786649404236, 3664.670179683841, 1113.6158025481195, 1118.9888101119047, 1122.0350498571222, 1130.5647325779635, 1132.7168207948014, 772.9806754831062, 900.9459432434074, 926.745145085107, 949.4255975135083, 955.9617615295449, 5612.382637909826, 5671.432856714296, 5864.179014937912, 6113.0, 6132.8773424532155, 451.95706407891197, 459.4326007380882, 460.53946053946055, 1016.9694909152737, 1042.9895701042892, 3866.188100498927, 3976.603517457527, 4006.258842114218, 4047.575004624472, 4151.045259590251, 10291.794164116676, 10588.841167382496, 10627.04356607909, 10643.201759868041, 10647.25469217163, 10597.0, 10605.363678179334, 10606.363618182933, 10624.096951759188, 10628.0, 7935.801604959922, 8059.959700201579, 8104.918950810416, 8216.958915205385, 8239.95880020596, 5809.82570522885, 5840.386759390203, 5902.704864756831, 5944.524438045032, 5969.104634304716, 9539.236861051239, 9881.258905581966, 9891.35706179096, 10000.2999790014, 10037.247206459399, 9528.333016688764, 10063.849042264226, 10131.733533308336, 10251.231157663056, 10257.538410771682, 7248.528845625017, 7501.549907005598, 7597.582132982739, 7608.429367797548, 7617.200193979553, 8117.269445749911, 8198.836023279382, 8208.630611622611, 8216.21945915137, 8220.3834712398, 7018.613976231356, 7163.176234732864, 7188.245234250538, 7215.206327304098, 7509.549427034395, 7202.755315962989, 7262.6731797070315, 7271.563706177646, 7455.403567714461, 7474.0, 6817.386435220656, 6986.126734158231, 6991.615461149686, 6995.230524642184, 7013.403860671696, 4943.171026720137, 4991.9500804991485, 5018.046571151615, 5031.471695472069, 5137.306463627362, 4303.462067241595, 4926.433460152129, 5000.349744686327, 5201.531862132275, 5230.581553475637], "value": 4473.931444412654, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.3622760464467, 282.32524267001855, 290.40032333231875, 297.6799940064431, 312.02025639492, 4265.976165720228, 4682.672212945095, 4724.598409135219, 5097.439281679017, 5493.121100623901, 5057.671251368659, 5400.676834175632, 5408.4050754417085, 5410.295756836594, 5421.457854214586, 3512.666296701809, 4028.529586700862, 4055.3308704063793, 4088.5093788745394, 4093.263212621742, 3518.47222916562, 3896.707746918978, 4110.362893751473, 4111.259973204822, 4129.339305711087, 3498.982505087471, 3613.7289703272227, 3837.827297771607, 4067.491563554556, 4083.9387409188885, 3504.6331930547235, 3922.3135951208424, 4053.7567745935044, 4071.6946229032646, 4072.2873497137884, 3540.6636369544854, 3543.8936831895085, 3552.236269202134, 3556.253186830743, 3570.732195085379, 4486.842960496399, 4909.754512274372, 5243.187305967543, 5243.606729495303, 5294.444083371268, 4635.172544747701, 4652.906941861143, 4658.324542941255, 4674.555917187861, 4674.626029917633, 5062.0, 5336.359636843604, 5617.4382561743905, 5661.801836935729, 5679.914801277984, 3717.219383929378, 3835.4055121456217, 3853.903652408684, 3880.47613572167, 3883.359245724452, 4905.5094490550655, 5911.379305172938, 5968.14987353926, 5996.97001514994, 6001.589626437783, 3524.3479956208184, 3798.5631652360335, 3970.6029397060065, 3972.1261322509004, 3976.681865450757, 3412.351653185887, 3819.4652748615217, 3974.900627484278, 3976.3439032559304, 3989.5863002883416, 3292.522584225274, 4430.778461076998, 4486.865394038184, 4566.246569316026, 4706.458757242893, 5611.102223644198, 5625.859353516113, 5635.731275859991, 5653.180288858092, 5682.602217844794, 1326.8474125475693, 1345.5425155447194, 1350.8382790799903, 1352.8106065150887, 1364.5565191312862, 726.6947881889619, 802.4342838298985, 821.1665159862744, 825.9710910118238, 827.7061643116607, 3767.830447629863, 3990.244292511286, 4103.4710149271605, 4388.912221755547, 4411.470623525198, 4469.374287599739, 4637.536246375403, 4868.318435419044, 4892.339534162914, 4898.877528061756, 3506.878877798761, 4375.431193944831, 4411.029573493826, 4413.492448368414, 4414.690971631955, 2116.8518203725594, 2303.953920921606, 2499.3631718537117, 2561.462092960462, 2589.948201035969, 4120.546739858613, 4290.742555446684, 4323.162655871242, 4452.041101915147, 4580.1755683976535, 4232.15961972476, 4774.734695305747, 4777.976110119427, 4812.5668689818085, 4917.754112294371, 4511.458624964959, 5070.923936140961, 5713.228714123707, 6148.815535533941, 6174.320824709278, 5565.5825813063375, 5581.944180558305, 5923.763049477972, 5924.940750592611, 5943.791967281298, 5060.811688636136, 5454.890902182014, 5627.409122042291, 5785.27684039495, 5849.473547380756, 4485.37204791323, 5268.525832675077, 5853.590248682552, 6098.493366314298, 6113.602615829956, 3928.9214215716097, 3967.8809635710977, 3975.801209939549, 4000.6799456044027, 4007.6393124618926, 5082.059818933509, 5275.419703832498, 5795.536357091507, 5896.6756828374855, 5988.700564971647, 4454.26504626733, 5058.9494105058475, 5470.987867244572, 5593.720313984203, 5610.7194640268635, 4858.3441235432765, 5176.2494438306985, 5188.507091826271, 5687.459691329317, 5720.713964301852, 4397.868063958086, 4727.763611819327, 5351.705656188947, 5577.235366127767, 5594.0, 4586.931196031996, 5696.658200507983, 5720.456556627114, 5733.426657334233, 5743.310802703702, 5569.637973531708, 5583.664980101207, 5589.748461319169, 5598.832035038955, 5635.0], "value": 3939.2691820235273, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.2607077745314, 723.598402886398, 771.0015529888793, 814.8451794159113, 824.9628766705498, 15033.548993530185, 16423.671526569462, 16447.753283700753, 16449.506514804576, 16478.0, 14834.851651483483, 14930.925345373258, 14946.10323380598, 14946.925265373686, 14950.476733314335, 673.3939454490949, 697.4106879686682, 830.2942498875938, 840.1556435782046, 858.4463021351256, 838.0404436919723, 855.5038077914826, 856.7900864288227, 857.237059017475, 857.4512312120216, 913.4154141349542, 915.3363811236867, 915.702396721065, 916.3814425262955, 916.4134953629682, 720.1142594608608, 744.1033554566734, 748.6331697468256, 909.7816524034217, 911.0297533127232, 553.2282465960003, 554.0276814191069, 651.1892693596456, 672.4250765595394, 674.5952428542835, 14579.562613121623, 14740.557783266519, 14767.335469903881, 14789.0, 14838.258087095603, 11252.606158784403, 11859.229150105215, 13685.657858553517, 13763.036587438883, 13811.309434528233, 12575.622731318075, 12747.490100396066, 12777.361131943366, 12861.935690321581, 13368.598942031755, 4146.460960075201, 4541.364209010741, 4706.22347312693, 4753.04939012199, 4788.497207793202, 93853.5307323459, 118608.0, 118934.40532797367, 122301.38849305785, 123669.0, 10708.518116684769, 11035.724106897393, 11194.66416007521, 11447.885521144848, 11475.827862582068, 20891.37325880226, 21236.0, 21369.358919232447, 21389.572208555743, 21408.678869816966, 7859.214078592095, 8014.062204340157, 8027.719029834045, 8190.959045204736, 8306.958465207756, 4405.603495685404, 4420.557944205618, 4448.7330760153845, 4598.31025346194, 4661.137689527448, 3383.1880348716622, 3487.47687846825, 3494.982525087409, 3498.930021399558, 3512.771669841452, 613.4816080412045, 622.9470495007885, 639.5139693832721, 641.7400952614199, 652.7715299645087, 567.8619995716132, 582.9504492118133, 626.7931582577738, 630.3948209718629, 646.4698946863526, 8855.291576673852, 8878.245349145265, 8894.42186257891, 9216.170544651013, 9230.723078307661, 563.3662130103634, 564.5145175149369, 578.6585914310493, 623.9594426362272, 625.2871726232099, 4354.673399495051, 4396.252637051709, 4414.183376075436, 4453.910921781546, 4529.9547004530195, 84085.0, 94392.52803736075, 123422.0, 154832.0, 162862.0, 129317.70682293057, 141126.0, 143132.568674314, 143846.2807685976, 144030.0, 110024.4498777501, 110266.0, 110658.44670776755, 113286.0, 115771.0, 7824.41141068254, 8267.29727973129, 8502.574871256289, 8520.360972926932, 8613.784655383666, 63165.0, 63974.68012659907, 64378.356216437234, 64981.0, 65341.0, 90375.0, 97131.0, 99315.50342248242, 99697.0, 100106.0, 11419.429028548706, 11483.540658373571, 11523.942380288045, 11984.820227696417, 12036.63890083299, 11187.440627968732, 12032.639020829389, 12301.261924284572, 12356.629301120622, 12769.489220431078, 11609.187356884937, 11980.64058078259, 12035.578754743541, 12376.876231237573, 12405.193662411913, 8406.492395717594, 13524.661883452993, 13855.792163117763, 14374.568762937128, 14415.639609009439, 13616.18302901829, 13668.384922678304, 13882.0, 14124.858751412765, 14247.501337453139, 10720.445535397479, 10903.65722285382, 11428.82856757166, 11708.47311870985, 11965.581204658143, 9757.707268781947, 11006.889931100586, 11054.723631909266, 11367.829482557934, 11510.53957841677], "value": 7692.889667844296, "unit": "score"}], "name": "dromaeo_css", "value": 7223.04004960133, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 153.0, 168.0, 159.0, 113.0, 113.0, 113.0, 112.0, 112.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 105.0, 160.0, 110.0, 132.0, 159.0, 136.0, 142.0, 107.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 186.0, 184.0, 182.0, 184.0, 182.0, 181.0, 181.0, 181.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 77.0, 76.0, 60.0, 74.0, 87.0, 96.0, 92.0, 58.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 103.0, 84.0, 109.0, 98.0, 108.0, 55.0, 96.0, 136.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 87.0, 86.0, 90.0, 86.0, 89.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 102.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 84.0, 83.0, 81.0, 85.0, 84.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 53.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 66.0, 77.0, 78.0, 74.0, 71.0, 71.0, 72.0, 73.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 82.0, 81.0, 86.0, 83.0, 83.0, 88.0, 84.0, 96.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 152.0, 148.0, 131.0, 140.0, 142.0, 146.0, 212.0, 135.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 165.0, 165.0, 165.0, 164.0, 166.0, 167.0, 166.0, 164.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 85.0, 87.0, 77.0, 65.0, 66.0, 67.0, 68.0, 67.0, 66.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1469.2, "alertThreshold": 2.0}]} 11:11:04 INFO - Return code: 0 11:11:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:11:04 INFO - # TBPL SUCCESS # 11:11:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:11:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:11:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:11:04 INFO - 2016-08-12 11:11:04.219 screenresolution[4938:24833] starting screenresolution argv=screenresolution get 11:11:04 INFO - 2016-08-12 11:11:04.222 screenresolution[4938:24833] Display 0: 1600x1200x32@60 11:11:04 INFO - 2016-08-12 11:11:04.228 screenresolution[4939:24836] starting screenresolution argv=screenresolution list 11:11:04 INFO - Available Modes on Display 0 11:11:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:11:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:11:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:11:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:11:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:11:04 INFO - Intel Iris: 11:11:04 INFO - Chipset Model: Intel Iris 11:11:04 INFO - Type: GPU 11:11:04 INFO - Bus: Built-In 11:11:04 INFO - VRAM (Dynamic, Max): 1536 MB 11:11:04 INFO - Vendor: Intel (0x8086) 11:11:04 INFO - Device ID: 0x0a2e 11:11:04 INFO - Revision ID: 0x0009 11:11:04 INFO - Displays: 11:11:04 INFO - Display: 11:11:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:11:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:11:04 INFO - Display Serial Number: 1600x1200 60 11:11:04 INFO - Main Display: Yes 11:11:04 INFO - Mirror: Off 11:11:04 INFO - Online: Yes 11:11:04 INFO - Rotation: Supported 11:11:04 INFO - Return code: 0 11:11:04 INFO - Running post-action listener: _resource_record_post_action 11:11:04 INFO - [mozharness: 2016-08-12 18:11:04.462366Z] Finished run-tests step (success) 11:11:04 INFO - Running post-run listener: _resource_record_post_run 11:11:04 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31534080; Write bytes: 703794688; Read time: 315; Write time: 1920 11:11:04 INFO - TinderboxPrint: CPU usage
53.4% 11:11:04 INFO - TinderboxPrint: I/O read bytes / time
31,534,080 / 315 11:11:04 INFO - TinderboxPrint: I/O write bytes / time
703,794,688 / 1,920 11:11:04 INFO - TinderboxPrint: CPU idle
2,261.1 (46.6%) 11:11:04 INFO - TinderboxPrint: CPU system
967.2 (19.9%) 11:11:04 INFO - TinderboxPrint: CPU user
1,625.4 (33.5%) 11:11:04 INFO - TinderboxPrint: Swap in / out
430,739,456 / 0 11:11:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397294080; Write bytes: 391313408; Read time: 29596; Write time: 771 11:11:04 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30059520; Write bytes: 296412672; Read time: 302; Write time: 1111 11:11:04 INFO - Running post-run listener: _upload_blobber_files 11:11:04 INFO - Blob upload gear active. 11:11:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:11:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:11:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:11:05 INFO - (blobuploader) - INFO - Open directory for files ... 11:11:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:11:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:11:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:06 INFO - (blobuploader) - INFO - Done attempting. 11:11:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:11:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:11:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:07 INFO - (blobuploader) - INFO - Done attempting. 11:11:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:11:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:11:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:09 INFO - (blobuploader) - INFO - Done attempting. 11:11:09 INFO - (blobuploader) - INFO - Iteration through files over. 11:11:09 INFO - Return code: 0 11:11:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:11:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:11:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5d79b50c3b46d104118071830e2203d3b8bbdd573c919119732bf2aa6c73f7a8ebba41dce0fea644597f565c229b92bdf644ccc3d69d4475386a3a7e47964959", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9571ee156d2b3e0b8d7bf5434a56f986c1f31d32d2d92b92d1e0cc28e943671d9b77bd0bffb147a6af3c27f8fc6b3ef330693a8c08c02f3f19ef2f64a8ad29d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1668ebef39efabfb9af55484f43a3273d62bc578b750da83937ff3eda0764c72001a3126cccc39bd0a1d3e80ea2773c3e21f7cb4106822098d3bce4570de7203"} 11:11:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:11:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:11:09 INFO - Contents: 11:11:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5d79b50c3b46d104118071830e2203d3b8bbdd573c919119732bf2aa6c73f7a8ebba41dce0fea644597f565c229b92bdf644ccc3d69d4475386a3a7e47964959", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9571ee156d2b3e0b8d7bf5434a56f986c1f31d32d2d92b92d1e0cc28e943671d9b77bd0bffb147a6af3c27f8fc6b3ef330693a8c08c02f3f19ef2f64a8ad29d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1668ebef39efabfb9af55484f43a3273d62bc578b750da83937ff3eda0764c72001a3126cccc39bd0a1d3e80ea2773c3e21f7cb4106822098d3bce4570de7203"} 11:11:09 INFO - Running post-run listener: copy_logs_to_upload_dir 11:11:09 INFO - Copying logs to upload dir... 11:11:09 INFO - mkdir: /builds/slave/test/build/upload/logs 11:11:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.594420 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-12 11:11:09.742733) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 11:11:09.745954) ========= 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.zeBXTpXvFk/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.ymur0pUn6T/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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/Autoland/sha512/5d79b50c3b46d104118071830e2203d3b8bbdd573c919119732bf2aa6c73f7a8ebba41dce0fea644597f565c229b92bdf644ccc3d69d4475386a3a7e47964959", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9571ee156d2b3e0b8d7bf5434a56f986c1f31d32d2d92b92d1e0cc28e943671d9b77bd0bffb147a6af3c27f8fc6b3ef330693a8c08c02f3f19ef2f64a8ad29d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1668ebef39efabfb9af55484f43a3273d62bc578b750da83937ff3eda0764c72001a3126cccc39bd0a1d3e80ea2773c3e21f7cb4106822098d3bce4570de7203"} build_url:https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011557 build_url: 'https://queue.taskcluster.net/v1/task/GnlgIsbZTb2De_3d1NteJA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5d79b50c3b46d104118071830e2203d3b8bbdd573c919119732bf2aa6c73f7a8ebba41dce0fea644597f565c229b92bdf644ccc3d69d4475386a3a7e47964959", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e9571ee156d2b3e0b8d7bf5434a56f986c1f31d32d2d92b92d1e0cc28e943671d9b77bd0bffb147a6af3c27f8fc6b3ef330693a8c08c02f3f19ef2f64a8ad29d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1668ebef39efabfb9af55484f43a3273d62bc578b750da83937ff3eda0764c72001a3126cccc39bd0a1d3e80ea2773c3e21f7cb4106822098d3bce4570de7203"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 11:11:09.778489) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:11:09.778806) ========= 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.zeBXTpXvFk/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.ymur0pUn6T/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.004973 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 11:11:09.827194) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 11:11:09.827516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 11:11:09.827829) ========= ========= Total master_lag: 0.17 =========