builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0205 starttime: 1472134902.15 results: success (0) buildid: 20160825051426 builduid: fbe03b41c7ce425b8d29acd4a7b241a0 revision: 9467a563a01b466fdca07c0495de7bcfd0a66215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.152243) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.152726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.153049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.179525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.180136) ========= 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.wuX8Btumz3/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.c7T2Stm5uI/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-25 07:21:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 36.6M=0s 2016-08-25 07:21:42 (36.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169018 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.377054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.377448) ========= 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.wuX8Btumz3/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.c7T2Stm5uI/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.026591 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:42.429673) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-25 07:21:42.430083) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9467a563a01b466fdca07c0495de7bcfd0a66215 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 9467a563a01b466fdca07c0495de7bcfd0a66215 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wuX8Btumz3/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.c7T2Stm5uI/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-25 07:21:42,517 truncating revision to first 12 chars 2016-08-25 07:21:42,517 Setting DEBUG logging. 2016-08-25 07:21:42,517 attempt 1/10 2016-08-25 07:21:42,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9467a563a01b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 07:21:43,749 unpacking tar archive at: fx-team-9467a563a01b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.493385 ========= master_lag: 0.59 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-25 07:21:44.517082) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:44.517446) ========= script_repo_revision: 9467a563a01b466fdca07c0495de7bcfd0a66215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:44.517898) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:44.518199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 07:21:44.538172) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-25 07:21:44.538863) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wuX8Btumz3/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.c7T2Stm5uI/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:21:44 INFO - MultiFileLogger online at 20160825 07:21:44 in /builds/slave/test 07:21:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:21:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:21:44 INFO - {'append_to_log': False, 07:21:44 INFO - 'base_work_dir': '/builds/slave/test', 07:21:44 INFO - 'blob_upload_branch': 'Fx-Team', 07:21:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:21:44 INFO - 'branch': 'Fx-Team', 07:21:44 INFO - 'buildbot_json_path': 'buildprops.json', 07:21:44 INFO - 'config_files': ('talos/mac_config.py',), 07:21:44 INFO - 'default_actions': ('clobber', 07:21:44 INFO - 'read-buildbot-config', 07:21:44 INFO - 'download-and-extract', 07:21:44 INFO - 'populate-webroot', 07:21:44 INFO - 'create-virtualenv', 07:21:44 INFO - 'install', 07:21:44 INFO - 'run-tests'), 07:21:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:21:44 INFO - 'download_minidump_stackwalk': True, 07:21:44 INFO - 'download_symbols': 'ondemand', 07:21:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:21:44 INFO - 'tooltool.py': '/tools/tooltool.py', 07:21:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:21:44 INFO - '/tools/misc-python/virtualenv.py')}, 07:21:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:21:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:21:44 INFO - 'installer_path': 'installer.exe', 07:21:44 INFO - 'log_level': 'info', 07:21:44 INFO - 'log_name': 'talos', 07:21:44 INFO - 'log_to_console': True, 07:21:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:21:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:21:44 INFO - 'opt_config_files': (), 07:21:44 INFO - 'pip_index': False, 07:21:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:21:44 INFO - 'cmd': ('bash', 07:21:44 INFO - '-c', 07:21:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:21:44 INFO - 'enabled': True, 07:21:44 INFO - 'halt_on_failure': False, 07:21:44 INFO - 'name': 'check_screen_resolution'},), 07:21:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:21:44 INFO - 'cmd': ('bash', 07:21:44 INFO - '-c', 07:21:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:21:44 INFO - 'enabled': True, 07:21:44 INFO - 'halt_on_failure': False, 07:21:44 INFO - 'name': 'check_screen_resolution'},), 07:21:44 INFO - 'run_cmd_checks_enabled': True, 07:21:44 INFO - 'sps_profile': False, 07:21:44 INFO - 'sps_profile_interval': 0, 07:21:44 INFO - 'suite': 'dromaeojs', 07:21:44 INFO - 'system_bits': '32', 07:21:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:21:44 INFO - 'title': 't-yosemite-r7-0205', 07:21:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:21:44 INFO - 'use_talos_json': True, 07:21:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:21:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:21:44 INFO - 'work_dir': 'build'} 07:21:44 INFO - [mozharness: 2016-08-25 14:21:44.738928Z] Running clobber step. 07:21:44 INFO - Running pre-action listener: _resource_record_pre_action 07:21:44 INFO - Running main action method: clobber 07:21:44 INFO - rmtree: /builds/slave/test/build 07:21:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:21:46 INFO - Running post-action listener: _resource_record_post_action 07:21:46 INFO - [mozharness: 2016-08-25 14:21:46.828901Z] Finished clobber step (success) 07:21:46 INFO - [mozharness: 2016-08-25 14:21:46.829040Z] Running read-buildbot-config step. 07:21:46 INFO - Running pre-action listener: _resource_record_pre_action 07:21:46 INFO - Running main action method: read_buildbot_config 07:21:46 INFO - Using buildbot properties: 07:21:46 INFO - { 07:21:46 INFO - "project": "", 07:21:46 INFO - "product": "firefox", 07:21:46 INFO - "script_repo_revision": "production", 07:21:46 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:21:46 INFO - "repository": "", 07:21:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:21:46 INFO - "buildid": "20160825051426", 07:21:46 INFO - "pgo_build": "False", 07:21:46 INFO - "basedir": "/builds/slave/test", 07:21:46 INFO - "buildnumber": 98, 07:21:46 INFO - "slavename": "t-yosemite-r7-0205", 07:21:46 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:21:46 INFO - "platform": "yosemite_r7", 07:21:46 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:21:46 INFO - "branch": "fx-team", 07:21:46 INFO - "repo_path": "integration/fx-team", 07:21:46 INFO - "revision": "9467a563a01b466fdca07c0495de7bcfd0a66215", 07:21:46 INFO - "stage_platform": "macosx64", 07:21:46 INFO - "builduid": "fbe03b41c7ce425b8d29acd4a7b241a0", 07:21:46 INFO - "slavebuilddir": "test" 07:21:46 INFO - } 07:21:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:21:46 INFO - Running post-action listener: _resource_record_post_action 07:21:46 INFO - [mozharness: 2016-08-25 14:21:46.832315Z] Finished read-buildbot-config step (success) 07:21:46 INFO - [mozharness: 2016-08-25 14:21:46.832421Z] Running download-and-extract step. 07:21:46 INFO - Running pre-action listener: _resource_record_pre_action 07:21:46 INFO - Running main action method: download_and_extract 07:21:46 INFO - mkdir: /builds/slave/test/build/tests 07:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:46 INFO - trying https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:21:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:21:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 07:21:47 INFO - Downloaded 1199 bytes. 07:21:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:21:47 INFO - Using the following test package requirements: 07:21:47 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:21:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:21:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:21:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'jsshell-mac.zip'], 07:21:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:21:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:21:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:21:47 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:21:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:21:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:21:47 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:21:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:47 INFO - trying https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:21:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:21:48 INFO - Downloaded 16345895 bytes. 07:21:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:21:55 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:55 INFO - trying https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:21:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:21:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:21:56 INFO - Downloaded 16345895 bytes. 07:21:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:02 INFO - trying https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:22:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:22:03 INFO - Downloaded 14413981 bytes. 07:22:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 07:22:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:04 INFO - trying https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:22:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 07:22:06 INFO - Downloaded 111544895 bytes. 07:22:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:22:06 INFO - mkdir: /builds/slave/test/properties 07:22:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:22:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:22:06 INFO - Contents: 07:22:06 INFO - build_url:https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:22:07 INFO - Running post-action listener: _resource_record_post_action 07:22:07 INFO - Running post-action listener: set_extra_try_arguments 07:22:07 INFO - [mozharness: 2016-08-25 14:22:07.652586Z] Finished download-and-extract step (success) 07:22:07 INFO - [mozharness: 2016-08-25 14:22:07.652711Z] Running populate-webroot step. 07:22:07 INFO - Running pre-action listener: _resource_record_pre_action 07:22:07 INFO - Running main action method: populate_webroot 07:22:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:22:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:22:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:22:07 INFO - '--tppagecycles', 07:22:07 INFO - '7'], 07:22:07 INFO - 'tests': ['tsvgm']}}, 07:22:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:22:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:22:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:22:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:22:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:22:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:22:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['damp', 'tps']}, 07:22:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'tests': ['damp', 'tps']}, 07:22:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['dromaeo_dom']}, 07:22:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:22:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['basic_compositor_video']}, 07:22:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:22:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['a11yr', 07:22:07 INFO - 'ts_paint', 07:22:07 INFO - 'tpaint', 07:22:07 INFO - 'sessionrestore', 07:22:07 INFO - 'sessionrestore_no_auto_restore', 07:22:07 INFO - 'tabpaint']}, 07:22:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:22:07 INFO - 'ts_paint', 07:22:07 INFO - 'tpaint', 07:22:07 INFO - 'sessionrestore', 07:22:07 INFO - 'sessionrestore_no_auto_restore', 07:22:07 INFO - 'tabpaint']}, 07:22:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:22:07 INFO - 'ts_paint', 07:22:07 INFO - 'tpaint', 07:22:07 INFO - 'sessionrestore', 07:22:07 INFO - 'sessionrestore_no_auto_restore', 07:22:07 INFO - 'tabpaint']}, 07:22:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:22:07 INFO - 'ts_paint', 07:22:07 INFO - 'tpaint', 07:22:07 INFO - 'sessionrestore', 07:22:07 INFO - 'sessionrestore_no_auto_restore', 07:22:07 INFO - 'tabpaint']}, 07:22:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['a11yr', 07:22:07 INFO - 'ts_paint', 07:22:07 INFO - 'tpaint', 07:22:07 INFO - 'sessionrestore', 07:22:07 INFO - 'sessionrestore_no_auto_restore', 07:22:07 INFO - 'tabpaint']}, 07:22:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['a11yr', 07:22:07 INFO - 'ts_paint', 07:22:07 INFO - 'tpaint', 07:22:07 INFO - 'sessionrestore', 07:22:07 INFO - 'sessionrestore_no_auto_restore', 07:22:07 INFO - 'tabpaint']}, 07:22:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['tsvgx', 07:22:07 INFO - 'tsvgr_opacity', 07:22:07 INFO - 'tart', 07:22:07 INFO - 'tscrollx', 07:22:07 INFO - 'cart']}, 07:22:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:22:07 INFO - 'tsvgr_opacity', 07:22:07 INFO - 'tart', 07:22:07 INFO - 'tscrollx', 07:22:07 INFO - 'cart']}, 07:22:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'talos_options': ['--disable-e10s'], 07:22:07 INFO - 'tests': ['tp5o']}, 07:22:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'tests': ['tp5o']}, 07:22:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'talos_options': ['--disable-e10s', 07:22:07 INFO - '--xperf_path', 07:22:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:22:07 INFO - 'tests': ['tp5n']}, 07:22:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:07 INFO - 'talos_options': ['--xperf_path', 07:22:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:22:07 INFO - 'tests': ['tp5n']}}, 07:22:07 INFO - 'talos.zip': {'path': '', 07:22:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:22:07 INFO - Running post-action listener: _resource_record_post_action 07:22:07 INFO - [mozharness: 2016-08-25 14:22:07.671587Z] Finished populate-webroot step (success) 07:22:07 INFO - [mozharness: 2016-08-25 14:22:07.671700Z] Running create-virtualenv step. 07:22:07 INFO - Running pre-action listener: _resource_record_pre_action 07:22:07 INFO - Running main action method: create_virtualenv 07:22:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:22:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:22:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:22:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:22:07 INFO - Using real prefix '/tools/python27' 07:22:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:22:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:22:11 INFO - Installing pip.................done. 07:22:11 INFO - Return code: 0 07:22:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:11 INFO - 'HOME': '/Users/cltbld', 07:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:11 INFO - 'LOGNAME': 'cltbld', 07:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:11 INFO - 'MOZ_NO_REMOTE': '1', 07:22:11 INFO - 'NO_EM_RESTART': '1', 07:22:11 INFO - 'PAGER': '/bin/cat', 07:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:11 INFO - 'PWD': '/builds/slave/test', 07:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:11 INFO - 'SHELL': '/bin/bash', 07:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:11 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:11 INFO - 'USER': 'cltbld', 07:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:11 INFO - 'XPC_FLAGS': '0x0', 07:22:11 INFO - 'XPC_SERVICE_NAME': '0', 07:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:22:11 INFO - Cleaning up... 07:22:11 INFO - Return code: 0 07:22:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:11 INFO - 'HOME': '/Users/cltbld', 07:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:11 INFO - 'LOGNAME': 'cltbld', 07:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:11 INFO - 'MOZ_NO_REMOTE': '1', 07:22:11 INFO - 'NO_EM_RESTART': '1', 07:22:11 INFO - 'PAGER': '/bin/cat', 07:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:11 INFO - 'PWD': '/builds/slave/test', 07:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:11 INFO - 'SHELL': '/bin/bash', 07:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:11 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:11 INFO - 'USER': 'cltbld', 07:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:11 INFO - 'XPC_FLAGS': '0x0', 07:22:11 INFO - 'XPC_SERVICE_NAME': '0', 07:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:11 INFO - Downloading/unpacking psutil>=3.1.1 07:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:22:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:22:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:22:15 INFO - Installing collected packages: psutil 07:22:15 INFO - Running setup.py install for psutil 07:22:15 INFO - building 'psutil._psutil_osx' extension 07:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:22:15 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:22:15 INFO - building 'psutil._psutil_posix' extension 07:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:22:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:22:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:22:16 INFO - ^ 07:22:16 INFO - 1 warning generated. 07:22:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:22:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:22:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:22:16 INFO - Successfully installed psutil 07:22:16 INFO - Cleaning up... 07:22:16 INFO - Return code: 0 07:22:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:16 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 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:16 INFO - 'HOME': '/Users/cltbld', 07:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:16 INFO - 'LOGNAME': 'cltbld', 07:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:16 INFO - 'MOZ_NO_REMOTE': '1', 07:22:16 INFO - 'NO_EM_RESTART': '1', 07:22:16 INFO - 'PAGER': '/bin/cat', 07:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:16 INFO - 'PWD': '/builds/slave/test', 07:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:16 INFO - 'SHELL': '/bin/bash', 07:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:16 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:16 INFO - 'USER': 'cltbld', 07:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:16 INFO - 'XPC_FLAGS': '0x0', 07:22:16 INFO - 'XPC_SERVICE_NAME': '0', 07:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:22:19 INFO - Installing collected packages: mozsystemmonitor 07:22:19 INFO - Running setup.py install for mozsystemmonitor 07:22:19 INFO - Successfully installed mozsystemmonitor 07:22:19 INFO - Cleaning up... 07:22:19 INFO - Return code: 0 07:22:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:19 INFO - 'HOME': '/Users/cltbld', 07:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:19 INFO - 'LOGNAME': 'cltbld', 07:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:19 INFO - 'MOZ_NO_REMOTE': '1', 07:22:19 INFO - 'NO_EM_RESTART': '1', 07:22:19 INFO - 'PAGER': '/bin/cat', 07:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:19 INFO - 'PWD': '/builds/slave/test', 07:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:19 INFO - 'SHELL': '/bin/bash', 07:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:19 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:19 INFO - 'USER': 'cltbld', 07:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:19 INFO - 'XPC_FLAGS': '0x0', 07:22:19 INFO - 'XPC_SERVICE_NAME': '0', 07:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:19 INFO - Downloading/unpacking jsonschema==2.5.1 07:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:22:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:22 INFO - Downloading functools32-3.2.3-2.tar.gz 07:22:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:22:22 INFO - warning: no files found matching '*.txt' 07:22:22 INFO - no previously-included directories found matching 'build' 07:22:22 INFO - no previously-included directories found matching 'dist' 07:22:22 INFO - no previously-included directories found matching '.git*' 07:22:22 INFO - Installing collected packages: jsonschema, functools32 07:22:22 INFO - Running setup.py install for functools32 07:22:22 INFO - warning: no files found matching '*.txt' 07:22:22 INFO - no previously-included directories found matching 'build' 07:22:22 INFO - no previously-included directories found matching 'dist' 07:22:22 INFO - no previously-included directories found matching '.git*' 07:22:22 INFO - Successfully installed jsonschema functools32 07:22:22 INFO - Cleaning up... 07:22:22 INFO - Return code: 0 07:22:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:22 INFO - 'HOME': '/Users/cltbld', 07:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:22 INFO - 'LOGNAME': 'cltbld', 07:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:22 INFO - 'MOZ_NO_REMOTE': '1', 07:22:22 INFO - 'NO_EM_RESTART': '1', 07:22:22 INFO - 'PAGER': '/bin/cat', 07:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:22 INFO - 'PWD': '/builds/slave/test', 07:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:22 INFO - 'SHELL': '/bin/bash', 07:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:22 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:22 INFO - 'USER': 'cltbld', 07:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:22 INFO - 'XPC_FLAGS': '0x0', 07:22:22 INFO - 'XPC_SERVICE_NAME': '0', 07:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:22:23 INFO - Cleaning up... 07:22:23 INFO - Return code: 0 07:22:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:22: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')]} 07:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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')]} 07:22:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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', '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 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:22: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 blobuploader==1.2.4 07:22:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:23 INFO - 'HOME': '/Users/cltbld', 07:22:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:23 INFO - 'LOGNAME': 'cltbld', 07:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:23 INFO - 'MOZ_NO_REMOTE': '1', 07:22:23 INFO - 'NO_EM_RESTART': '1', 07:22:23 INFO - 'PAGER': '/bin/cat', 07:22:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:23 INFO - 'PWD': '/builds/slave/test', 07:22:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:23 INFO - 'SHELL': '/bin/bash', 07:22:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:23 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:23 INFO - 'USER': 'cltbld', 07:22:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:23 INFO - 'XPC_FLAGS': '0x0', 07:22:23 INFO - 'XPC_SERVICE_NAME': '0', 07:22:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:23 INFO - Downloading/unpacking blobuploader==1.2.4 07:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:25 INFO - Downloading blobuploader-1.2.4.tar.gz 07:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:22:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:22:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:26 INFO - Downloading docopt-0.6.1.tar.gz 07:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:22:27 INFO - Installing collected packages: blobuploader, requests, docopt 07:22:27 INFO - Running setup.py install for blobuploader 07:22:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:22:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:22:27 INFO - Running setup.py install for requests 07:22:27 INFO - Running setup.py install for docopt 07:22:27 INFO - Successfully installed blobuploader requests docopt 07:22:27 INFO - Cleaning up... 07:22:27 INFO - Return code: 0 07:22:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:27 INFO - 'HOME': '/Users/cltbld', 07:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:27 INFO - 'LOGNAME': 'cltbld', 07:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:27 INFO - 'MOZ_NO_REMOTE': '1', 07:22:27 INFO - 'NO_EM_RESTART': '1', 07:22:27 INFO - 'PAGER': '/bin/cat', 07:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:27 INFO - 'PWD': '/builds/slave/test', 07:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:27 INFO - 'SHELL': '/bin/bash', 07:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:27 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:27 INFO - 'USER': 'cltbld', 07:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:27 INFO - 'XPC_FLAGS': '0x0', 07:22:27 INFO - 'XPC_SERVICE_NAME': '0', 07:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-fbaIHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-qe2Gxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ZdEb8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-jMc96p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-4JH9VF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-EQ8V_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-fNhQrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-9cH7x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-n2BkWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-vc3TQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Pj1BlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-YHWa2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ZWeKMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-3kjkDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-D1eOaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:29 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Q3R3ZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:30 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-aa6TDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:22:30 INFO - Running setup.py install for manifestparser 07:22:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:22:30 INFO - Running setup.py install for mozcrash 07:22:30 INFO - Running setup.py install for mozdebug 07:22:30 INFO - Running setup.py install for mozdevice 07:22:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:22:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:22:30 INFO - Running setup.py install for mozfile 07:22:30 INFO - Running setup.py install for mozhttpd 07:22:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Running setup.py install for mozinfo 07:22:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Running setup.py install for mozInstall 07:22:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Running setup.py install for mozleak 07:22:31 INFO - Running setup.py install for mozlog 07:22:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Running setup.py install for moznetwork 07:22:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:22:31 INFO - Running setup.py install for mozprocess 07:22:31 INFO - Running setup.py install for mozprofile 07:22:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:22:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:22:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:22:32 INFO - Running setup.py install for mozrunner 07:22:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:22:32 INFO - Running setup.py install for mozscreenshot 07:22:32 INFO - Running setup.py install for moztest 07:22:32 INFO - Running setup.py install for mozversion 07:22:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:22:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:22:32 INFO - Cleaning up... 07:22:32 INFO - Return code: 0 07:22:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22: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')]} 07:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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')]} 07:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:32 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 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:22:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:32 INFO - 'HOME': '/Users/cltbld', 07:22:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:32 INFO - 'LOGNAME': 'cltbld', 07:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:32 INFO - 'MOZ_NO_REMOTE': '1', 07:22:32 INFO - 'NO_EM_RESTART': '1', 07:22:32 INFO - 'PAGER': '/bin/cat', 07:22:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:32 INFO - 'PWD': '/builds/slave/test', 07:22:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:32 INFO - 'SHELL': '/bin/bash', 07:22:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:32 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:32 INFO - 'USER': 'cltbld', 07:22:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:32 INFO - 'XPC_FLAGS': '0x0', 07:22:32 INFO - 'XPC_SERVICE_NAME': '0', 07:22:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-0NxXkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-32Ij0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-eTXqAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-zJb043-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-8rCDAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-4qywB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-6q382c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-FYjPG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-YW7qcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-jqSF5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-SJ6sKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-WdwfAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-1kR1u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-rC9i6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-HNWMqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-tNDVPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:34 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-notYss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:22: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 07:22: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 07:22: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 07:22: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 07:22: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 07:22: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 07:22:37 INFO - Downloading blessings-1.6.tar.gz 07:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:22:37 INFO - Installing collected packages: blessings 07:22:37 INFO - Running setup.py install for blessings 07:22:37 INFO - Successfully installed blessings 07:22:37 INFO - Cleaning up... 07:22:37 INFO - Return code: 0 07:22:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:37 INFO - Reading from file tmpfile_stdout 07:22:37 INFO - Current package versions: 07:22:37 INFO - blessings == 1.6 07:22:37 INFO - blobuploader == 1.2.4 07:22:37 INFO - docopt == 0.6.1 07:22:37 INFO - functools32 == 3.2.3-2 07:22:37 INFO - jsonschema == 2.5.1 07:22:37 INFO - manifestparser == 1.1 07:22:37 INFO - mozInstall == 1.12 07:22:37 INFO - mozcrash == 0.17 07:22:37 INFO - mozdebug == 0.1 07:22:37 INFO - mozdevice == 0.48 07:22:37 INFO - mozfile == 1.2 07:22:37 INFO - mozhttpd == 0.7 07:22:37 INFO - mozinfo == 0.9 07:22:37 INFO - mozleak == 0.1 07:22:37 INFO - mozlog == 3.3 07:22:37 INFO - moznetwork == 0.27 07:22:37 INFO - mozprocess == 0.23 07:22:37 INFO - mozprofile == 0.28 07:22:37 INFO - mozrunner == 6.12 07:22:37 INFO - mozscreenshot == 0.1 07:22:37 INFO - mozsystemmonitor == 0.3 07:22:37 INFO - moztest == 0.7 07:22:37 INFO - mozversion == 1.4 07:22:37 INFO - psutil == 3.1.1 07:22:37 INFO - requests == 1.2.3 07:22:37 INFO - wsgiref == 0.1.2 07:22:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:37 INFO - 'HOME': '/Users/cltbld', 07:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:37 INFO - 'LOGNAME': 'cltbld', 07:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:37 INFO - 'MOZ_NO_REMOTE': '1', 07:22:37 INFO - 'NO_EM_RESTART': '1', 07:22:37 INFO - 'PAGER': '/bin/cat', 07:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:37 INFO - 'PWD': '/builds/slave/test', 07:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:37 INFO - 'SHELL': '/bin/bash', 07:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:37 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:37 INFO - 'USER': 'cltbld', 07:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:37 INFO - 'XPC_FLAGS': '0x0', 07:22:37 INFO - 'XPC_SERVICE_NAME': '0', 07:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:22:38 INFO - Cleaning up... 07:22:38 INFO - Return code: 0 07:22:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:38 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 0x11001ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100b1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc939d9ca50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, '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.wuX8Btumz3/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.c7T2Stm5uI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:38 INFO - 'HOME': '/Users/cltbld', 07:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:38 INFO - 'LOGNAME': 'cltbld', 07:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:38 INFO - 'MOZ_NO_REMOTE': '1', 07:22:38 INFO - 'NO_EM_RESTART': '1', 07:22:38 INFO - 'PAGER': '/bin/cat', 07:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:38 INFO - 'PWD': '/builds/slave/test', 07:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:38 INFO - 'SHELL': '/bin/bash', 07:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:22:38 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:38 INFO - 'USER': 'cltbld', 07:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:38 INFO - 'XPC_FLAGS': '0x0', 07:22:38 INFO - 'XPC_SERVICE_NAME': '0', 07:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:22:38 INFO - Cleaning up... 07:22:38 INFO - Return code: 0 07:22:38 INFO - Running post-action listener: _resource_record_post_action 07:22:38 INFO - Running post-action listener: _start_resource_monitoring 07:22:38 INFO - Starting resource monitoring. 07:22:38 INFO - [mozharness: 2016-08-25 14:22:38.432014Z] Finished create-virtualenv step (success) 07:22:38 INFO - [mozharness: 2016-08-25 14:22:38.433002Z] Running install step. 07:22:38 INFO - Running pre-action listener: _resource_record_pre_action 07:22:38 INFO - Running main action method: install 07:22:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:38 INFO - Reading from file tmpfile_stdout 07:22:38 INFO - Detecting whether we're running mozinstall >=1.0... 07:22:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:22:38 INFO - Reading from file tmpfile_stdout 07:22:38 INFO - Output received: 07:22:38 INFO - Usage: mozinstall [options] installer 07:22:38 INFO - Options: 07:22:38 INFO - -h, --help show this help message and exit 07:22:38 INFO - -d DEST, --destination=DEST 07:22:38 INFO - Directory to install application into. [default: 07:22:38 INFO - "/builds/slave/test"] 07:22:38 INFO - --app=APP Application being installed. [default: firefox] 07:22:38 INFO - mkdir: /builds/slave/test/build/application 07:22:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:23:12 INFO - Reading from file tmpfile_stdout 07:23:12 INFO - Output received: 07:23:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:23:12 INFO - Running post-action listener: _resource_record_post_action 07:23:12 INFO - [mozharness: 2016-08-25 14:23:12.405445Z] Finished install step (success) 07:23:12 INFO - [mozharness: 2016-08-25 14:23:12.405592Z] Running run-tests step. 07:23:12 INFO - Running pre-action listener: _resource_record_pre_action 07:23:12 INFO - Running main action method: run_tests 07:23:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:23:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:23:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:23:12 INFO - 2016-08-25 07:23:12.419 screenresolution[1634:7540] starting screenresolution argv=screenresolution get 07:23:12 INFO - 2016-08-25 07:23:12.422 screenresolution[1634:7540] Display 0: 1600x1200x32@60 07:23:12 INFO - 2016-08-25 07:23:12.428 screenresolution[1635:7542] starting screenresolution argv=screenresolution list 07:23:12 INFO - Available Modes on Display 0 07:23:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:23:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:23:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:23:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:23:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:23:12 INFO - Intel Iris: 07:23:12 INFO - Chipset Model: Intel Iris 07:23:12 INFO - Type: GPU 07:23:12 INFO - Bus: Built-In 07:23:12 INFO - VRAM (Dynamic, Max): 1536 MB 07:23:12 INFO - Vendor: Intel (0x8086) 07:23:12 INFO - Device ID: 0x0a2e 07:23:12 INFO - Revision ID: 0x0009 07:23:12 INFO - Displays: 07:23:12 INFO - Display: 07:23:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:23:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:23:12 INFO - Display Serial Number: 1600x1200 60 07:23:12 INFO - Main Display: Yes 07:23:12 INFO - Mirror: Off 07:23:12 INFO - Online: Yes 07:23:12 INFO - Rotation: Supported 07:23:12 INFO - Return code: 0 07:23:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:23:12 INFO - Python 2.7.3 07:23:12 INFO - Return code: 0 07:23:12 INFO - grabbing minidump binary from tooltool 07:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff80d30>, '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 0x10fce5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff78b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:23:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:23:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:23:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:23:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:23:12 INFO - Return code: 0 07:23:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:23:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:23:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:23:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:23:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:23:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wuX8Btumz3/Render', 07:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:23:12 INFO - 'HOME': '/Users/cltbld', 07:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:23:12 INFO - 'LOGNAME': 'cltbld', 07:23:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:23:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:12 INFO - 'MOZ_NO_REMOTE': '1', 07:23:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:23:12 INFO - 'NO_EM_RESTART': '1', 07:23:12 INFO - 'PAGER': '/bin/cat', 07:23:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:12 INFO - 'PWD': '/builds/slave/test', 07:23:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:23:12 INFO - 'SHELL': '/bin/bash', 07:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c7T2Stm5uI/Listeners', 07:23:12 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 07:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:23:12 INFO - 'USER': 'cltbld', 07:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:12 INFO - 'XPC_FLAGS': '0x0', 07:23:12 INFO - 'XPC_SERVICE_NAME': '0', 07:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:23:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:23:13 INFO - mozversion application_buildid: 20160825051426 07:23:13 INFO - mozversion application_changeset: 9467a563a01b466fdca07c0495de7bcfd0a66215 07:23:13 INFO - mozversion application_display_name: Nightly 07:23:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:23:13 INFO - mozversion application_name: Firefox 07:23:13 INFO - mozversion application_remotingname: firefox 07:23:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:23:13 INFO - mozversion application_vendor: Mozilla 07:23:13 INFO - mozversion application_version: 51.0a1 07:23:13 INFO - mozversion platform_buildid: 20160825051426 07:23:13 INFO - mozversion platform_changeset: 9467a563a01b466fdca07c0495de7bcfd0a66215 07:23:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:23:13 INFO - mozversion platform_version: 51.0a1 07:23:13 INFO - using testdate: 1472134993 07:23:13 INFO - actual date: 1472134993 07:23:13 INFO - starting webserver on 'localhost:49268' 07:23:13 INFO - SUITE-START | Running 2 tests 07:23:13 INFO - TEST-START | dromaeo_css 07:23:13 INFO - operating with platform_type : mac_ 07:23:13 INFO - Initialising browser for dromaeo_css test... 07:23:13 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmp6eQITH/profile http://localhost:49268/getInfo.html) 07:23:15 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 07:23:15 INFO - PROCESS | 1645 | colorDepth:24 07:23:15 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 07:23:15 INFO - PROCESS | 1645 | __metrics 07:23:15 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:23:15 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:23:16 INFO - TEST-INFO | 1645: exit 0 07:23:16 INFO - Browser initialized. 07:23:16 INFO - Running cycle 1/1 for dromaeo_css test... 07:23:16 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmp6eQITH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:23:28 INFO - PROCESS | 1647 | RSS: Main: 232390656 07:23:28 INFO - PROCESS | 1647 | 07:24:19 INFO - PROCESS | 1647 | 1472135059062 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:26:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 07:26:32 INFO - PROCESS | 1647 | RSS: Main: 349507584 07:26:32 INFO - PROCESS | 1647 | 07:29:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 07:29:36 INFO - PROCESS | 1647 | RSS: Main: 378974208 07:29:36 INFO - PROCESS | 1647 | 07:32:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 07:32:39 INFO - PROCESS | 1647 | RSS: Main: 360501248 07:32:39 INFO - PROCESS | 1647 | 07:35:41 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 07:35:42 INFO - PROCESS | 1647 | RSS: Main: 331214848 07:35:42 INFO - PROCESS | 1647 | 07:38:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 07:38:46 INFO - PROCESS | 1647 | RSS: Main: 323280896 07:38:46 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 07:41:49 INFO - PROCESS | 1647 | RSS: Main: 512692224 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | __start_tp_report 07:41:49 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 07:41:49 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 07:41:49 INFO - PROCESS | 1647 | |i|pagename|runs| 07:41:49 INFO - PROCESS | 1647 | |0;dojo.html;3325.4180518409275;3325.800451972882;3361.4621660534303;3390.4236279832426;3436.0894362993813;5576.470235327637;5669.546436285098;5688.118341657039;5778;5788.50797682197;5761.337446193689;5779.595428320019;5786.421357864211;5793.9281232971925;5795.15970184323;5149.665271757333;5178.145605975028;5215.061288967984;5232.215167724834;5275.630705850573;5250.238715386266;5260.210968354739;5260.421353651095;5263;5264.763085661154;5160.8709782255555;5161.612879034087;5171.250168725533;5182.844514664566;5266.736663166846;5247.737613119329;5252.6848389096585;5260.342457192851;5289.312389389354;5360.544353729938;5080.110980578384;5103.1324674805;5121.487851214886;5145.30538377318;5219.138842091051;5631.831045068655;5660.547156227533;5676.659400435987;5731.770729170828;5805.82582522525;6075.75696972125;6100.793122958898;6291.62250264981;6302.212223472066;6315;4999.550040496373;6228.439440450381;6302.369763023707;6321.494280457552;6323.241211054657;4902.754862256944;5501.752421141058;5768.077107662796;5818.447247511522;5837.474627283565;6207.006878899354;6259.593126446766;6301.369863013662;6340.746370145235;6353.555251132469;6122.54080943931;6135.2326666699655;6142.846428839315;6150;6161.630302181794;5380.7309634518115;6243.594166379262;6293.181886354744;6306.211723534559;6312.621242725451;6008.218931538872;6033.9698301508215;6248;6248.656323902138;6250.656213908188;5582.385937546866;6107.674343494518;6114.174586430864;6130.386961303834;6166.445019948136;4841.540053694894;4843.8304659337455;4848.10310092634;4871.853844384674;4878.756062196876;4361.084172323847;4368.497622773421;4389.341598760211;4396.626286765597;4411.845585404495;5563.972180139154;5591.10542313228;5628.549716022709;5695.117256825199;5704.258446401941;6235.719392627343;6247.156633854463;6257.655828929362;6259.499240060784;6264.686765661699;5624.85937851557;5692.459216374438;5694;5697.572682048863;5704.087346024616;5379.811706590251;5397.784088636489;5447.319085114361;5448.455154484599;5450.727463626803;6306.936930630818;6341.175647165839;6346.873062538723;6348.428641422294;6358.205224346864;6101.938980610137;6292.370762923671;6304.621722696653;6314.463270622048;6319.210098737658;6260.217472815898;6263.091851681572;6285.025820997753;6347.206599175103;6351.872962540816;6144.53915956314;6169.96915015422;6178.969105154445;6196.194494715658;6208.534359922934;5147.176451767699;5873.794417195378;5942;5982.670953097448;6276.6547839869245;5997.940020599913;6004;6069.4840938518955;6116.1836049259855;6121.696863452088;6077.5745697802495;6080.300765411857;6090.421409966046;6121.969390153021;6128.571000029955;5781.132830075522;5936.703164841654;5952.76188952437;5963.224780778471;5970.134330522137;6009.969950150221;6041.214642096499;6084.726187321669;6108.038540470994;6128.417800308964;5905.52755779528;5925.05199168131;5946.583739138218;5953.226080609493;5954.553408494467 07:41:49 INFO - PROCESS | 1647 | |1;ext.html;461.88222003389143;529.9841004769853;532.6883772992799;535.1865164949278;551.5890661457213;30573.388532229368;32380.190495237603;32413.837930810314;32421.51367729486;32624.184395390126;32456.513152302734;41477.17045659085;41547;41625.58374416255;43984.780076099654;9629.903700962988;9765.609375624967;9771.462569558671;9771.75570610737;9789.559469823875;9672.468014259213;9756.512174391251;9757.560909759079;9760.316777825556;9768;9730.805383892319;9774.31579789415;9777.12005919467;9787.265955053364;9795.559199836025;9656.613735450574;9733;9736.75658108546;9737.026297370241;9758.414495130315;9633.951830240874;9710.563024663836;9722.270829687732;9726.027397260288;9730.951345243298;40014.599854001666;40094.59905400938;40127.39808902839;40173.995650108984;40225;3326.933461330784;3437.5874895012507;3443.9827800861085;3445.707114895212;3550.3076900004507;25903.74096259051;30590.541141882888;31715.841420792978;31840.363192736015;31852.681473185436;3266.836658167082;3340.3820293245826;3406.7785593936314;3427.246005878703;3448.448248280263;505905;507488;508639;508710;517216;56596.43403565994;56915.715421422625;56920.715396422755;56980.71509642508;56981;58728.11907821386;59398;59611.70194149001;59789.103163452586;59925.10112348318;15757;16006.119663418624;16007.599810004844;16021.519354419386;16077.874548781472;12458.0033597312;12505.56230531927;12527.498900044078;12532.874671253354;12541.372931353395;15322.770158447633;15491.612709682122;15499.5350139496;15525.844741552666;15733;5539.972300138474;5541.750621222055;6068.302145253264;6075;6077.939220607737;966.6761634852263;976.1458723616835;990.272149969786;1003.7390278527489;1084.2735367303767;29171.53206692127;29372.26452425233;29623.851880740458;29662;29825.254368640522;864.6195673903588;1014.9238807089498;1052.3738375666467;1067.3435836960214;1108.451316598289;13114.213147211198;15093;15236.619084522976;15356.616084597974;15477;671272;673067;675865.6206719034;676442;679267;49543.256851147264;57487.71256143692;57782.71108644514;57884;57975.71012144996;1265.7215412609212;1362.734266817961;1398.5734351023036;1408.8732014118777;1419.2974477633638;30261.394772104875;30813.691863081083;30884.382312354075;31604.683953160173;31621.683783162793;49396.259056114905;54015.72992135014;56082.439175607724;56191.43808561862;59815.70092149511;627731;628382;633848;647215;649805;22475.43811404728;22883.771162288165;25589.744102558736;25704;26303.868480657475;46805.29792053054;46952;47041.764791175825;47315;48128;29435;39011;39025;39147.80426097851;40651.79674101611;38759.80620096994;39053.609463905;39186.804065979486;39479.80260098681;39508;37572.436413454365;37911.43132853065;38295.04262393463;38338.42492362673;38362.61637383702;32523;34395.14012149716;34684.12634946042;34704.96777967231;35081;24949.87725552391;25024.874875625505;25037.749622503543;25427;25693.743062569883 07:41:49 INFO - PROCESS | 1647 | |2;jquery.html;340.74614412262866;354.78890060414045;358.4998926497536;368.120192739353;385.35260761919966;15666.21668916554;17352.392666256685;20273.03890704484;20511.53861536614;20582.794172058293;20708.585828283427;20743.896280518577;20988.58022839542;21148.682769758427;21591.244306449276;5160.664556803796;5693.089105743093;5894.115882617621;5937.079752638348;5973.596204891869;4189.539150693437;4393.406890069848;5777.740001699934;5976.940230597704;6370.140031095812;6562.573432726881;6636.73453061877;6655.301193374675;6660.067590537328;6680.899786503206;6300.7794727184555;6462.127612772286;6513.576617519846;6514.93485065148;6524.706388212542;5406.8918621627745;5624.100143976944;5631.662100273956;5638.887222255532;5666.829995100154;26552.73447265522;26594.33514162142;26667.733322666914;26667.866660666765;26951.730482695315;2170.3597438755446;2262.7963483286585;2268.6823844661762;2357.2221167014845;2368.7631236876346;9915.801683966352;10116.190704743603;10397.116245119174;13092.345382730826;14724;1589.4996093015977;1821.353419536059;1886.5377982394402;1907.1513176636402;1921.6733155363622;110639.44680276628;113663;123083;123355.9999999991;128664.356678216;10313.896861031293;11120.944395277971;11124.49939752713;11272.89995251296;11281.71795705114;14173;14175.007749457645;14216.147031177956;14232.43070277198;14251.287435628177;8099.757007289791;8411.957940210383;8433.493990360597;8487.660493580188;8535.274501667303;8357.373197010249;8375.45559538628;8382.413231073766;8547.871781923277;8566;3822.847086116524;4314.654827613784;4388.319810429389;4397.362382454527;4482.148391805546;1713.2376092638656;1754.6578417208777;1800.1809176824513;1811.5742800441885;1847.288793814391;860.8622620380709;863.87905693203;871.4771137317558;891.3003292415423;937.3579098317674;21187.046582903808;21792.455188620093;21905.561888762135;21977;21994.780052199272;814.2142832166869;921.576075005505;935.9485228312373;953.4898829126384;959.1990687775645;8912.598933047899;9886.851697224547;10691.679249622524;11095.056920161715;11319.603813866475;590080;590512;595406;606956;614066.9296653578;131124.3443782794;131322;131522;132160;134508.3274583621;954.8949615542285;961.9807603847884;982.729749318943;985.3102828020271;1008.7377281906757;18276.268949242414;18811.71782423292;18876.528086797938;18893.622127557097;18973.715394269373;45859.54140458686;53092.93814123618;53599;53899.73050134724;54703.70907345547;77734.83397748925;79255;79701.60149199217;79723;80138.59930700542;7046.4715146363205;7322.853542929005;7341.15576708664;7420;8053.758387248392;10615.938406159323;11035.83446248323;12969.870301296865;13412.463501459832;13457.596272111852;11217.775644486901;11277.939941620845;11533.077353811843;11663.24188927717;11677.591284305008;9595.952020239854;10725.624603138853;11003.22977391575;11514.251573647685;11609.76780464369;10321.535530900977;10925.672229833117;11431.59989400367;11706.76586468283;11937.403129843648;6755.966220168868;6860.348266914635;7969.123396426273;8066;8322.20939010793;10745.301555398873;11648;11684.70788230301;11692.649220523397;11702.122340824642 07:41:49 INFO - PROCESS | 1647 | |3;mootools.html;1495.8354580996092;1531.6324082220267;1681.1846254566533;1811.5652243461573;1855.5175654329878;7815.25755053269;8083.191680831914;8404.831903361937;8818.382713210078;8831;8424.368172387065;8696.260817830493;8709.25971292441;8771.649134034647;8778.604962776675;2366.5858474766933;2397.61638137898;2398.040783686522;2403.098837935774;2403.7956773674173;2100.3173968460237;2288.3592594073684;2366.089055713554;2366.3374255208482;2380.381100913766;2369.786719195272;2395.173665085543;2395.9041638334447;2398.760123987596;2404.362843846382;2306.238941149425;2341.098677009347;2346.21401830387;2353.917612883558;2370.5495955768347;2342.8125749939963;2350.847194932324;2362.11420717231;2384.0940442631863;2387.5583017141885;8828.117188281185;8914.687985920527;8926.2412694921;8928.598213080362;8933.106689331014;4521.864344069683;5189.2475591039465;5350.30446042016;5354.196870469422;5375.784968601251;3525.083478295636;3547.94678079829;3627.7097832173363;3628.1473853644375;3637.5998640149564;1020.8009438159562;1030.469308306223;1033.529743966501;1041.4688508860536;1800.4418630224673;11454.885451145465;11480.483378247998;11499.022583080448;11519.769604607945;11522.942385288105;3984.880453586397;4186.995121170958;4442.555744425532;4514.616257618073;4590.954090459053;7411.9617105296475;8617.95691021541;8618.353623478264;8628.52543110135;8647.048824629286;5040.823571174991;5050.318207042076;5051.444341122474;5051.898962020739;5059.822906198266;4611.76941152941;4643.489216186217;4727.361806156194;4731.550502702238;4740.454847692559;3427.3830710471857;3572.6427357264065;3645.8177091145867;3760.1163726524246;3772.0192749884677;1050.564015933379;1056.334509259162;1087.5867170475326;1113.1206346985953;1123.27548731068;844.0293662288295;858.1332853817564;888.4329417389188;906.6328137104483;950.7670620697974;5654.6324488908085;5717.714114294268;6005.069214271794;6012.30858451275;6125;941.7268991992246;956.6699488676395;970.3595626886221;973.4208146153028;992.3252188511881;3647.3434781739543;3869.157204414463;4032.3145065338376;4081.469408976814;4104.56902025283;10494.160467162608;10759.408232547128;10848.132149428027;10860.891391086147;10864.511097000654;10076.244281678757;10599.682009539727;10782.137429005661;10808.135349172047;10838;7909.24862138096;8129.471584347117;8133;8140.430169888051;8152.144024877421;5844.327902291291;5861;5922.940770592411;6136.987397079611;6297.921685843569;9491.572879220314;9878.901210987799;9913.256505761952;9936.056074672895;9939.503024848873;10021.99780021992;10038.598456061676;10063.345882517613;10066.597336106766;10079.647212347532;7458.888116678146;7554.395648348009;7601.353884919844;7621.771346859603;7653.9234607655435;8244;8318;8427.536485493112;8434.156584341517;8435.704750333709;6988.196357418758;7530.5858177798555;7613.885791713239;7723.034620672416;7786.961065194638;7298.525595836254;7412.555246685;7416.7774966751085;7430.517016393917;7512.924870751222;6945.0971373721095;6956.756513522002;7018.508704390848;7022.367986881205;7026.824329391806;4601.19479091151;4703.659457054723;4943.752812359439;5003.499650034967;5134.897302053863;4164.292070348109;4799.784009719641;5018.774155162953;5031.723255220998;5123.615728820428 07:41:49 INFO - PROCESS | 1647 | |4;prototype.html;254.63841345289686;266.88657320638725;288.7718702225242;302.19615821913715;306.38416782267643;4216.430781844454;5016.799328026875;5208.453112423198;5246.344206974129;5260.684358938457;4850.9029819403595;5159.742012899359;5169.30214421053;5205.583553315735;5235.240890070947;3740.4202348636004;3862.9420558691572;3921.803849825802;3990.06233535119;4008.9175922501054;3557.146284891633;3616.987243571804;3939.428782826503;3970.3845903884944;4009.6992725545556;3463.6709512596262;3510.8420121094487;3895.7662540247534;3912.8434862605463;3949.2496425679174;3210.7752457328115;3373.730101591867;3728.881335599323;3914.275858966072;3933.3903244997073;2911.839848808315;3314.685104915029;3395.439752440844;3404.7446441516986;3422.7946323220685;3950.3876899080688;4494.9325760113625;4804.903901921942;5081.23781432788;5141.76862041209;4086.366613174963;4336.34954756789;4515.096980603892;4532.796024178887;4547.681662283641;5378;5384;5399.460053994609;5435.239066530688;5456.781728730846;3281.819499927503;3322.7175690066374;3735.477033215352;3750.5124333836425;3763.6424539668687;4763.595094416979;4999.975000125012;5176.21420609889;5725.799597014085;5740.6268592541355;3754.75594086389;3793.943090853639;3794.0135564753364;3800.448934904479;3804.5434547854434;3461.745958798721;3743.5882052974152;3802.923941521154;3845.1732877431486;3856.749311294757;3863.903402414906;3986.5614782373914;4119.629233369011;4510.368548403226;4517.62212525183;4977.850664479999;5107.821226257138;5157.561607263349;5424.620276580601;5474.863128421742;1173.3898372846072;1174.8531433570804;1299.3373379576485;1313.9277339746216;1324.735052989406;738.3723834740427;773.9303462688385;809.5102463009795;817.8364327134597;826.7643721539332;4372.660626564772;4566.109608626286;4693.249080147229;4869.61388919434;5195.272481947008;4983.227599722049;5220.817271395559;5581.832545023656;5606.6916319602815;5647.49172574462;4309.806058727365;4544.409226800495;4842.215725957589;5003.324551185616;5134.974325128351;2745;2928.428956353533;2935.2515108647585;2936.5595160725634;2949.0857834071076;5038.875896943919;5709.286339207599;5761.711914404347;5839.634947846129;5844.298684157929;4640.489546149921;4952.925706114411;5233.476652334736;5373.543248823894;5492.697901615449;5290.7831198823715;5393.406725260218;5488.615796894259;5502.147167189012;5789.536837052941;4720.598749106227;5395.32931199059;5593.972030139823;5605.635633683797;5672.858178545569;5209.431539395526;5567.248421463212;5650.519705825051;5691.288588926384;5731.914021289767;5294.120587191492;5895.29256489224;5904.439078287556;5925;5940.524758019435;3358.7648864579246;3673.7428380013143;3784.924301513899;3812.8474861005247;3823.4264860271182;5041.647084704182;5055.191169412877;5461.641090729252;5697.401772813961;5698.572607054571;4742.834000809955;5506.697131657797;5534.280543529477;5607.214989901336;5607.775688972395;4492.191405547033;4926.241304869284;5155.922661160161;5631.633943793804;5662.688552129673;5427.232111623021;5442.292501974718;5470.456237849419;5484.369297530835;5510.016394098202;4434.733915965053;5333.359996800254;5550.639208451437;5581.609287350009;5594.944050559442;5380.730963451733;5400.270963419888;5439.728013599225;5459.7270136492225;5484.945150548603 07:41:49 INFO - PROCESS | 1647 | |5;yui.html;726.5822152262449;746.4540945641045;759.0663483914784;807.2290962131166;832.9916700832997;15231.771523427158;15993.92003039986;16005.199740013011;16045.91977040116;16136.677266454679;12306;12826.294553799537;14496.927515362408;14757;14761.926190369033;860.1914200651383;877.3770622857778;886.7295474880159;887.2901678657048;890.8663700444953;853.0403296291672;853.2150421612132;855.8288342331558;857.1086070486687;859.7420773767876;720.7801620505744;745.4520927118564;896.5427631907742;898.3980732908967;900.693764120199;734.5629350536423;735.8565079809413;885.4775682347446;919.2278486071732;923.007766650853;682.3381320119477;682.757621044527;683.5522732610149;683.9931600684029;684.4797953555284;14786.704265914621;14828.851711482856;14830.851691483054;14833.55499335011;14851.925740371336;14039.368228429745;14331.641708957255;14343.497977570734;14421;14454.927725361411;12985;13044.673883152902;13090.214587124803;13110.672233194151;13253.801192982113;4662.580367766917;4814.2537906624175;4851.151048566522;4861.246506791453;4884.95115048852;83825.58087209525;105359.47320263427;106430;106777.44949532834;109132;9219.939706933654;11111.444427778579;11159.163062770325;11297;11322.207445478738;18653.81346186548;22137;22229.221977230718;22332.88833555822;22599.32202033909;7791.883121753178;7807.414443916729;7907.288344048948;7951.642176102089;8096.352291816758;4372.3878656988045;4389.978050109729;4419.337099435111;4434.113177364476;4531.410916580823;3667.3765459871884;3819.388897776342;3825.1202223488754;3828.8085595720104;3829.7319187657154;689.2315068698365;707.2079271216271;710.8222944263935;712.9322714342129;715.0382735221119;660.3132741948416;680.8570200258001;682.4574463301688;682.8600136971909;691.1913061717802;8258.876116858251;8530.701425450203;8937.865932011026;9182.127697868691;9622.663206787729;585.772805971489;632.7152781248457;658.1214079204316;662.8873091574444;689.6551724137931;3930.6265904739003;4525.456945166601;4661.277501987197;4685.367475390779;4770.737609431445;160355;163944;168550.9311336083;169164.61670766762;172986.16110711114;138697.61302387048;140457.5954240465;142492;145435.09129817344;147919.26040369945;73781;119244.21133683002;124659;124742;129262.99999999812;9680;9681.757956051155;9783.706488805346;9892.258080644124;9916.454594997344;55999;60307;61201;64635.70728585496;70907.64546177236;71292.57414851777;72998;91863;98351;98516;13469;13998.970149328086;14004.969279454626;14080.706366725284;14445.422183112978;15062.171580563174;15204.239788010334;15688;15764.369425222863;16306.510804675878;14442.927785361006;14565.941152358124;14787.038842475205;14825;14843.40626374933;14379.784303235669;14425.278736063365;14459.060161089496;14593.85406145925;15100.169490678118;12858.807117893426;14009.369578369202;14133.293335333397;14344.784828227375;14441.35013924397;11153.330800151693;11210.383428911487;11288.492017859051;11541.339782633262;11670.94164529172;9994.350367226107;10963.616273430393;11273.830892536454;11593.24643898144;11938.880611193777 07:41:49 INFO - PROCESS | 1647 | __end_tp_report 07:41:49 INFO - PROCESS | 1647 | __start_cc_report 07:41:49 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1281 07:41:49 INFO - PROCESS | 1647 | __end_cc_report 07:41:49 INFO - PROCESS | 1647 | __startTimestamp1472136109932__endTimestamp 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | ------- Summary: start ------- 07:41:49 INFO - PROCESS | 1647 | Number of tests: 6 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5710.02 Median:5925.05 stddev:642.65 (10.8%) stddev-sans-first:616.79 07:41:49 INFO - PROCESS | 1647 | Values: 3325.4 3325.8 3361.5 3390.4 3436.1 5576.5 5669.5 5688.1 5778.0 5788.5 5761.3 5779.6 5786.4 5793.9 5795.2 5149.7 5178.1 5215.1 5232.2 5275.6 5250.2 5260.2 5260.4 5263.0 5264.8 5160.9 5161.6 5171.3 5182.8 5266.7 5247.7 5252.7 5260.3 5289.3 5360.5 5080.1 5103.1 5121.5 5145.3 5219.1 5631.8 5660.5 5676.7 5731.8 5805.8 6075.8 6100.8 6291.6 6302.2 6315.0 4999.6 6228.4 6302.4 6321.5 6323.2 4902.8 5501.8 5768.1 5818.4 5837.5 6207.0 6259.6 6301.4 6340.7 6353.6 6122.5 6135.2 6142.8 6150.0 6161.6 5380.7 6243.6 6293.2 6306.2 6312.6 6008.2 6034.0 6248.0 6248.7 6250.7 5582.4 6107.7 6114.2 6130.4 6166.4 4841.5 4843.8 4848.1 4871.9 4878.8 4361.1 4368.5 4389.3 4396.6 4411.8 5564.0 5591.1 5628.5 5695.1 5704.3 6235.7 6247.2 6257.7 6259.5 6264.7 5624.9 5692.5 5694.0 5697.6 5704.1 5379.8 5397.8 5447.3 5448.5 5450.7 6306.9 6341.2 6346.9 6348.4 6358.2 6101.9 6292.4 6304.6 6314.5 6319.2 6260.2 6263.1 6285.0 6347.2 6351.9 6144.5 6170.0 6179.0 6196.2 6208.5 5147.2 5873.8 5942.0 5982.7 6276.7 5997.9 6004.0 6069.5 6116.2 6121.7 6077.6 6080.3 6090.4 6122.0 6128.6 5781.1 5936.7 5952.8 5963.2 5970.1 6010.0 6041.2 6084.7 6108.0 6128.4 5905.5 5925.1 5946.6 5953.2 5954.6 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:72871.94 Median:39761.30 stddev:163862.21 (412.1%) stddev-sans-first:164231.69 07:41:49 INFO - PROCESS | 1647 | Values: 461.9 530.0 532.7 535.2 551.6 30573.4 32380.2 32413.8 32421.5 32624.2 32456.5 41477.2 41547.0 41625.6 43984.8 9629.9 9765.6 9771.5 9771.8 9789.6 9672.5 9756.5 9757.6 9760.3 9768.0 9730.8 9774.3 9777.1 9787.3 9795.6 9656.6 9733.0 9736.8 9737.0 9758.4 9634.0 9710.6 9722.3 9726.0 9731.0 40014.6 40094.6 40127.4 40174.0 40225.0 3326.9 3437.6 3444.0 3445.7 3550.3 25903.7 30590.5 31715.8 31840.4 31852.7 3266.8 3340.4 3406.8 3427.2 3448.4 505905.0 507488.0 508639.0 508710.0 517216.0 56596.4 56915.7 56920.7 56980.7 56981.0 58728.1 59398.0 59611.7 59789.1 59925.1 15757.0 16006.1 16007.6 16021.5 16077.9 12458.0 12505.6 12527.5 12532.9 12541.4 15322.8 15491.6 15499.5 15525.8 15733.0 5540.0 5541.8 6068.3 6075.0 6077.9 966.7 976.1 990.3 1003.7 1084.3 29171.5 29372.3 29623.9 29662.0 29825.3 864.6 1014.9 1052.4 1067.3 1108.5 13114.2 15093.0 15236.6 15356.6 15477.0 671272.0 673067.0 675865.6 676442.0 679267.0 49543.3 57487.7 57782.7 57884.0 57975.7 1265.7 1362.7 1398.6 1408.9 1419.3 30261.4 30813.7 30884.4 31604.7 31621.7 49396.3 54015.7 56082.4 56191.4 59815.7 627731.0 628382.0 633848.0 647215.0 649805.0 22475.4 22883.8 25589.7 25704.0 26303.9 46805.3 46952.0 47041.8 47315.0 48128.0 29435.0 39011.0 39025.0 39147.8 40651.8 38759.8 39053.6 39186.8 39479.8 39508.0 37572.4 37911.4 38295.0 38338.4 38362.6 32523.0 34395.1 34684.1 34705.0 35081.0 24949.9 25024.9 25037.7 25427.0 25693.7 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35270.44 Median:26809.80 stddev:100214.92 (373.8%) stddev-sans-first:100461.73 07:41:49 INFO - PROCESS | 1647 | Values: 340.7 354.8 358.5 368.1 385.4 15666.2 17352.4 20273.0 20511.5 20582.8 20708.6 20743.9 20988.6 21148.7 21591.2 5160.7 5693.1 5894.1 5937.1 5973.6 4189.5 4393.4 5777.7 5976.9 6370.1 6562.6 6636.7 6655.3 6660.1 6680.9 6300.8 6462.1 6513.6 6514.9 6524.7 5406.9 5624.1 5631.7 5638.9 5666.8 26552.7 26594.3 26667.7 26667.9 26951.7 2170.4 2262.8 2268.7 2357.2 2368.8 9915.8 10116.2 10397.1 13092.3 14724.0 1589.5 1821.4 1886.5 1907.2 1921.7 110639.4 113663.0 123083.0 123356.0 128664.4 10313.9 11120.9 11124.5 11272.9 11281.7 14173.0 14175.0 14216.1 14232.4 14251.3 8099.8 8412.0 8433.5 8487.7 8535.3 8357.4 8375.5 8382.4 8547.9 8566.0 3822.8 4314.7 4388.3 4397.4 4482.1 1713.2 1754.7 1800.2 1811.6 1847.3 860.9 863.9 871.5 891.3 937.4 21187.0 21792.5 21905.6 21977.0 21994.8 814.2 921.6 935.9 953.5 959.2 8912.6 9886.9 10691.7 11095.1 11319.6 590080.0 590512.0 595406.0 606956.0 614066.9 131124.3 131322.0 131522.0 132160.0 134508.3 954.9 962.0 982.7 985.3 1008.7 18276.3 18811.7 18876.5 18893.6 18973.7 45859.5 53092.9 53599.0 53899.7 54703.7 77734.8 79255.0 79701.6 79723.0 80138.6 7046.5 7322.9 7341.2 7420.0 8053.8 10615.9 11035.8 12969.9 13412.5 13457.6 11217.8 11277.9 11533.1 11663.2 11677.6 9596.0 10725.6 11003.2 11514.3 11609.8 10321.5 10925.7 11431.6 11706.8 11937.4 6756.0 6860.3 7969.1 8066.0 8322.2 10745.3 11648.0 11684.7 11692.6 11702.1 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5631.79 Median:4973.63 stddev:3147.28 (63.3%) stddev-sans-first:3140.76 07:41:49 INFO - PROCESS | 1647 | Values: 1495.8 1531.6 1681.2 1811.6 1855.5 7815.3 8083.2 8404.8 8818.4 8831.0 8424.4 8696.3 8709.3 8771.6 8778.6 2366.6 2397.6 2398.0 2403.1 2403.8 2100.3 2288.4 2366.1 2366.3 2380.4 2369.8 2395.2 2395.9 2398.8 2404.4 2306.2 2341.1 2346.2 2353.9 2370.5 2342.8 2350.8 2362.1 2384.1 2387.6 8828.1 8914.7 8926.2 8928.6 8933.1 4521.9 5189.2 5350.3 5354.2 5375.8 3525.1 3547.9 3627.7 3628.1 3637.6 1020.8 1030.5 1033.5 1041.5 1800.4 11454.9 11480.5 11499.0 11519.8 11522.9 3984.9 4187.0 4442.6 4514.6 4591.0 7412.0 8618.0 8618.4 8628.5 8647.0 5040.8 5050.3 5051.4 5051.9 5059.8 4611.8 4643.5 4727.4 4731.6 4740.5 3427.4 3572.6 3645.8 3760.1 3772.0 1050.6 1056.3 1087.6 1113.1 1123.3 844.0 858.1 888.4 906.6 950.8 5654.6 5717.7 6005.1 6012.3 6125.0 941.7 956.7 970.4 973.4 992.3 3647.3 3869.2 4032.3 4081.5 4104.6 10494.2 10759.4 10848.1 10860.9 10864.5 10076.2 10599.7 10782.1 10808.1 10838.0 7909.2 8129.5 8133.0 8140.4 8152.1 5844.3 5861.0 5922.9 6137.0 6297.9 9491.6 9878.9 9913.3 9936.1 9939.5 10022.0 10038.6 10063.3 10066.6 10079.6 7458.9 7554.4 7601.4 7621.8 7653.9 8244.0 8318.0 8427.5 8434.2 8435.7 6988.2 7530.6 7613.9 7723.0 7787.0 7298.5 7412.6 7416.8 7430.5 7512.9 6945.1 6956.8 7018.5 7022.4 7026.8 4601.2 4703.7 4943.8 5003.5 5134.9 4164.3 4799.8 5018.8 5031.7 5123.6 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4384.96 Median:4980.54 stddev:1368.75 (27.5%) stddev-sans-first:1337.02 07:41:49 INFO - PROCESS | 1647 | Values: 254.6 266.9 288.8 302.2 306.4 4216.4 5016.8 5208.5 5246.3 5260.7 4850.9 5159.7 5169.3 5205.6 5235.2 3740.4 3862.9 3921.8 3990.1 4008.9 3557.1 3617.0 3939.4 3970.4 4009.7 3463.7 3510.8 3895.8 3912.8 3949.2 3210.8 3373.7 3728.9 3914.3 3933.4 2911.8 3314.7 3395.4 3404.7 3422.8 3950.4 4494.9 4804.9 5081.2 5141.8 4086.4 4336.3 4515.1 4532.8 4547.7 5378.0 5384.0 5399.5 5435.2 5456.8 3281.8 3322.7 3735.5 3750.5 3763.6 4763.6 5000.0 5176.2 5725.8 5740.6 3754.8 3793.9 3794.0 3800.4 3804.5 3461.7 3743.6 3802.9 3845.2 3856.7 3863.9 3986.6 4119.6 4510.4 4517.6 4977.9 5107.8 5157.6 5424.6 5474.9 1173.4 1174.9 1299.3 1313.9 1324.7 738.4 773.9 809.5 817.8 826.8 4372.7 4566.1 4693.2 4869.6 5195.3 4983.2 5220.8 5581.8 5606.7 5647.5 4309.8 4544.4 4842.2 5003.3 5135.0 2745.0 2928.4 2935.3 2936.6 2949.1 5038.9 5709.3 5761.7 5839.6 5844.3 4640.5 4952.9 5233.5 5373.5 5492.7 5290.8 5393.4 5488.6 5502.1 5789.5 4720.6 5395.3 5594.0 5605.6 5672.9 5209.4 5567.2 5650.5 5691.3 5731.9 5294.1 5895.3 5904.4 5925.0 5940.5 3358.8 3673.7 3784.9 3812.8 3823.4 5041.6 5055.2 5461.6 5697.4 5698.6 4742.8 5506.7 5534.3 5607.2 5607.8 4492.2 4926.2 5155.9 5631.6 5662.7 5427.2 5442.3 5470.5 5484.4 5510.0 4434.7 5333.4 5550.6 5581.6 5594.9 5380.7 5400.3 5439.7 5459.7 5484.9 07:41:49 INFO - PROCESS | 1647 | 07:41:49 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25740.44 Median:3880.18 stddev:42089.43 (1084.7%) stddev-sans-first:42165.59 07:41:49 INFO - PROCESS | 1647 | Values: 726.6 746.5 759.1 807.2 833.0 15231.8 15993.9 16005.2 16045.9 16136.7 12306.0 12826.3 14496.9 14757.0 14761.9 860.2 877.4 886.7 887.3 890.9 853.0 853.2 855.8 857.1 859.7 720.8 745.5 896.5 898.4 900.7 734.6 735.9 885.5 919.2 923.0 682.3 682.8 683.6 684.0 684.5 14786.7 14828.9 14830.9 14833.6 14851.9 14039.4 14331.6 14343.5 14421.0 14454.9 12985.0 13044.7 13090.2 13110.7 13253.8 4662.6 4814.3 4851.2 4861.2 4885.0 83825.6 105359.5 106430.0 106777.4 109132.0 9219.9 11111.4 11159.2 11297.0 11322.2 18653.8 22137.0 22229.2 22332.9 22599.3 7791.9 7807.4 7907.3 7951.6 8096.4 4372.4 4390.0 4419.3 4434.1 4531.4 3667.4 3819.4 3825.1 3828.8 3829.7 689.2 707.2 710.8 712.9 715.0 660.3 680.9 682.5 682.9 691.2 8258.9 8530.7 8937.9 9182.1 9622.7 585.8 632.7 658.1 662.9 689.7 3930.6 4525.5 4661.3 4685.4 4770.7 160355.0 163944.0 168550.9 169164.6 172986.2 138697.6 140457.6 142492.0 145435.1 147919.3 73781.0 119244.2 124659.0 124742.0 129263.0 9680.0 9681.8 9783.7 9892.3 9916.5 55999.0 60307.0 61201.0 64635.7 70907.6 71292.6 72998.0 91863.0 98351.0 98516.0 13469.0 13999.0 14005.0 14080.7 14445.4 15062.2 15204.2 15688.0 15764.4 16306.5 14442.9 14565.9 14787.0 14825.0 14843.4 14379.8 14425.3 14459.1 14593.9 15100.2 12858.8 14009.4 14133.3 14344.8 14441.4 11153.3 11210.4 11288.5 11541.3 11670.9 9994.4 10963.6 11273.8 11593.2 11938.9 07:41:49 INFO - PROCESS | 1647 | -------- Summary: end -------- 07:41:49 INFO - PROCESS | 1647 | 07:41:50 INFO - TEST-INFO | 1647: exit 0 07:41:50 INFO - TEST-OK | dromaeo_css | took 1117593ms 07:41:50 INFO - TEST-START | kraken 07:41:50 INFO - operating with platform_type : mac_ 07:41:50 INFO - Initialising browser for kraken test... 07:41:50 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpH8zGT5/profile http://localhost:49268/getInfo.html) 07:41:52 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 07:41:52 INFO - PROCESS | 1655 | colorDepth:24 07:41:52 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 07:41:52 INFO - PROCESS | 1655 | __metrics 07:41:52 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:41:52 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:41:53 INFO - TEST-INFO | 1655: exit 0 07:41:53 INFO - Browser initialized. 07:41:53 INFO - Running cycle 1/1 for kraken test... 07:41:53 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpH8zGT5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:42:05 INFO - PROCESS | 1656 | RSS: Main: 232378368 07:42:05 INFO - PROCESS | 1656 | 07:42:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 07:42:09 INFO - PROCESS | 1656 | RSS: Main: 290017280 07:42:09 INFO - PROCESS | 1656 | 07:42:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 07:42:12 INFO - PROCESS | 1656 | RSS: Main: 282267648 07:42:12 INFO - PROCESS | 1656 | 07:42:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 07:42:16 INFO - PROCESS | 1656 | RSS: Main: 371982336 07:42:16 INFO - PROCESS | 1656 | 07:42:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 07:42:18 INFO - PROCESS | 1656 | RSS: Main: 278122496 07:42:18 INFO - PROCESS | 1656 | 07:42:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:42:21 INFO - PROCESS | 1656 | RSS: Main: 277544960 07:42:21 INFO - PROCESS | 1656 | 07:42:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 07:42:24 INFO - PROCESS | 1656 | RSS: Main: 316555264 07:42:24 INFO - PROCESS | 1656 | 07:42:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 07:42:28 INFO - PROCESS | 1656 | RSS: Main: 317906944 07:42:28 INFO - PROCESS | 1656 | 07:42:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 07:42:31 INFO - PROCESS | 1656 | RSS: Main: 317100032 07:42:31 INFO - PROCESS | 1656 | 07:42:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:42:33 INFO - PROCESS | 1656 | RSS: Main: 296095744 07:42:33 INFO - PROCESS | 1656 | 07:42:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:42:36 INFO - PROCESS | 1656 | RSS: Main: 301527040 07:42:36 INFO - PROCESS | 1656 | 07:42:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:42:39 INFO - PROCESS | 1656 | RSS: Main: 301355008 07:42:39 INFO - PROCESS | 1656 | 07:42:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:42:42 INFO - PROCESS | 1656 | RSS: Main: 319193088 07:42:42 INFO - PROCESS | 1656 | 07:42:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:42:46 INFO - PROCESS | 1656 | RSS: Main: 315293696 07:42:46 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 07:42:48 INFO - PROCESS | 1656 | RSS: Main: 358637568 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | __start_tp_report 07:42:48 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 07:42:48 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 07:42:48 INFO - PROCESS | 1656 | |i|pagename|runs| 07:42:48 INFO - PROCESS | 1656 | |0;ai-astar;113;113;128;112;112;112;111;112;113;113 07:42:48 INFO - PROCESS | 1656 | |1;audio-beat-detection;146;109;131;160;108;131;129;105;157;106 07:42:48 INFO - PROCESS | 1656 | |2;audio-dft;183;186;183;182;182;183;184;181;181;182 07:42:48 INFO - PROCESS | 1656 | |3;audio-fft;76;76;75;151;57;58;57;63;137;57 07:42:48 INFO - PROCESS | 1656 | |4;audio-oscillator;82;82;133;66;100;105;121;69;69;124 07:42:48 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;86;83;86;83;86;82;86;83;85;82 07:42:48 INFO - PROCESS | 1656 | |6;imaging-darkroom;92;92;92;92;91;92;92;93;92;97 07:42:48 INFO - PROCESS | 1656 | |7;imaging-desaturate;84;85;87;86;85;84;87;87;86;86 07:42:48 INFO - PROCESS | 1656 | |8;json-parse-financial;53;54;51;53;52;54;54;53;52;53 07:42:48 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;65;66;73;72;76;71;68;69;69;70 07:42:48 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;85;85;84;84;85;87;76;86;94;105 07:42:48 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;140;139;139;136;136;140;145;143;178;140 07:42:48 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;168;166;169;168;168;169;175;167;167;165 07:42:48 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;67;67;68;68;67;64;67;66;67 07:42:48 INFO - PROCESS | 1656 | __end_tp_report 07:42:48 INFO - PROCESS | 1656 | __start_cc_report 07:42:48 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1969 07:42:48 INFO - PROCESS | 1656 | __end_cc_report 07:42:48 INFO - PROCESS | 1656 | __startTimestamp1472136168268__endTimestamp 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | ------- Summary: start ------- 07:42:48 INFO - PROCESS | 1656 | Number of tests: 14 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:113.90 Median:113.00 stddev:5.00 (4.4%) stddev-sans-first:5.29 07:42:48 INFO - PROCESS | 1656 | Values: 113.0 113.0 128.0 112.0 112.0 112.0 111.0 112.0 113.0 113.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:131.00 stddev:21.03 (16.1%) stddev-sans-first:21.30 07:42:48 INFO - PROCESS | 1656 | Values: 146.0 109.0 131.0 160.0 108.0 131.0 129.0 105.0 157.0 106.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 07:42:48 INFO - PROCESS | 1656 | Values: 183.0 186.0 183.0 182.0 182.0 183.0 184.0 181.0 181.0 182.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:80.70 Median:60.50 stddev:34.48 (57.0%) stddev-sans-first:36.53 07:42:48 INFO - PROCESS | 1656 | Values: 76.0 76.0 75.0 151.0 57.0 58.0 57.0 63.0 137.0 57.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:67.50 stddev:24.98 (37.0%) stddev-sans-first:26.04 07:42:48 INFO - PROCESS | 1656 | Values: 82.0 82.0 133.0 66.0 100.0 105.0 121.0 69.0 69.0 124.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:84.20 Median:85.50 stddev:1.75 (2.0%) stddev-sans-first:1.73 07:42:48 INFO - PROCESS | 1656 | Values: 86.0 83.0 86.0 83.0 86.0 82.0 86.0 83.0 85.0 82.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:92.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 07:42:48 INFO - PROCESS | 1656 | Values: 92.0 92.0 92.0 92.0 91.0 92.0 92.0 93.0 92.0 97.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.16 (1.3%) stddev-sans-first:1.05 07:42:48 INFO - PROCESS | 1656 | Values: 84.0 85.0 87.0 86.0 85.0 84.0 87.0 87.0 86.0 86.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 07:42:48 INFO - PROCESS | 1656 | Values: 53.0 54.0 51.0 53.0 52.0 54.0 54.0 53.0 52.0 53.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:3.28 (4.7%) stddev-sans-first:2.96 07:42:48 INFO - PROCESS | 1656 | Values: 65.0 66.0 73.0 72.0 76.0 71.0 68.0 69.0 69.0 70.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:85.00 stddev:7.64 (9.0%) stddev-sans-first:8.06 07:42:48 INFO - PROCESS | 1656 | Values: 85.0 85.0 84.0 84.0 85.0 87.0 76.0 86.0 94.0 105.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:140.00 stddev:12.39 (8.9%) stddev-sans-first:13.08 07:42:48 INFO - PROCESS | 1656 | Values: 140.0 139.0 139.0 136.0 136.0 140.0 145.0 143.0 178.0 140.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:168.00 stddev:2.70 (1.6%) stddev-sans-first:2.86 07:42:48 INFO - PROCESS | 1656 | Values: 168.0 166.0 169.0 168.0 168.0 169.0 175.0 167.0 167.0 165.0 07:42:48 INFO - PROCESS | 1656 | 07:42:48 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.20 (1.8%) stddev-sans-first:1.20 07:42:48 INFO - PROCESS | 1656 | Values: 68.0 67.0 67.0 68.0 68.0 67.0 64.0 67.0 66.0 67.0 07:42:48 INFO - PROCESS | 1656 | -------- Summary: end -------- 07:42:48 INFO - PROCESS | 1656 | 07:42:49 INFO - TEST-INFO | 1656: exit 0 07:42:49 INFO - TEST-OK | kraken | took 58489ms 07:42:49 INFO - SUITE-END | took 1176s 07:42:49 INFO - Completed test suite (00:19:36) 07:42:49 INFO - javascript benchmark 07:42:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.4180518409275, 3325.800451972882, 3361.4621660534303, 3390.4236279832426, 3436.0894362993813, 5576.470235327637, 5669.546436285098, 5688.118341657039, 5778.0, 5788.50797682197, 5761.337446193689, 5779.595428320019, 5786.421357864211, 5793.9281232971925, 5795.15970184323, 5149.665271757333, 5178.145605975028, 5215.061288967984, 5232.215167724834, 5275.630705850573, 5250.238715386266, 5260.210968354739, 5260.421353651095, 5263.0, 5264.763085661154, 5160.8709782255555, 5161.612879034087, 5171.250168725533, 5182.844514664566, 5266.736663166846, 5247.737613119329, 5252.6848389096585, 5260.342457192851, 5289.312389389354, 5360.544353729938, 5080.110980578384, 5103.1324674805, 5121.487851214886, 5145.30538377318, 5219.138842091051, 5631.831045068655, 5660.547156227533, 5676.659400435987, 5731.770729170828, 5805.82582522525, 6075.75696972125, 6100.793122958898, 6291.62250264981, 6302.212223472066, 6315.0, 4999.550040496373, 6228.439440450381, 6302.369763023707, 6321.494280457552, 6323.241211054657, 4902.754862256944, 5501.752421141058, 5768.077107662796, 5818.447247511522, 5837.474627283565, 6207.006878899354, 6259.593126446766, 6301.369863013662, 6340.746370145235, 6353.555251132469, 6122.54080943931, 6135.2326666699655, 6142.846428839315, 6150.0, 6161.630302181794, 5380.7309634518115, 6243.594166379262, 6293.181886354744, 6306.211723534559, 6312.621242725451, 6008.218931538872, 6033.9698301508215, 6248.0, 6248.656323902138, 6250.656213908188, 5582.385937546866, 6107.674343494518, 6114.174586430864, 6130.386961303834, 6166.445019948136, 4841.540053694894, 4843.8304659337455, 4848.10310092634, 4871.853844384674, 4878.756062196876, 4361.084172323847, 4368.497622773421, 4389.341598760211, 4396.626286765597, 4411.845585404495, 5563.972180139154, 5591.10542313228, 5628.549716022709, 5695.117256825199, 5704.258446401941, 6235.719392627343, 6247.156633854463, 6257.655828929362, 6259.499240060784, 6264.686765661699, 5624.85937851557, 5692.459216374438, 5694.0, 5697.572682048863, 5704.087346024616, 5379.811706590251, 5397.784088636489, 5447.319085114361, 5448.455154484599, 5450.727463626803, 6306.936930630818, 6341.175647165839, 6346.873062538723, 6348.428641422294, 6358.205224346864, 6101.938980610137, 6292.370762923671, 6304.621722696653, 6314.463270622048, 6319.210098737658, 6260.217472815898, 6263.091851681572, 6285.025820997753, 6347.206599175103, 6351.872962540816, 6144.53915956314, 6169.96915015422, 6178.969105154445, 6196.194494715658, 6208.534359922934, 5147.176451767699, 5873.794417195378, 5942.0, 5982.670953097448, 6276.6547839869245, 5997.940020599913, 6004.0, 6069.4840938518955, 6116.1836049259855, 6121.696863452088, 6077.5745697802495, 6080.300765411857, 6090.421409966046, 6121.969390153021, 6128.571000029955, 5781.132830075522, 5936.703164841654, 5952.76188952437, 5963.224780778471, 5970.134330522137, 6009.969950150221, 6041.214642096499, 6084.726187321669, 6108.038540470994, 6128.417800308964, 5905.52755779528, 5925.05199168131, 5946.583739138218, 5953.226080609493, 5954.553408494467], "value": 5669.679312529432, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.88222003389143, 529.9841004769853, 532.6883772992799, 535.1865164949278, 551.5890661457213, 30573.388532229368, 32380.190495237603, 32413.837930810314, 32421.51367729486, 32624.184395390126, 32456.513152302734, 41477.17045659085, 41547.0, 41625.58374416255, 43984.780076099654, 9629.903700962988, 9765.609375624967, 9771.462569558671, 9771.75570610737, 9789.559469823875, 9672.468014259213, 9756.512174391251, 9757.560909759079, 9760.316777825556, 9768.0, 9730.805383892319, 9774.31579789415, 9777.12005919467, 9787.265955053364, 9795.559199836025, 9656.613735450574, 9733.0, 9736.75658108546, 9737.026297370241, 9758.414495130315, 9633.951830240874, 9710.563024663836, 9722.270829687732, 9726.027397260288, 9730.951345243298, 40014.599854001666, 40094.59905400938, 40127.39808902839, 40173.995650108984, 40225.0, 3326.933461330784, 3437.5874895012507, 3443.9827800861085, 3445.707114895212, 3550.3076900004507, 25903.74096259051, 30590.541141882888, 31715.841420792978, 31840.363192736015, 31852.681473185436, 3266.836658167082, 3340.3820293245826, 3406.7785593936314, 3427.246005878703, 3448.448248280263, 505905.0, 507488.0, 508639.0, 508710.0, 517216.0, 56596.43403565994, 56915.715421422625, 56920.715396422755, 56980.71509642508, 56981.0, 58728.11907821386, 59398.0, 59611.70194149001, 59789.103163452586, 59925.10112348318, 15757.0, 16006.119663418624, 16007.599810004844, 16021.519354419386, 16077.874548781472, 12458.0033597312, 12505.56230531927, 12527.498900044078, 12532.874671253354, 12541.372931353395, 15322.770158447633, 15491.612709682122, 15499.5350139496, 15525.844741552666, 15733.0, 5539.972300138474, 5541.750621222055, 6068.302145253264, 6075.0, 6077.939220607737, 966.6761634852263, 976.1458723616835, 990.272149969786, 1003.7390278527489, 1084.2735367303767, 29171.53206692127, 29372.26452425233, 29623.851880740458, 29662.0, 29825.254368640522, 864.6195673903588, 1014.9238807089498, 1052.3738375666467, 1067.3435836960214, 1108.451316598289, 13114.213147211198, 15093.0, 15236.619084522976, 15356.616084597974, 15477.0, 671272.0, 673067.0, 675865.6206719034, 676442.0, 679267.0, 49543.256851147264, 57487.71256143692, 57782.71108644514, 57884.0, 57975.71012144996, 1265.7215412609212, 1362.734266817961, 1398.5734351023036, 1408.8732014118777, 1419.2974477633638, 30261.394772104875, 30813.691863081083, 30884.382312354075, 31604.683953160173, 31621.683783162793, 49396.259056114905, 54015.72992135014, 56082.439175607724, 56191.43808561862, 59815.70092149511, 627731.0, 628382.0, 633848.0, 647215.0, 649805.0, 22475.43811404728, 22883.771162288165, 25589.744102558736, 25704.0, 26303.868480657475, 46805.29792053054, 46952.0, 47041.764791175825, 47315.0, 48128.0, 29435.0, 39011.0, 39025.0, 39147.80426097851, 40651.79674101611, 38759.80620096994, 39053.609463905, 39186.804065979486, 39479.80260098681, 39508.0, 37572.436413454365, 37911.43132853065, 38295.04262393463, 38338.42492362673, 38362.61637383702, 32523.0, 34395.14012149716, 34684.12634946042, 34704.96777967231, 35081.0, 24949.87725552391, 25024.874875625505, 25037.749622503543, 25427.0, 25693.743062569883], "value": 19669.50000276905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.74614412262866, 354.78890060414045, 358.4998926497536, 368.120192739353, 385.35260761919966, 15666.21668916554, 17352.392666256685, 20273.03890704484, 20511.53861536614, 20582.794172058293, 20708.585828283427, 20743.896280518577, 20988.58022839542, 21148.682769758427, 21591.244306449276, 5160.664556803796, 5693.089105743093, 5894.115882617621, 5937.079752638348, 5973.596204891869, 4189.539150693437, 4393.406890069848, 5777.740001699934, 5976.940230597704, 6370.140031095812, 6562.573432726881, 6636.73453061877, 6655.301193374675, 6660.067590537328, 6680.899786503206, 6300.7794727184555, 6462.127612772286, 6513.576617519846, 6514.93485065148, 6524.706388212542, 5406.8918621627745, 5624.100143976944, 5631.662100273956, 5638.887222255532, 5666.829995100154, 26552.73447265522, 26594.33514162142, 26667.733322666914, 26667.866660666765, 26951.730482695315, 2170.3597438755446, 2262.7963483286585, 2268.6823844661762, 2357.2221167014845, 2368.7631236876346, 9915.801683966352, 10116.190704743603, 10397.116245119174, 13092.345382730826, 14724.0, 1589.4996093015977, 1821.353419536059, 1886.5377982394402, 1907.1513176636402, 1921.6733155363622, 110639.44680276628, 113663.0, 123083.0, 123355.9999999991, 128664.356678216, 10313.896861031293, 11120.944395277971, 11124.49939752713, 11272.89995251296, 11281.71795705114, 14173.0, 14175.007749457645, 14216.147031177956, 14232.43070277198, 14251.287435628177, 8099.757007289791, 8411.957940210383, 8433.493990360597, 8487.660493580188, 8535.274501667303, 8357.373197010249, 8375.45559538628, 8382.413231073766, 8547.871781923277, 8566.0, 3822.847086116524, 4314.654827613784, 4388.319810429389, 4397.362382454527, 4482.148391805546, 1713.2376092638656, 1754.6578417208777, 1800.1809176824513, 1811.5742800441885, 1847.288793814391, 860.8622620380709, 863.87905693203, 871.4771137317558, 891.3003292415423, 937.3579098317674, 21187.046582903808, 21792.455188620093, 21905.561888762135, 21977.0, 21994.780052199272, 814.2142832166869, 921.576075005505, 935.9485228312373, 953.4898829126384, 959.1990687775645, 8912.598933047899, 9886.851697224547, 10691.679249622524, 11095.056920161715, 11319.603813866475, 590080.0, 590512.0, 595406.0, 606956.0, 614066.9296653578, 131124.3443782794, 131322.0, 131522.0, 132160.0, 134508.3274583621, 954.8949615542285, 961.9807603847884, 982.729749318943, 985.3102828020271, 1008.7377281906757, 18276.268949242414, 18811.71782423292, 18876.528086797938, 18893.622127557097, 18973.715394269373, 45859.54140458686, 53092.93814123618, 53599.0, 53899.73050134724, 54703.70907345547, 77734.83397748925, 79255.0, 79701.60149199217, 79723.0, 80138.59930700542, 7046.4715146363205, 7322.853542929005, 7341.15576708664, 7420.0, 8053.758387248392, 10615.938406159323, 11035.83446248323, 12969.870301296865, 13412.463501459832, 13457.596272111852, 11217.775644486901, 11277.939941620845, 11533.077353811843, 11663.24188927717, 11677.591284305008, 9595.952020239854, 10725.624603138853, 11003.22977391575, 11514.251573647685, 11609.76780464369, 10321.535530900977, 10925.672229833117, 11431.59989400367, 11706.76586468283, 11937.403129843648, 6755.966220168868, 6860.348266914635, 7969.123396426273, 8066.0, 8322.20939010793, 10745.301555398873, 11648.0, 11684.70788230301, 11692.649220523397, 11702.122340824642], "value": 9525.825145252715, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.8354580996092, 1531.6324082220267, 1681.1846254566533, 1811.5652243461573, 1855.5175654329878, 7815.25755053269, 8083.191680831914, 8404.831903361937, 8818.382713210078, 8831.0, 8424.368172387065, 8696.260817830493, 8709.25971292441, 8771.649134034647, 8778.604962776675, 2366.5858474766933, 2397.61638137898, 2398.040783686522, 2403.098837935774, 2403.7956773674173, 2100.3173968460237, 2288.3592594073684, 2366.089055713554, 2366.3374255208482, 2380.381100913766, 2369.786719195272, 2395.173665085543, 2395.9041638334447, 2398.760123987596, 2404.362843846382, 2306.238941149425, 2341.098677009347, 2346.21401830387, 2353.917612883558, 2370.5495955768347, 2342.8125749939963, 2350.847194932324, 2362.11420717231, 2384.0940442631863, 2387.5583017141885, 8828.117188281185, 8914.687985920527, 8926.2412694921, 8928.598213080362, 8933.106689331014, 4521.864344069683, 5189.2475591039465, 5350.30446042016, 5354.196870469422, 5375.784968601251, 3525.083478295636, 3547.94678079829, 3627.7097832173363, 3628.1473853644375, 3637.5998640149564, 1020.8009438159562, 1030.469308306223, 1033.529743966501, 1041.4688508860536, 1800.4418630224673, 11454.885451145465, 11480.483378247998, 11499.022583080448, 11519.769604607945, 11522.942385288105, 3984.880453586397, 4186.995121170958, 4442.555744425532, 4514.616257618073, 4590.954090459053, 7411.9617105296475, 8617.95691021541, 8618.353623478264, 8628.52543110135, 8647.048824629286, 5040.823571174991, 5050.318207042076, 5051.444341122474, 5051.898962020739, 5059.822906198266, 4611.76941152941, 4643.489216186217, 4727.361806156194, 4731.550502702238, 4740.454847692559, 3427.3830710471857, 3572.6427357264065, 3645.8177091145867, 3760.1163726524246, 3772.0192749884677, 1050.564015933379, 1056.334509259162, 1087.5867170475326, 1113.1206346985953, 1123.27548731068, 844.0293662288295, 858.1332853817564, 888.4329417389188, 906.6328137104483, 950.7670620697974, 5654.6324488908085, 5717.714114294268, 6005.069214271794, 6012.30858451275, 6125.0, 941.7268991992246, 956.6699488676395, 970.3595626886221, 973.4208146153028, 992.3252188511881, 3647.3434781739543, 3869.157204414463, 4032.3145065338376, 4081.469408976814, 4104.56902025283, 10494.160467162608, 10759.408232547128, 10848.132149428027, 10860.891391086147, 10864.511097000654, 10076.244281678757, 10599.682009539727, 10782.137429005661, 10808.135349172047, 10838.0, 7909.24862138096, 8129.471584347117, 8133.0, 8140.430169888051, 8152.144024877421, 5844.327902291291, 5861.0, 5922.940770592411, 6136.987397079611, 6297.921685843569, 9491.572879220314, 9878.901210987799, 9913.256505761952, 9936.056074672895, 9939.503024848873, 10021.99780021992, 10038.598456061676, 10063.345882517613, 10066.597336106766, 10079.647212347532, 7458.888116678146, 7554.395648348009, 7601.353884919844, 7621.771346859603, 7653.9234607655435, 8244.0, 8318.0, 8427.536485493112, 8434.156584341517, 8435.704750333709, 6988.196357418758, 7530.5858177798555, 7613.885791713239, 7723.034620672416, 7786.961065194638, 7298.525595836254, 7412.555246685, 7416.7774966751085, 7430.517016393917, 7512.924870751222, 6945.0971373721095, 6956.756513522002, 7018.508704390848, 7022.367986881205, 7026.824329391806, 4601.19479091151, 4703.659457054723, 4943.752812359439, 5003.499650034967, 5134.897302053863, 4164.292070348109, 4799.784009719641, 5018.774155162953, 5031.723255220998, 5123.615728820428], "value": 4536.965384251717, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.63841345289686, 266.88657320638725, 288.7718702225242, 302.19615821913715, 306.38416782267643, 4216.430781844454, 5016.799328026875, 5208.453112423198, 5246.344206974129, 5260.684358938457, 4850.9029819403595, 5159.742012899359, 5169.30214421053, 5205.583553315735, 5235.240890070947, 3740.4202348636004, 3862.9420558691572, 3921.803849825802, 3990.06233535119, 4008.9175922501054, 3557.146284891633, 3616.987243571804, 3939.428782826503, 3970.3845903884944, 4009.6992725545556, 3463.6709512596262, 3510.8420121094487, 3895.7662540247534, 3912.8434862605463, 3949.2496425679174, 3210.7752457328115, 3373.730101591867, 3728.881335599323, 3914.275858966072, 3933.3903244997073, 2911.839848808315, 3314.685104915029, 3395.439752440844, 3404.7446441516986, 3422.7946323220685, 3950.3876899080688, 4494.9325760113625, 4804.903901921942, 5081.23781432788, 5141.76862041209, 4086.366613174963, 4336.34954756789, 4515.096980603892, 4532.796024178887, 4547.681662283641, 5378.0, 5384.0, 5399.460053994609, 5435.239066530688, 5456.781728730846, 3281.819499927503, 3322.7175690066374, 3735.477033215352, 3750.5124333836425, 3763.6424539668687, 4763.595094416979, 4999.975000125012, 5176.21420609889, 5725.799597014085, 5740.6268592541355, 3754.75594086389, 3793.943090853639, 3794.0135564753364, 3800.448934904479, 3804.5434547854434, 3461.745958798721, 3743.5882052974152, 3802.923941521154, 3845.1732877431486, 3856.749311294757, 3863.903402414906, 3986.5614782373914, 4119.629233369011, 4510.368548403226, 4517.62212525183, 4977.850664479999, 5107.821226257138, 5157.561607263349, 5424.620276580601, 5474.863128421742, 1173.3898372846072, 1174.8531433570804, 1299.3373379576485, 1313.9277339746216, 1324.735052989406, 738.3723834740427, 773.9303462688385, 809.5102463009795, 817.8364327134597, 826.7643721539332, 4372.660626564772, 4566.109608626286, 4693.249080147229, 4869.61388919434, 5195.272481947008, 4983.227599722049, 5220.817271395559, 5581.832545023656, 5606.6916319602815, 5647.49172574462, 4309.806058727365, 4544.409226800495, 4842.215725957589, 5003.324551185616, 5134.974325128351, 2745.0, 2928.428956353533, 2935.2515108647585, 2936.5595160725634, 2949.0857834071076, 5038.875896943919, 5709.286339207599, 5761.711914404347, 5839.634947846129, 5844.298684157929, 4640.489546149921, 4952.925706114411, 5233.476652334736, 5373.543248823894, 5492.697901615449, 5290.7831198823715, 5393.406725260218, 5488.615796894259, 5502.147167189012, 5789.536837052941, 4720.598749106227, 5395.32931199059, 5593.972030139823, 5605.635633683797, 5672.858178545569, 5209.431539395526, 5567.248421463212, 5650.519705825051, 5691.288588926384, 5731.914021289767, 5294.120587191492, 5895.29256489224, 5904.439078287556, 5925.0, 5940.524758019435, 3358.7648864579246, 3673.7428380013143, 3784.924301513899, 3812.8474861005247, 3823.4264860271182, 5041.647084704182, 5055.191169412877, 5461.641090729252, 5697.401772813961, 5698.572607054571, 4742.834000809955, 5506.697131657797, 5534.280543529477, 5607.214989901336, 5607.775688972395, 4492.191405547033, 4926.241304869284, 5155.922661160161, 5631.633943793804, 5662.688552129673, 5427.232111623021, 5442.292501974718, 5470.456237849419, 5484.369297530835, 5510.016394098202, 4434.733915965053, 5333.359996800254, 5550.639208451437, 5581.609287350009, 5594.944050559442, 5380.730963451733, 5400.270963419888, 5439.728013599225, 5459.7270136492225, 5484.945150548603], "value": 3939.773613441238, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.5822152262449, 746.4540945641045, 759.0663483914784, 807.2290962131166, 832.9916700832997, 15231.771523427158, 15993.92003039986, 16005.199740013011, 16045.91977040116, 16136.677266454679, 12306.0, 12826.294553799537, 14496.927515362408, 14757.0, 14761.926190369033, 860.1914200651383, 877.3770622857778, 886.7295474880159, 887.2901678657048, 890.8663700444953, 853.0403296291672, 853.2150421612132, 855.8288342331558, 857.1086070486687, 859.7420773767876, 720.7801620505744, 745.4520927118564, 896.5427631907742, 898.3980732908967, 900.693764120199, 734.5629350536423, 735.8565079809413, 885.4775682347446, 919.2278486071732, 923.007766650853, 682.3381320119477, 682.757621044527, 683.5522732610149, 683.9931600684029, 684.4797953555284, 14786.704265914621, 14828.851711482856, 14830.851691483054, 14833.55499335011, 14851.925740371336, 14039.368228429745, 14331.641708957255, 14343.497977570734, 14421.0, 14454.927725361411, 12985.0, 13044.673883152902, 13090.214587124803, 13110.672233194151, 13253.801192982113, 4662.580367766917, 4814.2537906624175, 4851.151048566522, 4861.246506791453, 4884.95115048852, 83825.58087209525, 105359.47320263427, 106430.0, 106777.44949532834, 109132.0, 9219.939706933654, 11111.444427778579, 11159.163062770325, 11297.0, 11322.207445478738, 18653.81346186548, 22137.0, 22229.221977230718, 22332.88833555822, 22599.32202033909, 7791.883121753178, 7807.414443916729, 7907.288344048948, 7951.642176102089, 8096.352291816758, 4372.3878656988045, 4389.978050109729, 4419.337099435111, 4434.113177364476, 4531.410916580823, 3667.3765459871884, 3819.388897776342, 3825.1202223488754, 3828.8085595720104, 3829.7319187657154, 689.2315068698365, 707.2079271216271, 710.8222944263935, 712.9322714342129, 715.0382735221119, 660.3132741948416, 680.8570200258001, 682.4574463301688, 682.8600136971909, 691.1913061717802, 8258.876116858251, 8530.701425450203, 8937.865932011026, 9182.127697868691, 9622.663206787729, 585.772805971489, 632.7152781248457, 658.1214079204316, 662.8873091574444, 689.6551724137931, 3930.6265904739003, 4525.456945166601, 4661.277501987197, 4685.367475390779, 4770.737609431445, 160355.0, 163944.0, 168550.9311336083, 169164.61670766762, 172986.16110711114, 138697.61302387048, 140457.5954240465, 142492.0, 145435.09129817344, 147919.26040369945, 73781.0, 119244.21133683002, 124659.0, 124742.0, 129262.99999999812, 9680.0, 9681.757956051155, 9783.706488805346, 9892.258080644124, 9916.454594997344, 55999.0, 60307.0, 61201.0, 64635.70728585496, 70907.64546177236, 71292.57414851777, 72998.0, 91863.0, 98351.0, 98516.0, 13469.0, 13998.970149328086, 14004.969279454626, 14080.706366725284, 14445.422183112978, 15062.171580563174, 15204.239788010334, 15688.0, 15764.369425222863, 16306.510804675878, 14442.927785361006, 14565.941152358124, 14787.038842475205, 14825.0, 14843.40626374933, 14379.784303235669, 14425.278736063365, 14459.060161089496, 14593.85406145925, 15100.169490678118, 12858.807117893426, 14009.369578369202, 14133.293335333397, 14344.784828227375, 14441.35013924397, 11153.330800151693, 11210.383428911487, 11288.492017859051, 11541.339782633262, 11670.94164529172, 9994.350367226107, 10963.616273430393, 11273.830892536454, 11593.24643898144, 11938.880611193777], "value": 8043.478976505937, "unit": "score"}], "name": "dromaeo_css", "value": 7311.34283132579, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 128.0, 112.0, 112.0, 112.0, 111.0, 112.0, 113.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 131.0, 160.0, 108.0, 131.0, 129.0, 105.0, 157.0, 106.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 183.0, 182.0, 182.0, 183.0, 184.0, 181.0, 181.0, 182.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 151.0, 57.0, 58.0, 57.0, 63.0, 137.0, 57.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 133.0, 66.0, 100.0, 105.0, 121.0, 69.0, 69.0, 124.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 86.0, 82.0, 86.0, 83.0, 85.0, 82.0], "value": 84.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 91.0, 92.0, 92.0, 93.0, 92.0, 97.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 86.0, 85.0, 84.0, 87.0, 87.0, 86.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 51.0, 53.0, 52.0, 54.0, 54.0, 53.0, 52.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 73.0, 72.0, 76.0, 71.0, 68.0, 69.0, 69.0, 70.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 85.0, 87.0, 76.0, 86.0, 94.0, 105.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 136.0, 136.0, 140.0, 145.0, 143.0, 178.0, 140.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 169.0, 168.0, 168.0, 169.0, 175.0, 167.0, 167.0, 165.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 64.0, 67.0, 66.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1451.6, "alertThreshold": 2.0}]} 07:42:49 INFO - Return code: 0 07:42:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:42:49 INFO - # TBPL SUCCESS # 07:42:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:42:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:42:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:42:49 INFO - 2016-08-25 07:42:49.619 screenresolution[1660:9794] starting screenresolution argv=screenresolution get 07:42:49 INFO - 2016-08-25 07:42:49.621 screenresolution[1660:9794] Display 0: 1600x1200x32@60 07:42:49 INFO - 2016-08-25 07:42:49.627 screenresolution[1661:9797] starting screenresolution argv=screenresolution list 07:42:49 INFO - Available Modes on Display 0 07:42:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:42:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:42:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:42:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:42:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:42:49 INFO - Intel Iris: 07:42:49 INFO - Chipset Model: Intel Iris 07:42:49 INFO - Type: GPU 07:42:49 INFO - Bus: Built-In 07:42:49 INFO - VRAM (Dynamic, Max): 1536 MB 07:42:49 INFO - Vendor: Intel (0x8086) 07:42:49 INFO - Device ID: 0x0a2e 07:42:49 INFO - Revision ID: 0x0009 07:42:49 INFO - Displays: 07:42:49 INFO - Display: 07:42:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:42:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:42:49 INFO - Display Serial Number: 1600x1200 60 07:42:49 INFO - Main Display: Yes 07:42:49 INFO - Mirror: Off 07:42:49 INFO - Online: Yes 07:42:49 INFO - Rotation: Supported 07:42:49 INFO - Return code: 0 07:42:49 INFO - Running post-action listener: _resource_record_post_action 07:42:49 INFO - [mozharness: 2016-08-25 14:42:49.859522Z] Finished run-tests step (success) 07:42:49 INFO - Running post-run listener: _resource_record_post_run 07:42:49 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31583232; Write bytes: 703157760; Read time: 194; Write time: 2002 07:42:49 INFO - TinderboxPrint: CPU usage
54.0% 07:42:49 INFO - TinderboxPrint: I/O read bytes / time
31,583,232 / 194 07:42:49 INFO - TinderboxPrint: I/O write bytes / time
703,157,760 / 2,002 07:42:49 INFO - TinderboxPrint: CPU idle
2,228.7 (46.0%) 07:42:49 INFO - TinderboxPrint: CPU system
977.8 (20.2%) 07:42:49 INFO - TinderboxPrint: CPU user
1,635.1 (33.8%) 07:42:49 INFO - TinderboxPrint: Swap in / out
433,766,400 / 0 07:42:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384649728; Write bytes: 379237888; Read time: 28246; Write time: 826 07:42:49 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30657536; Write bytes: 294379520; Read time: 182; Write time: 1107 07:42:50 INFO - Running post-run listener: _upload_blobber_files 07:42:50 INFO - Blob upload gear active. 07:42:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:42:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:42:50 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:42:50 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:42:50 INFO - (blobuploader) - INFO - Open directory for files ... 07:42:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:42:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:42:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:51 INFO - (blobuploader) - INFO - Done attempting. 07:42:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:42:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:42:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:52 INFO - (blobuploader) - INFO - Done attempting. 07:42:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:42:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:42:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:55 INFO - (blobuploader) - INFO - Done attempting. 07:42:55 INFO - (blobuploader) - INFO - Iteration through files over. 07:42:55 INFO - Return code: 0 07:42:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:42:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:42:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cac86b3d3ebf3542506de1e6d7775b1ba9382bef3d98118b00e1ae6220b86b6fb5440fec4592c40fc2c3718e05eaf13b6c7f474bed3343448a3f3b7209edc0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f60c1514557b27da09afd27beccbc62c8a448c71c60aa37d086ae5a663d54a4b59250cdfe8665bbce4e6dfbebbd265a0136e9319f6a31d6e8322762465a73bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c82e5fcf84844c87cf926257876b304580f475bea2e086221dc3092c77e79e1a6d17d5046163fdb2e9f5784c108fa0b0068f8711674448a8eee625525203918"} 07:42:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:42:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:42:55 INFO - Contents: 07:42:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cac86b3d3ebf3542506de1e6d7775b1ba9382bef3d98118b00e1ae6220b86b6fb5440fec4592c40fc2c3718e05eaf13b6c7f474bed3343448a3f3b7209edc0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f60c1514557b27da09afd27beccbc62c8a448c71c60aa37d086ae5a663d54a4b59250cdfe8665bbce4e6dfbebbd265a0136e9319f6a31d6e8322762465a73bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c82e5fcf84844c87cf926257876b304580f475bea2e086221dc3092c77e79e1a6d17d5046163fdb2e9f5784c108fa0b0068f8711674448a8eee625525203918"} 07:42:55 INFO - Running post-run listener: copy_logs_to_upload_dir 07:42:55 INFO - Copying logs to upload dir... 07:42:55 INFO - mkdir: /builds/slave/test/build/upload/logs 07:42:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.884263 ========= master_lag: 5.25 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-25 07:43:00.675229) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 07:43:00.676521) ========= 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.wuX8Btumz3/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.c7T2Stm5uI/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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/Fx-Team/sha512/6cac86b3d3ebf3542506de1e6d7775b1ba9382bef3d98118b00e1ae6220b86b6fb5440fec4592c40fc2c3718e05eaf13b6c7f474bed3343448a3f3b7209edc0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f60c1514557b27da09afd27beccbc62c8a448c71c60aa37d086ae5a663d54a4b59250cdfe8665bbce4e6dfbebbd265a0136e9319f6a31d6e8322762465a73bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c82e5fcf84844c87cf926257876b304580f475bea2e086221dc3092c77e79e1a6d17d5046163fdb2e9f5784c108fa0b0068f8711674448a8eee625525203918"} build_url:https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010906 build_url: 'https://queue.taskcluster.net/v1/task/Xs6xEbrBQMqdvpDwGlMCiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cac86b3d3ebf3542506de1e6d7775b1ba9382bef3d98118b00e1ae6220b86b6fb5440fec4592c40fc2c3718e05eaf13b6c7f474bed3343448a3f3b7209edc0e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f60c1514557b27da09afd27beccbc62c8a448c71c60aa37d086ae5a663d54a4b59250cdfe8665bbce4e6dfbebbd265a0136e9319f6a31d6e8322762465a73bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c82e5fcf84844c87cf926257876b304580f475bea2e086221dc3092c77e79e1a6d17d5046163fdb2e9f5784c108fa0b0068f8711674448a8eee625525203918"}' ========= master_lag: 0.41 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 07:43:01.100960) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 12 secs) (at 2016-08-25 07:43:01.101320) ========= 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.wuX8Btumz3/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.c7T2Stm5uI/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005448 ========= master_lag: 12.32 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 12 secs) (at 2016-08-25 07:43:13.422935) ========= ========= Started reboot skipped (results: 3, elapsed: 7 secs) (at 2016-08-25 07:43:13.423239) ========= ========= Finished reboot skipped (results: 3, elapsed: 7 secs) (at 2016-08-25 07:43:20.885334) ========= ========= Total master_lag: 18.63 =========