builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0235 starttime: 1471976566.82 results: success (0) buildid: 20160823003946 builduid: 2559179a43d141f1a5ded0a2cbb1bbd4 revision: 35416a1541ae9860846ec93081d12b7e96c930c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:46.824669) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:46.825579) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:46.826177) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:46.855781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:46.856509) ========= 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.StmgNpbdHq/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.5baC9gqFHb/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-23 11:22:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.3M=0.001s 2016-08-23 11:22:46 (19.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139686 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:47.021306) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:47.021685) ========= 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.StmgNpbdHq/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.5baC9gqFHb/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.026117 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:47.071039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 11:22:47.071453) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 35416a1541ae9860846ec93081d12b7e96c930c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 35416a1541ae9860846ec93081d12b7e96c930c3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StmgNpbdHq/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.5baC9gqFHb/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-23 11:22:47,151 truncating revision to first 12 chars 2016-08-23 11:22:47,152 Setting DEBUG logging. 2016-08-23 11:22:47,152 attempt 1/10 2016-08-23 11:22:47,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/35416a1541ae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 11:22:48,451 unpacking tar archive at: autoland-35416a1541ae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.564472 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 11:22:48.660308) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:48.660893) ========= script_repo_revision: 35416a1541ae9860846ec93081d12b7e96c930c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:48.661335) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:48.661812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 11:22:48.681701) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-23 11:22:48.682377) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StmgNpbdHq/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.5baC9gqFHb/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:22:48 INFO - MultiFileLogger online at 20160823 11:22:48 in /builds/slave/test 11:22:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:22:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:22:48 INFO - {'append_to_log': False, 11:22:48 INFO - 'base_work_dir': '/builds/slave/test', 11:22:48 INFO - 'blob_upload_branch': 'Autoland', 11:22:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:22:48 INFO - 'branch': 'Autoland', 11:22:48 INFO - 'buildbot_json_path': 'buildprops.json', 11:22:48 INFO - 'config_files': ('talos/mac_config.py',), 11:22:48 INFO - 'default_actions': ('clobber', 11:22:48 INFO - 'read-buildbot-config', 11:22:48 INFO - 'download-and-extract', 11:22:48 INFO - 'populate-webroot', 11:22:48 INFO - 'create-virtualenv', 11:22:48 INFO - 'install', 11:22:48 INFO - 'run-tests'), 11:22:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:22:48 INFO - 'download_minidump_stackwalk': True, 11:22:48 INFO - 'download_symbols': 'ondemand', 11:22:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:22:48 INFO - 'tooltool.py': '/tools/tooltool.py', 11:22:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:22:48 INFO - '/tools/misc-python/virtualenv.py')}, 11:22:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:22:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:22:48 INFO - 'installer_path': 'installer.exe', 11:22:48 INFO - 'log_level': 'info', 11:22:48 INFO - 'log_name': 'talos', 11:22:48 INFO - 'log_to_console': True, 11:22:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:22:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:22:48 INFO - 'opt_config_files': (), 11:22:48 INFO - 'pip_index': False, 11:22:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:22:48 INFO - 'cmd': ('bash', 11:22:48 INFO - '-c', 11:22:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:22:48 INFO - 'enabled': True, 11:22:48 INFO - 'halt_on_failure': False, 11:22:48 INFO - 'name': 'check_screen_resolution'},), 11:22:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:22:48 INFO - 'cmd': ('bash', 11:22:48 INFO - '-c', 11:22:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:22:48 INFO - 'enabled': True, 11:22:48 INFO - 'halt_on_failure': False, 11:22:48 INFO - 'name': 'check_screen_resolution'},), 11:22:48 INFO - 'run_cmd_checks_enabled': True, 11:22:48 INFO - 'sps_profile': False, 11:22:48 INFO - 'sps_profile_interval': 0, 11:22:48 INFO - 'suite': 'dromaeojs', 11:22:48 INFO - 'system_bits': '32', 11:22:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:22:48 INFO - 'title': 't-yosemite-r7-0235', 11:22:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:22:48 INFO - 'use_talos_json': True, 11:22:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:22:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:22:48 INFO - 'work_dir': 'build'} 11:22:48 INFO - [mozharness: 2016-08-23 18:22:48.875828Z] Running clobber step. 11:22:48 INFO - Running pre-action listener: _resource_record_pre_action 11:22:48 INFO - Running main action method: clobber 11:22:48 INFO - rmtree: /builds/slave/test/build 11:22:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:22:51 INFO - Running post-action listener: _resource_record_post_action 11:22:51 INFO - [mozharness: 2016-08-23 18:22:51.001817Z] Finished clobber step (success) 11:22:51 INFO - [mozharness: 2016-08-23 18:22:51.001963Z] Running read-buildbot-config step. 11:22:51 INFO - Running pre-action listener: _resource_record_pre_action 11:22:51 INFO - Running main action method: read_buildbot_config 11:22:51 INFO - Using buildbot properties: 11:22:51 INFO - { 11:22:51 INFO - "project": "", 11:22:51 INFO - "product": "firefox", 11:22:51 INFO - "script_repo_revision": "production", 11:22:51 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:22:51 INFO - "repository": "", 11:22:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:22:51 INFO - "buildid": "20160823003946", 11:22:51 INFO - "pgo_build": "False", 11:22:51 INFO - "basedir": "/builds/slave/test", 11:22:51 INFO - "buildnumber": 115, 11:22:51 INFO - "slavename": "t-yosemite-r7-0235", 11:22:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:22:51 INFO - "platform": "yosemite_r7", 11:22:51 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:22:51 INFO - "branch": "autoland", 11:22:51 INFO - "repo_path": "integration/autoland", 11:22:51 INFO - "revision": "35416a1541ae9860846ec93081d12b7e96c930c3", 11:22:51 INFO - "stage_platform": "macosx64", 11:22:51 INFO - "builduid": "2559179a43d141f1a5ded0a2cbb1bbd4", 11:22:51 INFO - "slavebuilddir": "test" 11:22:51 INFO - } 11:22:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:22:51 INFO - Running post-action listener: _resource_record_post_action 11:22:51 INFO - [mozharness: 2016-08-23 18:22:51.005975Z] Finished read-buildbot-config step (success) 11:22:51 INFO - [mozharness: 2016-08-23 18:22:51.006114Z] Running download-and-extract step. 11:22:51 INFO - Running pre-action listener: _resource_record_pre_action 11:22:51 INFO - Running main action method: download_and_extract 11:22:51 INFO - mkdir: /builds/slave/test/build/tests 11:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:51 INFO - trying https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:22:52 INFO - Downloaded 1199 bytes. 11:22:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:22:52 INFO - Using the following test package requirements: 11:22:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:22:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:22:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:22:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'jsshell-mac.zip'], 11:22:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:22:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:22:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:22:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:22:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:22:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:22:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:22:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:22:52 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')]} 11:22:52 INFO - trying https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:22:52 INFO - Downloading https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:22:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:22:54 INFO - Downloaded 16353619 bytes. 11:22:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:23:00 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 11:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:00 INFO - trying https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:23:00 INFO - Downloading https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:23:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:23:01 INFO - Downloaded 16353619 bytes. 11:23:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:08 INFO - trying https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:23:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:23:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:23:10 INFO - Downloaded 14413981 bytes. 11:23:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:23:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:10 INFO - trying https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:23:10 INFO - Downloading https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/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 11:23:14 INFO - Downloaded 111536041 bytes. 11:23:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:23:14 INFO - mkdir: /builds/slave/test/properties 11:23:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:23:14 INFO - Writing to file /builds/slave/test/properties/build_url 11:23:14 INFO - Contents: 11:23:14 INFO - build_url:https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:23:15 INFO - Running post-action listener: _resource_record_post_action 11:23:15 INFO - Running post-action listener: set_extra_try_arguments 11:23:15 INFO - [mozharness: 2016-08-23 18:23:15.044999Z] Finished download-and-extract step (success) 11:23:15 INFO - [mozharness: 2016-08-23 18:23:15.045145Z] Running populate-webroot step. 11:23:15 INFO - Running pre-action listener: _resource_record_pre_action 11:23:15 INFO - Running main action method: populate_webroot 11:23:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:23:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:23:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:23:15 INFO - '--tppagecycles', 11:23:15 INFO - '7'], 11:23:15 INFO - 'tests': ['tsvgm']}}, 11:23:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:23:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:23:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:23:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:23:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:23:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:23:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['damp', 'tps']}, 11:23:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'tests': ['damp', 'tps']}, 11:23:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['dromaeo_dom']}, 11:23:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:23:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['basic_compositor_video']}, 11:23:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:23:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['a11yr', 11:23:15 INFO - 'ts_paint', 11:23:15 INFO - 'tpaint', 11:23:15 INFO - 'sessionrestore', 11:23:15 INFO - 'sessionrestore_no_auto_restore', 11:23:15 INFO - 'tabpaint']}, 11:23:15 INFO - 'other-e10s': {'tests': ['a11yr', 11:23:15 INFO - 'ts_paint', 11:23:15 INFO - 'tpaint', 11:23:15 INFO - 'sessionrestore', 11:23:15 INFO - 'sessionrestore_no_auto_restore', 11:23:15 INFO - 'tabpaint']}, 11:23:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:23:15 INFO - 'ts_paint', 11:23:15 INFO - 'tpaint', 11:23:15 INFO - 'sessionrestore', 11:23:15 INFO - 'sessionrestore_no_auto_restore', 11:23:15 INFO - 'tabpaint']}, 11:23:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:23:15 INFO - 'ts_paint', 11:23:15 INFO - 'tpaint', 11:23:15 INFO - 'sessionrestore', 11:23:15 INFO - 'sessionrestore_no_auto_restore', 11:23:15 INFO - 'tabpaint']}, 11:23:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['a11yr', 11:23:15 INFO - 'ts_paint', 11:23:15 INFO - 'tpaint', 11:23:15 INFO - 'sessionrestore', 11:23:15 INFO - 'sessionrestore_no_auto_restore', 11:23:15 INFO - 'tabpaint']}, 11:23:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['a11yr', 11:23:15 INFO - 'ts_paint', 11:23:15 INFO - 'tpaint', 11:23:15 INFO - 'sessionrestore', 11:23:15 INFO - 'sessionrestore_no_auto_restore', 11:23:15 INFO - 'tabpaint']}, 11:23:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['tsvgx', 11:23:15 INFO - 'tsvgr_opacity', 11:23:15 INFO - 'tart', 11:23:15 INFO - 'tscrollx', 11:23:15 INFO - 'cart']}, 11:23:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:23:15 INFO - 'tsvgr_opacity', 11:23:15 INFO - 'tart', 11:23:15 INFO - 'tscrollx', 11:23:15 INFO - 'cart']}, 11:23:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'talos_options': ['--disable-e10s'], 11:23:15 INFO - 'tests': ['tp5o']}, 11:23:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'tests': ['tp5o']}, 11:23:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'talos_options': ['--disable-e10s', 11:23:15 INFO - '--xperf_path', 11:23:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:23:15 INFO - 'tests': ['tp5n']}, 11:23:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:15 INFO - 'talos_options': ['--xperf_path', 11:23:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:23:15 INFO - 'tests': ['tp5n']}}, 11:23:15 INFO - 'talos.zip': {'path': '', 11:23:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:23:15 INFO - Running post-action listener: _resource_record_post_action 11:23:15 INFO - [mozharness: 2016-08-23 18:23:15.063957Z] Finished populate-webroot step (success) 11:23:15 INFO - [mozharness: 2016-08-23 18:23:15.064098Z] Running create-virtualenv step. 11:23:15 INFO - Running pre-action listener: _resource_record_pre_action 11:23:15 INFO - Running main action method: create_virtualenv 11:23:15 INFO - Creating virtualenv /builds/slave/test/build/venv 11:23:15 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 11:23:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:23:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:23:15 INFO - Using real prefix '/tools/python27' 11:23:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:23:15 INFO - Installing distribute.............................................................................................................................................................................................done. 11:23:18 INFO - Installing pip.................done. 11:23:18 INFO - Return code: 0 11:23:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:18 INFO - 'HOME': '/Users/cltbld', 11:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:18 INFO - 'LOGNAME': 'cltbld', 11:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:18 INFO - 'MOZ_NO_REMOTE': '1', 11:23:18 INFO - 'NO_EM_RESTART': '1', 11:23:18 INFO - 'PAGER': '/bin/cat', 11:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:18 INFO - 'PWD': '/builds/slave/test', 11:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:18 INFO - 'SHELL': '/bin/bash', 11:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:18 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:18 INFO - 'USER': 'cltbld', 11:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:18 INFO - 'XPC_FLAGS': '0x0', 11:23:18 INFO - 'XPC_SERVICE_NAME': '0', 11:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:19 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 11:23:19 INFO - Cleaning up... 11:23:19 INFO - Return code: 0 11:23:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:23: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')]} 11:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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')]} 11:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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', '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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:23: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 psutil>=3.1.1 11:23:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:19 INFO - 'HOME': '/Users/cltbld', 11:23:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:19 INFO - 'LOGNAME': 'cltbld', 11:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:19 INFO - 'MOZ_NO_REMOTE': '1', 11:23:19 INFO - 'NO_EM_RESTART': '1', 11:23:19 INFO - 'PAGER': '/bin/cat', 11:23:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:19 INFO - 'PWD': '/builds/slave/test', 11:23:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:19 INFO - 'SHELL': '/bin/bash', 11:23:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:19 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:19 INFO - 'USER': 'cltbld', 11:23:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:19 INFO - 'XPC_FLAGS': '0x0', 11:23:19 INFO - 'XPC_SERVICE_NAME': '0', 11:23:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:19 INFO - Downloading/unpacking psutil>=3.1.1 11:23: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 11:23: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 11:23: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 11:23: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 11:23: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 11:23: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 11:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:23:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:23:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:23:22 INFO - Installing collected packages: psutil 11:23:22 INFO - Running setup.py install for psutil 11:23:22 INFO - building 'psutil._psutil_osx' extension 11:23:22 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 11:23:22 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 11:23:22 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 11:23:23 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 11:23:23 INFO - building 'psutil._psutil_posix' extension 11:23:23 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 11:23:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:23:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:23:23 INFO - ^ 11:23:23 INFO - 1 warning generated. 11:23:23 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 11:23:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:23:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:23:23 INFO - Successfully installed psutil 11:23:23 INFO - Cleaning up... 11:23:23 INFO - Return code: 0 11:23:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:23: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')]} 11:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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')]} 11:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:23: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 mozsystemmonitor==0.3 11:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:23 INFO - 'HOME': '/Users/cltbld', 11:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:23 INFO - 'LOGNAME': 'cltbld', 11:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:23 INFO - 'MOZ_NO_REMOTE': '1', 11:23:23 INFO - 'NO_EM_RESTART': '1', 11:23:23 INFO - 'PAGER': '/bin/cat', 11:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:23 INFO - 'PWD': '/builds/slave/test', 11:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:23 INFO - 'SHELL': '/bin/bash', 11:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:23 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:23 INFO - 'USER': 'cltbld', 11:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:23 INFO - 'XPC_FLAGS': '0x0', 11:23:23 INFO - 'XPC_SERVICE_NAME': '0', 11:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:23: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 11:23: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 11:23: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 11:23: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 11:23: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 11:23: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 11:23:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:23:26 INFO - Installing collected packages: mozsystemmonitor 11:23:26 INFO - Running setup.py install for mozsystemmonitor 11:23:26 INFO - Successfully installed mozsystemmonitor 11:23:26 INFO - Cleaning up... 11:23:26 INFO - Return code: 0 11:23:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:26 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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:26 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 11:23:26 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 11:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:26 INFO - 'HOME': '/Users/cltbld', 11:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:26 INFO - 'LOGNAME': 'cltbld', 11:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:26 INFO - 'MOZ_NO_REMOTE': '1', 11:23:26 INFO - 'NO_EM_RESTART': '1', 11:23:26 INFO - 'PAGER': '/bin/cat', 11:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:26 INFO - 'PWD': '/builds/slave/test', 11:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:26 INFO - 'SHELL': '/bin/bash', 11:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:26 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:26 INFO - 'USER': 'cltbld', 11:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:26 INFO - 'XPC_FLAGS': '0x0', 11:23:26 INFO - 'XPC_SERVICE_NAME': '0', 11:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:26 INFO - Downloading/unpacking jsonschema==2.5.1 11:23: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 11:23: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 11:23: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 11:23: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 11:23: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 11:23: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 11:23:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:23:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:29 INFO - Downloading functools32-3.2.3-2.tar.gz 11:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:23:29 INFO - warning: no files found matching '*.txt' 11:23:29 INFO - no previously-included directories found matching 'build' 11:23:29 INFO - no previously-included directories found matching 'dist' 11:23:29 INFO - no previously-included directories found matching '.git*' 11:23:29 INFO - Installing collected packages: jsonschema, functools32 11:23:29 INFO - Running setup.py install for functools32 11:23:29 INFO - warning: no files found matching '*.txt' 11:23:29 INFO - no previously-included directories found matching 'build' 11:23:29 INFO - no previously-included directories found matching 'dist' 11:23:29 INFO - no previously-included directories found matching '.git*' 11:23:29 INFO - Successfully installed jsonschema functools32 11:23:29 INFO - Cleaning up... 11:23:29 INFO - Return code: 0 11:23:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:23:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:29 INFO - 'HOME': '/Users/cltbld', 11:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:29 INFO - 'LOGNAME': 'cltbld', 11:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:29 INFO - 'MOZ_NO_REMOTE': '1', 11:23:29 INFO - 'NO_EM_RESTART': '1', 11:23:29 INFO - 'PAGER': '/bin/cat', 11:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:29 INFO - 'PWD': '/builds/slave/test', 11:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:29 INFO - 'SHELL': '/bin/bash', 11:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:29 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:29 INFO - 'USER': 'cltbld', 11:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:29 INFO - 'XPC_FLAGS': '0x0', 11:23:29 INFO - 'XPC_SERVICE_NAME': '0', 11:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:23:30 INFO - Cleaning up... 11:23:30 INFO - Return code: 0 11:23:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:23:30 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')]} 11:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:30 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')]} 11:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:30 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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:30 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 11:23:30 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 11:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:30 INFO - 'HOME': '/Users/cltbld', 11:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:30 INFO - 'LOGNAME': 'cltbld', 11:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:30 INFO - 'MOZ_NO_REMOTE': '1', 11:23:30 INFO - 'NO_EM_RESTART': '1', 11:23:30 INFO - 'PAGER': '/bin/cat', 11:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:30 INFO - 'PWD': '/builds/slave/test', 11:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:30 INFO - 'SHELL': '/bin/bash', 11:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:30 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:30 INFO - 'USER': 'cltbld', 11:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:30 INFO - 'XPC_FLAGS': '0x0', 11:23:30 INFO - 'XPC_SERVICE_NAME': '0', 11:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:30 INFO - Downloading/unpacking blobuploader==1.2.4 11:23:30 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 11:23:30 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 11:23:30 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 11:23:30 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 11:23:30 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 11:23:30 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 11:23:32 INFO - Downloading blobuploader-1.2.4.tar.gz 11:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:23:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:23:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:34 INFO - Downloading docopt-0.6.1.tar.gz 11:23:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:23:34 INFO - Installing collected packages: blobuploader, requests, docopt 11:23:34 INFO - Running setup.py install for blobuploader 11:23:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:23:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:23:34 INFO - Running setup.py install for requests 11:23:34 INFO - Running setup.py install for docopt 11:23:34 INFO - Successfully installed blobuploader requests docopt 11:23:34 INFO - Cleaning up... 11:23:35 INFO - Return code: 0 11:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:35 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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:35 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 11:23:35 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 11:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:35 INFO - 'HOME': '/Users/cltbld', 11:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:35 INFO - 'LOGNAME': 'cltbld', 11:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:35 INFO - 'MOZ_NO_REMOTE': '1', 11:23:35 INFO - 'NO_EM_RESTART': '1', 11:23:35 INFO - 'PAGER': '/bin/cat', 11:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:35 INFO - 'PWD': '/builds/slave/test', 11:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:35 INFO - 'SHELL': '/bin/bash', 11:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:35 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:35 INFO - 'USER': 'cltbld', 11:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:35 INFO - 'XPC_FLAGS': '0x0', 11:23:35 INFO - 'XPC_SERVICE_NAME': '0', 11:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-P5bXNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-kJMZos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-vWyD4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-mL39s9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-qxL5mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-2KtEVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:35 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-PkNXVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-w_TudG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-HaQp5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-APYIp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-T4jVTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-R7BeUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-M0KfZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-M2YGah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-o2qu4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-6otRTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:36 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-32q9m9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:23:37 INFO - Running setup.py install for manifestparser 11:23:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:23:37 INFO - Running setup.py install for mozcrash 11:23:37 INFO - Running setup.py install for mozdebug 11:23:37 INFO - Running setup.py install for mozdevice 11:23:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:23:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:23:37 INFO - Running setup.py install for mozfile 11:23:37 INFO - Running setup.py install for mozhttpd 11:23:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:23:37 INFO - Running setup.py install for mozinfo 11:23:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Running setup.py install for mozInstall 11:23:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Running setup.py install for mozleak 11:23:38 INFO - Running setup.py install for mozlog 11:23:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Running setup.py install for moznetwork 11:23:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:23:38 INFO - Running setup.py install for mozprocess 11:23:38 INFO - Running setup.py install for mozprofile 11:23:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:23:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:23:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:23:39 INFO - Running setup.py install for mozrunner 11:23:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:23:39 INFO - Running setup.py install for mozscreenshot 11:23:39 INFO - Running setup.py install for moztest 11:23:39 INFO - Running setup.py install for mozversion 11:23:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:23:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:23:39 INFO - Cleaning up... 11:23:39 INFO - Return code: 0 11:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:39 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')]} 11:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:39 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')]} 11:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:39 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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:39 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 11:23:39 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 11:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:39 INFO - 'HOME': '/Users/cltbld', 11:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:39 INFO - 'LOGNAME': 'cltbld', 11:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:39 INFO - 'MOZ_NO_REMOTE': '1', 11:23:39 INFO - 'NO_EM_RESTART': '1', 11:23:39 INFO - 'PAGER': '/bin/cat', 11:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:39 INFO - 'PWD': '/builds/slave/test', 11:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:39 INFO - 'SHELL': '/bin/bash', 11:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:39 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:39 INFO - 'USER': 'cltbld', 11:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:39 INFO - 'XPC_FLAGS': '0x0', 11:23:39 INFO - 'XPC_SERVICE_NAME': '0', 11:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:39 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-3UyUBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-2M4c8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-kw4hLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-rkmJZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-0F_PoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-PukDF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-UwwFjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-9vqxhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-2oEqfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-3S7FqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:40 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)) 11:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:40 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-0IxkRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:41 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)) 11:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:41 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-MpavU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:41 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)) 11:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:41 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-4oc_2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:41 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)) 11:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:41 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-d7o3o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:41 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)) 11:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:41 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-H9JUDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:41 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)) 11:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:41 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-DnaaD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:41 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)) 11:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:41 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-QbRIhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:41 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)) 11:23:41 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)) 11:23:41 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)) 11:23:41 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)) 11:23:41 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)) 11:23:41 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)) 11:23:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:23:41 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 11:23:41 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 11:23:41 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 11:23:41 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 11:23:41 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 11:23:41 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 11:23:44 INFO - Downloading blessings-1.6.tar.gz 11:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:23:44 INFO - Installing collected packages: blessings 11:23:44 INFO - Running setup.py install for blessings 11:23:44 INFO - Successfully installed blessings 11:23:44 INFO - Cleaning up... 11:23:44 INFO - Return code: 0 11:23:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:44 INFO - Reading from file tmpfile_stdout 11:23:44 INFO - Current package versions: 11:23:44 INFO - blessings == 1.6 11:23:44 INFO - blobuploader == 1.2.4 11:23:44 INFO - docopt == 0.6.1 11:23:44 INFO - functools32 == 3.2.3-2 11:23:44 INFO - jsonschema == 2.5.1 11:23:44 INFO - manifestparser == 1.1 11:23:44 INFO - mozInstall == 1.12 11:23:44 INFO - mozcrash == 0.17 11:23:44 INFO - mozdebug == 0.1 11:23:44 INFO - mozdevice == 0.48 11:23:44 INFO - mozfile == 1.2 11:23:44 INFO - mozhttpd == 0.7 11:23:44 INFO - mozinfo == 0.9 11:23:44 INFO - mozleak == 0.1 11:23:44 INFO - mozlog == 3.3 11:23:44 INFO - moznetwork == 0.27 11:23:44 INFO - mozprocess == 0.23 11:23:44 INFO - mozprofile == 0.28 11:23:44 INFO - mozrunner == 6.12 11:23:44 INFO - mozscreenshot == 0.1 11:23:44 INFO - mozsystemmonitor == 0.3 11:23:44 INFO - moztest == 0.7 11:23:44 INFO - mozversion == 1.4 11:23:44 INFO - psutil == 3.1.1 11:23:44 INFO - requests == 1.2.3 11:23:44 INFO - wsgiref == 0.1.2 11:23:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:44 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')]} 11:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:45 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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:45 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 11:23:45 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 11:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:45 INFO - 'HOME': '/Users/cltbld', 11:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:45 INFO - 'LOGNAME': 'cltbld', 11:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:45 INFO - 'MOZ_NO_REMOTE': '1', 11:23:45 INFO - 'NO_EM_RESTART': '1', 11:23:45 INFO - 'PAGER': '/bin/cat', 11:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:45 INFO - 'PWD': '/builds/slave/test', 11:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:45 INFO - 'SHELL': '/bin/bash', 11:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:45 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:45 INFO - 'USER': 'cltbld', 11:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:45 INFO - 'XPC_FLAGS': '0x0', 11:23:45 INFO - 'XPC_SERVICE_NAME': '0', 11:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 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)) 11:23:45 INFO - Cleaning up... 11:23:45 INFO - Return code: 0 11:23:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:45 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 0x10cb01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f873957d3f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, '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.StmgNpbdHq/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.5baC9gqFHb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 11:23:45 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 11:23:45 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 11:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:45 INFO - 'HOME': '/Users/cltbld', 11:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:45 INFO - 'LOGNAME': 'cltbld', 11:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:45 INFO - 'MOZ_NO_REMOTE': '1', 11:23:45 INFO - 'NO_EM_RESTART': '1', 11:23:45 INFO - 'PAGER': '/bin/cat', 11:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:45 INFO - 'PWD': '/builds/slave/test', 11:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:45 INFO - 'SHELL': '/bin/bash', 11:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:23:45 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:45 INFO - 'USER': 'cltbld', 11:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:45 INFO - 'XPC_FLAGS': '0x0', 11:23:45 INFO - 'XPC_SERVICE_NAME': '0', 11:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:23:45 INFO - Cleaning up... 11:23:45 INFO - Return code: 0 11:23:45 INFO - Running post-action listener: _resource_record_post_action 11:23:45 INFO - Running post-action listener: _start_resource_monitoring 11:23:45 INFO - Starting resource monitoring. 11:23:45 INFO - [mozharness: 2016-08-23 18:23:45.669811Z] Finished create-virtualenv step (success) 11:23:45 INFO - [mozharness: 2016-08-23 18:23:45.670941Z] Running install step. 11:23:45 INFO - Running pre-action listener: _resource_record_pre_action 11:23:45 INFO - Running main action method: install 11:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:45 INFO - Reading from file tmpfile_stdout 11:23:45 INFO - Detecting whether we're running mozinstall >=1.0... 11:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:23:46 INFO - Reading from file tmpfile_stdout 11:23:46 INFO - Output received: 11:23:46 INFO - Usage: mozinstall [options] installer 11:23:46 INFO - Options: 11:23:46 INFO - -h, --help show this help message and exit 11:23:46 INFO - -d DEST, --destination=DEST 11:23:46 INFO - Directory to install application into. [default: 11:23:46 INFO - "/builds/slave/test"] 11:23:46 INFO - --app=APP Application being installed. [default: firefox] 11:23:46 INFO - mkdir: /builds/slave/test/build/application 11:23:46 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'] 11:23:46 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 11:24:19 INFO - Reading from file tmpfile_stdout 11:24:19 INFO - Output received: 11:24:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:24:19 INFO - Running post-action listener: _resource_record_post_action 11:24:19 INFO - [mozharness: 2016-08-23 18:24:19.952246Z] Finished install step (success) 11:24:19 INFO - [mozharness: 2016-08-23 18:24:19.952378Z] Running run-tests step. 11:24:19 INFO - Running pre-action listener: _resource_record_pre_action 11:24:19 INFO - Running main action method: run_tests 11:24:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:24:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:24:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:24:19 INFO - 2016-08-23 11:24:19.966 screenresolution[1648:7363] starting screenresolution argv=screenresolution get 11:24:19 INFO - 2016-08-23 11:24:19.969 screenresolution[1648:7363] Display 0: 1600x1200x32@60 11:24:19 INFO - 2016-08-23 11:24:19.974 screenresolution[1649:7365] starting screenresolution argv=screenresolution list 11:24:19 INFO - Available Modes on Display 0 11:24:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:24:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:24:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:24:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:24:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:24:20 INFO - Intel Iris: 11:24:20 INFO - Chipset Model: Intel Iris 11:24:20 INFO - Type: GPU 11:24:20 INFO - Bus: Built-In 11:24:20 INFO - VRAM (Dynamic, Max): 1536 MB 11:24:20 INFO - Vendor: Intel (0x8086) 11:24:20 INFO - Device ID: 0x0a2e 11:24:20 INFO - Revision ID: 0x0009 11:24:20 INFO - Displays: 11:24:20 INFO - Display: 11:24:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:24:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:24:20 INFO - Display Serial Number: 1600x1200 60 11:24:20 INFO - Main Display: Yes 11:24:20 INFO - Mirror: Off 11:24:20 INFO - Online: Yes 11:24:20 INFO - Rotation: Supported 11:24:20 INFO - Return code: 0 11:24:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:24:20 INFO - Python 2.7.3 11:24:20 INFO - Return code: 0 11:24:20 INFO - grabbing minidump binary from tooltool 11:24:20 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')]} 11:24:20 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 0x10ca65d30>, '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 0x10c7cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca5db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:24:20 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 11:24:20 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 11:24:20 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 11:24:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:24:20 INFO - Return code: 0 11:24:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:24:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:24:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:24:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:24:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:24:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 11:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0235 --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 11:24:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StmgNpbdHq/Render', 11:24:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:24:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:24:20 INFO - 'HOME': '/Users/cltbld', 11:24:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:24:20 INFO - 'LOGNAME': 'cltbld', 11:24:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:24:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:20 INFO - 'MOZ_NO_REMOTE': '1', 11:24:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:24:20 INFO - 'NO_EM_RESTART': '1', 11:24:20 INFO - 'PAGER': '/bin/cat', 11:24:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:20 INFO - 'PWD': '/builds/slave/test', 11:24:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:24:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:24:20 INFO - 'SHELL': '/bin/bash', 11:24:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5baC9gqFHb/Listeners', 11:24:20 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 11:24:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:24:20 INFO - 'USER': 'cltbld', 11:24:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:24:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:20 INFO - 'XPC_FLAGS': '0x0', 11:24:20 INFO - 'XPC_SERVICE_NAME': '0', 11:24:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:24:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 11:24:20 INFO - mozversion application_buildid: 20160823003946 11:24:20 INFO - mozversion application_changeset: 35416a1541ae9860846ec93081d12b7e96c930c3 11:24:20 INFO - mozversion application_display_name: Nightly 11:24:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:24:20 INFO - mozversion application_name: Firefox 11:24:20 INFO - mozversion application_remotingname: firefox 11:24:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:24:20 INFO - mozversion application_vendor: Mozilla 11:24:20 INFO - mozversion application_version: 51.0a1 11:24:20 INFO - mozversion platform_buildid: 20160823003946 11:24:20 INFO - mozversion platform_changeset: 35416a1541ae9860846ec93081d12b7e96c930c3 11:24:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:24:20 INFO - mozversion platform_version: 51.0a1 11:24:20 INFO - using testdate: 1471976660 11:24:20 INFO - actual date: 1471976660 11:24:20 INFO - starting webserver on 'localhost:49262' 11:24:20 INFO - SUITE-START | Running 2 tests 11:24:20 INFO - TEST-START | dromaeo_css 11:24:20 INFO - operating with platform_type : mac_ 11:24:20 INFO - Initialising browser for dromaeo_css test... 11:24:20 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpQPUyaV/profile http://localhost:49262/getInfo.html) 11:24:22 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 11:24:22 INFO - PROCESS | 1659 | colorDepth:24 11:24:22 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 11:24:22 INFO - PROCESS | 1659 | __metrics 11:24:23 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:24:23 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:24:23 INFO - TEST-INFO | 1659: exit 0 11:24:23 INFO - Browser initialized. 11:24:23 INFO - Running cycle 1/1 for dromaeo_css test... 11:24:23 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpQPUyaV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:24:35 INFO - PROCESS | 1661 | RSS: Main: 228536320 11:24:35 INFO - PROCESS | 1661 | 11:25:26 INFO - PROCESS | 1661 | 1471976726657 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] 11:27:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 11:27:39 INFO - PROCESS | 1661 | RSS: Main: 336048128 11:27:39 INFO - PROCESS | 1661 | 11:30:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 11:30:43 INFO - PROCESS | 1661 | RSS: Main: 371433472 11:30:43 INFO - PROCESS | 1661 | 11:33:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 11:33:46 INFO - PROCESS | 1661 | RSS: Main: 336936960 11:33:46 INFO - PROCESS | 1661 | 11:36:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 11:36:49 INFO - PROCESS | 1661 | RSS: Main: 331911168 11:36:49 INFO - PROCESS | 1661 | 11:39:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 11:39:53 INFO - PROCESS | 1661 | RSS: Main: 327389184 11:39:53 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 11:42:57 INFO - PROCESS | 1661 | RSS: Main: 414851072 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | __start_tp_report 11:42:57 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 11:42:57 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 11:42:57 INFO - PROCESS | 1661 | |i|pagename|runs| 11:42:57 INFO - PROCESS | 1661 | |0;dojo.html;2683.9060632877854;3267.1342094344996;3305.173706573357;3334.883279085231;3353.698167164956;5503.91744123838;5508.862278443041;5627.184058311543;5707.315122185339;5736.455036771506;5590.524805391547;5633.408492108331;5656.773729050843;5714.1428785682165;5726.226959360485;5071.56891664209;5091.872703182432;5168.586513078963;5170.482951704818;5245.055889939809;5211.687298762067;5216.7391630418515;5262.184361423985;5263.710495922723;5276.34045744282;5052.469490703496;5068.518490743373;5071.3407257056715;5103.4896510349035;5226.1899405592;5135.717535535544;5146.974265128688;5163.979072242742;5193.792248310063;5226.738663066832;5143.151380022292;5172.405173405069;5178.171492561172;5188.7146206958605;5199.922001169985;5500.3949565547755;5621.988042152411;5674.375818659944;5744.023516002248;5769.3076830780155;6112.174856394375;6263;6264.592801467936;6273.529485288622;6281.340459251758;5957.374475680034;6269.968650156766;6305.87388252237;6343.111964324999;6352.904706429407;4851.369321988154;5638.830835074954;5694.572907031907;5729.656220626775;5786.797462088891;6054.636721796706;6346.428821406095;6347.587406818542;6365.2679941806355;6384.52116091295;5437.864053398696;6102.8169154924535;6115.296740874767;6125.84685382869;6133.233345831771;5996.220491336098;6126.417990290915;6194.38056194377;6225.750969961151;6272.153259310026;6153.661548614779;6164.845878853064;6195.504359651217;6197.411245931629;6248.593841400385;6209.068639704081;6221.875562488724;6223.408776166257;6226.782062627786;6233.283172435228;4630.9536904631195;4679.789409476582;4757.167495688241;4812.590929770939;4818.4217893852265;4399.054203346296;4404.097160082176;4419.845305414295;4421.955780442218;4485.237509623372;5727.541796656257;5727.8854422912145;5733.31200255972;5735.397783232784;5739.827805165852;6105.206323177958;6105.358937311607;6184.5980011299125;6328.905066424008;6332.271788744261;5630.971845140748;5636.915446268309;5692.345380281321;5694.914576281359;5772.336181339116;5390.676559406448;5436.374816896028;5438.83683489496;5441.755121019563;5453.072977593819;6309.652969086653;6309.842253943688;6317.336679648571;6321.652309123042;6322.589031712924;4919.729414882073;6164.845878853064;6230.59501132425;6285.402886725754;6289.245290565162;5556.611037227355;6318.715657795502;6319.9052014218905;6321.8735625288155;6337.714802833976;6118.082287656887;6131.938680613315;6152.692365381624;6157.476614487819;6163.445289923929;6105.961986462224;6178.1350610913605;6199.597026193283;6277.686115694106;6286.9371306288185;6059.666718330534;6116.204893363834;6119.938800611937;6125.663088529996;6141.539384546088;6106.6336019838955;6133.785317513865;6135.11040899076;6142.662153581596;6145.35473775265;5707.857303567444;5733.426657334233;5761.942380576141;5783.190353350618;5932.377100404396;6000.909986350295;6005.819825405245;6008.309044459943;6016.368281330398;6021.006533921854;5788.942110578841;5796.652200867793;5840.123981402823;5840.474357307863;5877.500412464989 11:42:57 INFO - PROCESS | 1661 | |1;ext.html;458.97475638839853;510.82631905152266;519.5479932458761;524.4807640435969;533.6611251855071;31413.528797068033;34205;41353.793231033844;42592.361114583226;42800;43336.783316083376;43629.56370436295;43655.78172109143;43777;43812.561874381245;9786.902130978706;9984.650537231199;10041.096301332878;10042.899571004305;10045.89954100457;8984.685536006242;9190;9190.954045229799;9203.4477931324;9211.907880921206;12114.515419383215;12389.62831115068;12400.503979840796;12605.621831345074;12635.873641263608;10201.744956376077;12284.938575307066;12300.753984920297;12303.753924921451;12313.07651926109;12195.756084878252;12287.815682764765;12288;12293.200941938834;12305.815412768816;41355.58644413547;41632.37551436731;41704.58295417068;41725;41772.37341439881;3427.3145370925913;3497.3704733148024;3503.754737168399;3929.8035098244973;4281.357796330544;31363.529547056583;32531.83734081338;32541.674583254102;32713;32824.50763238553;3202.2154572129743;3355.798652080883;3404.165979335054;3432.7597068205237;3462.2036931505895;412715;456255;457184;457883;465478.67260663817;61830;62377.37622623716;62396.06405903915;62431.37568624256;62597;64470.35529644737;64501.67749161318;64601;64633;64717.35282647207;16369.754453683205;16589.336426542803;16611.91694041522;16633.916830415772;16642.83357166437;12846.743065138831;12892.097553171188;12915.16051456671;12925.935370323088;12942.352882355844;15241.3903443861;15315.61710957235;15616.687666246611;17396;17730.5567360817;6421.8073457796345;6501.586666999769;6571.901421478682;6587.9670601646685;6594.8351291218105;896.3591032411726;989.4656885282011;1015;1043.0612448796144;1128.9500764289126;24935.625965610532;30433.48131890085;30541.389172216877;31146.582644710146;31503.36993260168;990.5988074829705;1016.6492560066765;1061.920355973305;1086.5870969031716;1128.8250321200062;15958.573847265732;15988.600284992968;16043;16200.51398458048;16479;658465;673906;674922;675678;675866;50486.495135048914;60853;60864;60881;60893.39106608966;1201.2792324605166;1249.7500499900057;1262.7916393795106;1412.2867951684539;1416.8795652369663;30860.382792344477;31890.840545797124;32043.67956320407;32849.67150328466;33035.669643303256;51787.48212517826;55058;57195;57232;62649;590724;593468;593476;594993;595478;25288.873555632104;26570.202893913214;26733;27349.589756153277;27769.861150694116;40575;49074.26388604245;49093.509064908896;49464;50041;37979.05052373602;40219.99450013773;40248;40741;41907;34367;39802.40296395614;40481.59518404778;40975.38536922008;41265;38144.80927595344;38634.04587174336;39098;39195.41206881842;39250.411243831935;34462.48306275458;34831.12922176966;35953.28093438169;35998.64001359953;36005.45991810073;25536.23391298264;25814.741852581235;26077.348066298495;26115.347116322246;26319.868400657873 11:42:57 INFO - PROCESS | 1661 | |2;jquery.html;352.49417086480884;372.23877171184927;374.73206657240087;377.8564145624662;401.64645145858464;15609.921950390246;15981.280842362092;20072.49818754532;20542.794572054274;20620.48448788781;21246;21437.892810535963;21750.782492175073;21761.564768704615;21911.904404779776;5950.285965684124;5964.284285885689;6168.228971378578;6250.156228909107;6264.906026409608;4265.936010959823;6101.877962440749;6206.255249370082;6208.565400421972;6216.906746398808;4425.955740442602;6233.437143354533;6809.421199198074;6864.485163612724;6864.759733409332;6566.967165164191;6821.55659882111;6841.247462779091;6850.588964662112;6857;5318.867028324285;5700.828975130753;5914.201582786355;5934.465898069195;5942.465178133945;23890.402739931465;25519.617205741928;26095.869520652464;26168.084117056;26578.6710664466;2513.0576033987254;2564.807639427032;2568.768810807018;2722.891084356623;2893.4357800228963;10233.283670143093;14213.502527411594;14924.477643282542;14965;15843.920780396023;1633.8774591905535;1785.803561608222;1800.2708902894349;1862.8416584590327;1907.399169261678;124521;128970;130879;132075;132511.33744331216;9451.243900487945;10952.890471095347;11385.487653055632;11426.88573114275;11482.540698372139;14320.997530172788;14380.496682616267;14381.78427323591;14396.640083997983;14404.85595144056;8248.340132789483;8526.658933642584;8555.828883422297;8651.653933842701;8674.913250867537;8458.957705211435;8477.237048665527;8502.234798868132;8665.696700615574;8669.523176225368;3700.6299370063316;4441.578050085237;4475.776211189427;4506.684532082722;4560.4071470708595;1594.3702237616076;1732.8007279162805;1827.0079875472136;1889.8866068035961;1903.9428817135508;671.7078071039114;707.8183564875916;888.7911340834899;937.1565590968047;973.8539219117189;21222;21236;22147.889260553915;22447.775522244894;23430.882845586006;899.2042042792176;947.1286416496839;979.9216062714966;982.062130665213;984.3355734879071;10438.425886576159;11239.94380028111;11377.189205774253;11407.201495895373;11538;469213;470186;611926;615950;638538;117281;140156;141427;142061.28969355294;145827;434.5697759218414;455.3897776978864;459.4326007380882;459.72186826969204;461.2274440312463;11143.052840508324;11196.94401527987;11343.48954297075;11455.369954652575;11564.190506664452;46505.37231196892;47828;55615.72192139013;57404;57943.551411215056;82386;82762;83838;84752;85010.1498985027;7055.788326350218;7654.846903062019;7735.187805280365;8091.919080809116;8132.959335203286;11444.656660300205;12659.87340126587;12844.293563854078;13166.47334106625;13214.141080829715;9806.803863922823;11076.113910886948;11611.245269057485;11685.298882067103;11770.58802941893;11159.776804463703;11186.608468703556;11525.596604118815;11706.063514918616;11801.29192248468;11303.660890173307;11433.256838305484;11808.232464889754;11842.585509507126;11880.643580692593;8230.588470576567;8247.752567422987;8304.792380190544;8344.749657510285;8429.861968634401;9440.197583205505;9591.28065395112;11354.886451135382;11540.422978851191;11570.487509871326 11:42:57 INFO - PROCESS | 1661 | |3;mootools.html;1385.148133897653;1527.19822093401;1666.6083470384458;1828.9634207315846;1848.6949653307213;8435.70475033374;8979.730608081753;9008.639654413824;9015.14356136166;9033.141851524111;6541.901871471931;8039.276465118138;8449;8651.913480865189;8802.64789408423;2332.4402143485527;2363.917262895799;2364.444355576447;2369.0642196332437;2371.1700904683394;2328.93013209604;2354.1878052072007;2369.8815059247054;2374.881255937205;2376.3821406434363;2346.6010778167665;2355.2463211772238;2357.6581395697613;2359.6578496118054;2371.0989823866907;1874.3627166763229;2322.547103314854;2337.2871274261342;2348.5889969255313;2355.3993731598503;1950.7171460138343;1959.3925882976323;2345.5543446745064;2350.74141844397;2356.1164563288767;8815.118488151198;8854.645814167425;8950.820983580292;8955.820883582292;9033.322500812465;5182.170852663556;5342.545883599899;5348.064088784447;5361.490658387447;5372.355317361941;3108.5246751750115;3256.576645036154;3604.2431089471243;3731.776093434403;3736.346139425617;1114.1031469666914;1128.949197286124;1781.4477511971202;1830.405118336546;1879.9060046997731;11643.01034412076;11650.53397864093;11685.532578696842;11692.240004399688;11714.355710435922;4304.74171549708;4584.472785629629;4620.283856002325;4621.098885717252;4633.837815676435;9203.539823008823;9204.263658907272;9213;9217.308701847389;9253.444793312423;5251.711155886386;5254.97372513135;5255.5795536357;5256.684598924076;5265.131253343232;3679.411294192916;4345.847895323648;4811.759412029384;4897.755112244374;4918.729469879119;3297.5713157289397;3351.949720754191;3759.605241449663;3800.8289626966853;3860;1004.5278719002057;1172.4841069929205;1180.6681803438141;1193;1197.9580714674944;904.4980036079908;922.686286662538;926.9582868770786;937.4438432202511;982.0719420147892;5741.7990370337675;5966.015607424726;6055.636661800306;6167.620242701341;6180.969095154585;453.3652885959683;455.5717625432083;463.3605624238529;472.98581042568776;476.8569429171235;4006.6193711597352;4017.77902215381;4087.4890638670167;4108.67130629549;4134.710570260053;10439.060484556427;10938.343699378063;10964.890351096547;10971.23201375896;10996.890031099747;10915.508802103925;10926.890731092588;10926.945365273123;10940.070094041936;10948.124150067975;8264.710735124241;8305.044919834176;8335.95832020836;8340.457870238415;8391.790205244917;5299.443558426464;5749.252597162342;5987.191729116514;6098.939010609837;6219.564630475823;8230.670773169006;9429.3399462037;9759.512024398893;9861.605535778488;9939.701808945743;9292.674756383494;9658.90341096608;10322.48387580633;10339.63811266602;10339.689809305733;7364.41084713211;7570.545767253982;7634.045744281965;7680.6543705534505;7766.805829854299;8272.669093236203;8381.161883811814;8452;8453.830923381374;8463.365247606578;7351.926480735338;7419.183889772229;7482.551046937201;7621.1616722161625;7629.961850190713;6614.239362473377;7175.605341706257;7341.6696248670005;7373.115226172894;7465.06686664167;6964.512484126062;7019.684114214975;7071.929280707127;7147.714091436493;7159.642017899188;5038.571721403721;5092.668976516515;5127.2052831811125;5158.3552055993005;5245.895082098413;4895.8041678332465;4996.975015125046;5064.302659508054;5323.414424413234;5339.893202136013 11:42:57 INFO - PROCESS | 1661 | |4;prototype.html;304.5340628837878;308.3247687564234;312.219451371571;316.7291965369607;319.8656564243018;4566.337881007252;5260.684358938462;5380.24676545284;5382.300300960879;5415.837524874255;4473.753943533105;4617;5192.92210616841;5331.493508116732;5340.279062326585;3099.6361600895534;3903.726739128262;3967.642912137907;4092.3452247640384;4126.071633882382;3582.964170358302;3991.8602848900296;4024.839006439739;4037.1336351190544;4057.97971010146;3701.666849983501;3979.4030895365636;3999.440078389028;4026.396040593905;4046.332355161395;3241.2383089973837;3799.5630502492013;3910.4720862683744;4032.9798351008203;4059.8782036538805;3481.918502125637;3483.7038851697635;3489.232368878868;3510.859565617372;3544.3088597723454;4651.279051746985;4915.5084491550915;5059.2916991621205;5088.338515992898;5088.6946783193125;3824.9808750956345;4004.2191772604356;4017.7589344639414;4177.874663760092;4345.456817897762;4728.763561821896;5372.979133964534;5416.995711586699;5420.674759514442;5508.669479831223;3233.9514907276175;3712.4407748745593;3746.98831315546;3779.45197946299;3784.3944968805135;5445.264889239981;5747.792963477675;5760.041585860786;5780.3063632363965;5798;3771.2080463103057;3798.506194194737;3805.5052843130215;3815.523059617548;3830.885073447801;3861.2856621525107;3895.49358583388;3897.232964595169;3902.8681682311812;3946.625070618287;3800.1259710266795;4593.885152871138;4594.356790049395;4598.379218805485;4648.2562789953445;5035.597152227813;5145.536901678792;5432.864178395572;5467.726613669301;5536.283752036801;1116.5198964445203;1178.5462596900254;1267.5359959247085;1285.190330092036;1323.6889331007205;762.7864198024562;775.3951917504355;785.4187900953368;803.702630026882;805.7139715400948;4181.372794080851;4311.827526898889;4503.369528266046;4578.748168850745;4988.72562009093;5168.069747445421;5300.284461597635;5695.345035320908;5696.974544582015;5748.827535173951;4372.6939114261695;4916.5820905222745;5093.414257360377;5124.693887750527;5158.819441319536;2396.676448679406;2983.925401864927;2988.520682262294;2988.9850550747105;2997.430488207234;4975.825846095369;5878.794847056343;5885.852853678693;5889.819497471318;5905.704714764244;5342.946570534323;5761.740721667535;5781.571739869265;5831.591788574759;5846.970765146232;5296.947030529645;5483.163140347949;5947.732352044169;5964.701764911824;5998.640081595118;5390.460953904578;5644.717764111861;5690.687012214368;5736.569757268138;5757.539396848326;5513.476219759116;5637.802676906289;5703.602617358773;5708.058170401837;5717.8570535736935;5452.991196628478;5659.886802264014;5945.910811337747;5976.505873531617;5988.760449582142;3710.6474884885893;3821.7324787264624;3932.646061854447;3936.5669776324007;3948.723589348833;4762.52374762521;4877.9024419512125;5137.614678899172;5743.8851222976145;5815.244018277597;5543.501084902378;5556.305461817273;5564.33228012641;5571.495696162095;5591.692456914746;5234.659747116425;5530.391656917655;5550.805721799879;5585.748641311232;5622.3815380307315;5423.240746295601;5430.83707488776;5443.972780136074;5448;5462.590305727007;4737.881552961066;5284.9735751320995;5513.566472717042;5540.891821635609;5561.443855614568;5329.946700532945;5397.676139431646;5430.972845135749;5449.564034877121;5489.890202195854 11:42:57 INFO - PROCESS | 1661 | |5;yui.html;735.889616557516;736.9078865141856;776.2547953964194;825.7481468152215;847.2586486824028;15440.768388474182;16537.338506459742;16542.503724888244;16547.83452165478;16552.75170872438;14839.851601483982;14973.92513037436;15000.624984375396;15019.023763455367;15035.02272352299;711.0898050495348;731.2212493694199;862.1593945902741;871.9346049046316;898.285862739124;855.2302927365358;867.761365624452;868.3183700794877;870.1037930931151;870.1907226279558;914.4421902639384;916.6425094213262;917.7338571814166;918.1598837064079;918.8897332320098;730.390124246255;757.9956557561253;760.9657565409569;907.2107266678032;907.3467103685336;564.7317524176025;568.4656422962444;684.5926673629183;710.4919982212742;714.8963400306978;13666.316684165751;13693.794593081111;13697.178169309873;13703.177809331371;13715.794263086062;14157.858421415756;14424.206668633222;14467;14535.345909434101;14642.780358294634;13072.215667060007;13200.603981880558;13331.266780327078;13338.19970801755;13837;4332.805023773938;4417.983863711364;4692.319613656035;4801.975990120062;4903.754812259408;98521.81479179615;106806.3979040315;114494;116728;117681;11458.94270528642;11480.540778368771;11559.479823407799;11722.355270460206;11733.4133293335;23015.309540713806;23017.19439819598;23018;23028;23047.654285185734;7145.321194486515;7767.223277672188;8014.919850801417;8059.30241838928;8080.797980050428;4332.545082766327;4453.51011388747;4459;4462.107578484316;4495.707778994355;3415.5047518109736;3489.3719130556237;3536.876209332661;3543.6633519815578;3549.5563054618174;623.9032949892774;628.7579281976381;632.9556931014877;636.7930422612669;653.5066025151076;562.6285207848937;565.8981383351037;598.5451057196562;620.8478922663886;622.2284367384501;8631.136886311444;8915.866262006075;9215.308851836138;9607.711768646952;9783.46190959504;645.5674697952384;647.1683886206202;657.8881590129594;669.493639311549;677.7788191652497;3953.0864444200033;4308.74147551148;4669.813207471731;4690.507496712864;4702.741349225757;148222;150943.49056509513;153892;154242;155147;123629;130960;135251.64748352589;137633;141075.1871053545;116573.41713291379;117025.24462133074;117774;118914;119467;9112.498812565373;9171.954140229256;9181;9192.770180745267;9259.953700231456;44159.77920110379;47632.52367476281;62750;63584.36415635784;65741.34258657352;84669;87809;88937.55531222561;91159.54420228122;93231;12572.622821315375;13533.729325413633;13571.98210134224;13694.452221911011;14050.859491404955;13177.275249860966;14553.272336382926;14818.369499472697;15251.161186134868;15587.76618350703;9412.48231347255;12441;12446.25322480654;14383.208923509308;14618.85381146175;9950.701478955643;12008.519659213884;12201.572944946884;12221.694457638274;12512.562060327844;11830.17188796776;12024;12034.097442691658;12265.448054837376;12388.504459821506;9833.262505311986;10049;10151.086402223835;10204.336718113298;10287.43419111926;9559;10400.791984160123;10406.063454289151;10442.477876106317;10446.895531044593 11:42:57 INFO - PROCESS | 1661 | __end_tp_report 11:42:57 INFO - PROCESS | 1661 | __start_cc_report 11:42:57 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1255 11:42:57 INFO - PROCESS | 1661 | __end_cc_report 11:42:57 INFO - PROCESS | 1661 | __startTimestamp1471977777293__endTimestamp 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | ------- Summary: start ------- 11:42:57 INFO - PROCESS | 1661 | Number of tests: 6 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5690.39 Median:5788.94 stddev:670.72 (11.6%) stddev-sans-first:629.94 11:42:57 INFO - PROCESS | 1661 | Values: 2683.9 3267.1 3305.2 3334.9 3353.7 5503.9 5508.9 5627.2 5707.3 5736.5 5590.5 5633.4 5656.8 5714.1 5726.2 5071.6 5091.9 5168.6 5170.5 5245.1 5211.7 5216.7 5262.2 5263.7 5276.3 5052.5 5068.5 5071.3 5103.5 5226.2 5135.7 5147.0 5164.0 5193.8 5226.7 5143.2 5172.4 5178.2 5188.7 5199.9 5500.4 5622.0 5674.4 5744.0 5769.3 6112.2 6263.0 6264.6 6273.5 6281.3 5957.4 6270.0 6305.9 6343.1 6352.9 4851.4 5638.8 5694.6 5729.7 5786.8 6054.6 6346.4 6347.6 6365.3 6384.5 5437.9 6102.8 6115.3 6125.8 6133.2 5996.2 6126.4 6194.4 6225.8 6272.2 6153.7 6164.8 6195.5 6197.4 6248.6 6209.1 6221.9 6223.4 6226.8 6233.3 4631.0 4679.8 4757.2 4812.6 4818.4 4399.1 4404.1 4419.8 4422.0 4485.2 5727.5 5727.9 5733.3 5735.4 5739.8 6105.2 6105.4 6184.6 6328.9 6332.3 5631.0 5636.9 5692.3 5694.9 5772.3 5390.7 5436.4 5438.8 5441.8 5453.1 6309.7 6309.8 6317.3 6321.7 6322.6 4919.7 6164.8 6230.6 6285.4 6289.2 5556.6 6318.7 6319.9 6321.9 6337.7 6118.1 6131.9 6152.7 6157.5 6163.4 6106.0 6178.1 6199.6 6277.7 6286.9 6059.7 6116.2 6119.9 6125.7 6141.5 6106.6 6133.8 6135.1 6142.7 6145.4 5707.9 5733.4 5761.9 5783.2 5932.4 6000.9 6005.8 6008.3 6016.4 6021.0 5788.9 5796.7 5840.1 5840.5 5877.5 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:71254.87 Median:36992.26 stddev:154799.22 (418.5%) stddev-sans-first:155142.21 11:42:57 INFO - PROCESS | 1661 | Values: 459.0 510.8 519.5 524.5 533.7 31413.5 34205.0 41353.8 42592.4 42800.0 43336.8 43629.6 43655.8 43777.0 43812.6 9786.9 9984.7 10041.1 10042.9 10045.9 8984.7 9190.0 9191.0 9203.4 9211.9 12114.5 12389.6 12400.5 12605.6 12635.9 10201.7 12284.9 12300.8 12303.8 12313.1 12195.8 12287.8 12288.0 12293.2 12305.8 41355.6 41632.4 41704.6 41725.0 41772.4 3427.3 3497.4 3503.8 3929.8 4281.4 31363.5 32531.8 32541.7 32713.0 32824.5 3202.2 3355.8 3404.2 3432.8 3462.2 412715.0 456255.0 457184.0 457883.0 465478.7 61830.0 62377.4 62396.1 62431.4 62597.0 64470.4 64501.7 64601.0 64633.0 64717.4 16369.8 16589.3 16611.9 16633.9 16642.8 12846.7 12892.1 12915.2 12925.9 12942.4 15241.4 15315.6 15616.7 17396.0 17730.6 6421.8 6501.6 6571.9 6588.0 6594.8 896.4 989.5 1015.0 1043.1 1129.0 24935.6 30433.5 30541.4 31146.6 31503.4 990.6 1016.6 1061.9 1086.6 1128.8 15958.6 15988.6 16043.0 16200.5 16479.0 658465.0 673906.0 674922.0 675678.0 675866.0 50486.5 60853.0 60864.0 60881.0 60893.4 1201.3 1249.8 1262.8 1412.3 1416.9 30860.4 31890.8 32043.7 32849.7 33035.7 51787.5 55058.0 57195.0 57232.0 62649.0 590724.0 593468.0 593476.0 594993.0 595478.0 25288.9 26570.2 26733.0 27349.6 27769.9 40575.0 49074.3 49093.5 49464.0 50041.0 37979.1 40220.0 40248.0 40741.0 41907.0 34367.0 39802.4 40481.6 40975.4 41265.0 38144.8 38634.0 39098.0 39195.4 39250.4 34462.5 34831.1 35953.3 35998.6 36005.5 25536.2 25814.7 26077.3 26115.3 26319.9 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:34713.01 Median:1622.96 stddev:95539.96 (5886.8%) stddev-sans-first:95773.14 11:42:57 INFO - PROCESS | 1661 | Values: 352.5 372.2 374.7 377.9 401.6 15609.9 15981.3 20072.5 20542.8 20620.5 21246.0 21437.9 21750.8 21761.6 21911.9 5950.3 5964.3 6168.2 6250.2 6264.9 4265.9 6101.9 6206.3 6208.6 6216.9 4426.0 6233.4 6809.4 6864.5 6864.8 6567.0 6821.6 6841.2 6850.6 6857.0 5318.9 5700.8 5914.2 5934.5 5942.5 23890.4 25519.6 26095.9 26168.1 26578.7 2513.1 2564.8 2568.8 2722.9 2893.4 10233.3 14213.5 14924.5 14965.0 15843.9 1633.9 1785.8 1800.3 1862.8 1907.4 124521.0 128970.0 130879.0 132075.0 132511.3 9451.2 10952.9 11385.5 11426.9 11482.5 14321.0 14380.5 14381.8 14396.6 14404.9 8248.3 8526.7 8555.8 8651.7 8674.9 8459.0 8477.2 8502.2 8665.7 8669.5 3700.6 4441.6 4475.8 4506.7 4560.4 1594.4 1732.8 1827.0 1889.9 1903.9 671.7 707.8 888.8 937.2 973.9 21222.0 21236.0 22147.9 22447.8 23430.9 899.2 947.1 979.9 982.1 984.3 10438.4 11239.9 11377.2 11407.2 11538.0 469213.0 470186.0 611926.0 615950.0 638538.0 117281.0 140156.0 141427.0 142061.3 145827.0 434.6 455.4 459.4 459.7 461.2 11143.1 11196.9 11343.5 11455.4 11564.2 46505.4 47828.0 55615.7 57404.0 57943.6 82386.0 82762.0 83838.0 84752.0 85010.1 7055.8 7654.8 7735.2 8091.9 8133.0 11444.7 12659.9 12844.3 13166.5 13214.1 9806.8 11076.1 11611.2 11685.3 11770.6 11159.8 11186.6 11525.6 11706.1 11801.3 11303.7 11433.3 11808.2 11842.6 11880.6 8230.6 8247.8 8304.8 8344.7 8429.9 9440.2 9591.3 11354.9 11540.4 11570.5 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5677.53 Median:5051.44 stddev:3198.30 (63.3%) stddev-sans-first:3191.00 11:42:57 INFO - PROCESS | 1661 | Values: 1385.1 1527.2 1666.6 1829.0 1848.7 8435.7 8979.7 9008.6 9015.1 9033.1 6541.9 8039.3 8449.0 8651.9 8802.6 2332.4 2363.9 2364.4 2369.1 2371.2 2328.9 2354.2 2369.9 2374.9 2376.4 2346.6 2355.2 2357.7 2359.7 2371.1 1874.4 2322.5 2337.3 2348.6 2355.4 1950.7 1959.4 2345.6 2350.7 2356.1 8815.1 8854.6 8950.8 8955.8 9033.3 5182.2 5342.5 5348.1 5361.5 5372.4 3108.5 3256.6 3604.2 3731.8 3736.3 1114.1 1128.9 1781.4 1830.4 1879.9 11643.0 11650.5 11685.5 11692.2 11714.4 4304.7 4584.5 4620.3 4621.1 4633.8 9203.5 9204.3 9213.0 9217.3 9253.4 5251.7 5255.0 5255.6 5256.7 5265.1 3679.4 4345.8 4811.8 4897.8 4918.7 3297.6 3351.9 3759.6 3800.8 3860.0 1004.5 1172.5 1180.7 1193.0 1198.0 904.5 922.7 927.0 937.4 982.1 5741.8 5966.0 6055.6 6167.6 6181.0 453.4 455.6 463.4 473.0 476.9 4006.6 4017.8 4087.5 4108.7 4134.7 10439.1 10938.3 10964.9 10971.2 10996.9 10915.5 10926.9 10926.9 10940.1 10948.1 8264.7 8305.0 8336.0 8340.5 8391.8 5299.4 5749.3 5987.2 6098.9 6219.6 8230.7 9429.3 9759.5 9861.6 9939.7 9292.7 9658.9 10322.5 10339.6 10339.7 7364.4 7570.5 7634.0 7680.7 7766.8 8272.7 8381.2 8452.0 8453.8 8463.4 7351.9 7419.2 7482.6 7621.2 7630.0 6614.2 7175.6 7341.7 7373.1 7465.1 6964.5 7019.7 7071.9 7147.7 7159.6 5038.6 5092.7 5127.2 5158.4 5245.9 4895.8 4997.0 5064.3 5323.4 5339.9 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4478.35 Median:5091.05 stddev:1398.17 (27.5%) stddev-sans-first:1366.54 11:42:57 INFO - PROCESS | 1661 | Values: 304.5 308.3 312.2 316.7 319.9 4566.3 5260.7 5380.2 5382.3 5415.8 4473.8 4617.0 5192.9 5331.5 5340.3 3099.6 3903.7 3967.6 4092.3 4126.1 3583.0 3991.9 4024.8 4037.1 4058.0 3701.7 3979.4 3999.4 4026.4 4046.3 3241.2 3799.6 3910.5 4033.0 4059.9 3481.9 3483.7 3489.2 3510.9 3544.3 4651.3 4915.5 5059.3 5088.3 5088.7 3825.0 4004.2 4017.8 4177.9 4345.5 4728.8 5373.0 5417.0 5420.7 5508.7 3234.0 3712.4 3747.0 3779.5 3784.4 5445.3 5747.8 5760.0 5780.3 5798.0 3771.2 3798.5 3805.5 3815.5 3830.9 3861.3 3895.5 3897.2 3902.9 3946.6 3800.1 4593.9 4594.4 4598.4 4648.3 5035.6 5145.5 5432.9 5467.7 5536.3 1116.5 1178.5 1267.5 1285.2 1323.7 762.8 775.4 785.4 803.7 805.7 4181.4 4311.8 4503.4 4578.7 4988.7 5168.1 5300.3 5695.3 5697.0 5748.8 4372.7 4916.6 5093.4 5124.7 5158.8 2396.7 2983.9 2988.5 2989.0 2997.4 4975.8 5878.8 5885.9 5889.8 5905.7 5342.9 5761.7 5781.6 5831.6 5847.0 5296.9 5483.2 5947.7 5964.7 5998.6 5390.5 5644.7 5690.7 5736.6 5757.5 5513.5 5637.8 5703.6 5708.1 5717.9 5453.0 5659.9 5945.9 5976.5 5988.8 3710.6 3821.7 3932.6 3936.6 3948.7 4762.5 4877.9 5137.6 5743.9 5815.2 5543.5 5556.3 5564.3 5571.5 5591.7 5234.7 5530.4 5550.8 5585.7 5622.4 5423.2 5430.8 5444.0 5448.0 5462.6 4737.9 5285.0 5513.6 5540.9 5561.4 5329.9 5397.7 5431.0 5449.6 5489.9 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25094.75 Median:4130.91 stddev:40635.33 (983.7%) stddev-sans-first:40708.17 11:42:57 INFO - PROCESS | 1661 | Values: 735.9 736.9 776.3 825.7 847.3 15440.8 16537.3 16542.5 16547.8 16552.8 14839.9 14973.9 15000.6 15019.0 15035.0 711.1 731.2 862.2 871.9 898.3 855.2 867.8 868.3 870.1 870.2 914.4 916.6 917.7 918.2 918.9 730.4 758.0 761.0 907.2 907.3 564.7 568.5 684.6 710.5 714.9 13666.3 13693.8 13697.2 13703.2 13715.8 14157.9 14424.2 14467.0 14535.3 14642.8 13072.2 13200.6 13331.3 13338.2 13837.0 4332.8 4418.0 4692.3 4802.0 4903.8 98521.8 106806.4 114494.0 116728.0 117681.0 11458.9 11480.5 11559.5 11722.4 11733.4 23015.3 23017.2 23018.0 23028.0 23047.7 7145.3 7767.2 8014.9 8059.3 8080.8 4332.5 4453.5 4459.0 4462.1 4495.7 3415.5 3489.4 3536.9 3543.7 3549.6 623.9 628.8 633.0 636.8 653.5 562.6 565.9 598.5 620.8 622.2 8631.1 8915.9 9215.3 9607.7 9783.5 645.6 647.2 657.9 669.5 677.8 3953.1 4308.7 4669.8 4690.5 4702.7 148222.0 150943.5 153892.0 154242.0 155147.0 123629.0 130960.0 135251.6 137633.0 141075.2 116573.4 117025.2 117774.0 118914.0 119467.0 9112.5 9172.0 9181.0 9192.8 9260.0 44159.8 47632.5 62750.0 63584.4 65741.3 84669.0 87809.0 88937.6 91159.5 93231.0 12572.6 13533.7 13572.0 13694.5 14050.9 13177.3 14553.3 14818.4 15251.2 15587.8 9412.5 12441.0 12446.3 14383.2 14618.9 9950.7 12008.5 12201.6 12221.7 12512.6 11830.2 12024.0 12034.1 12265.4 12388.5 9833.3 10049.0 10151.1 10204.3 10287.4 9559.0 10400.8 10406.1 10442.5 10446.9 11:42:57 INFO - PROCESS | 1661 | -------- Summary: end -------- 11:42:57 INFO - PROCESS | 1661 | 11:42:57 INFO - TEST-INFO | 1661: exit 0 11:42:57 INFO - TEST-OK | dromaeo_css | took 1117373ms 11:42:57 INFO - TEST-START | kraken 11:42:57 INFO - operating with platform_type : mac_ 11:42:57 INFO - Initialising browser for kraken test... 11:42:57 INFO - TEST-INFO | started process 1783 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpo9_jaq/profile http://localhost:49262/getInfo.html) 11:43:00 INFO - PROCESS | 1783 | __metrics Screen width/height:1600/1200 11:43:00 INFO - PROCESS | 1783 | colorDepth:24 11:43:00 INFO - PROCESS | 1783 | Browser inner width/height: 1024/689 11:43:00 INFO - PROCESS | 1783 | __metrics 11:43:00 INFO - PROCESS | 1783 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:43:00 INFO - PROCESS | 1783 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:43:00 INFO - TEST-INFO | 1783: exit 0 11:43:00 INFO - Browser initialized. 11:43:00 INFO - Running cycle 1/1 for kraken test... 11:43:00 INFO - TEST-INFO | started process 1784 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpo9_jaq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:43:12 INFO - PROCESS | 1784 | RSS: Main: 228818944 11:43:12 INFO - PROCESS | 1784 | 11:43:15 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 11:43:16 INFO - PROCESS | 1784 | RSS: Main: 293912576 11:43:16 INFO - PROCESS | 1784 | 11:43:18 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 11:43:19 INFO - PROCESS | 1784 | RSS: Main: 293982208 11:43:19 INFO - PROCESS | 1784 | 11:43:22 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 11:43:23 INFO - PROCESS | 1784 | RSS: Main: 379609088 11:43:23 INFO - PROCESS | 1784 | 11:43:25 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 11:43:26 INFO - PROCESS | 1784 | RSS: Main: 280367104 11:43:26 INFO - PROCESS | 1784 | 11:43:28 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:43:28 INFO - PROCESS | 1784 | RSS: Main: 284020736 11:43:28 INFO - PROCESS | 1784 | 11:43:31 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 11:43:32 INFO - PROCESS | 1784 | RSS: Main: 323424256 11:43:32 INFO - PROCESS | 1784 | 11:43:35 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 11:43:35 INFO - PROCESS | 1784 | RSS: Main: 322789376 11:43:35 INFO - PROCESS | 1784 | 11:43:38 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 11:43:39 INFO - PROCESS | 1784 | RSS: Main: 322449408 11:43:39 INFO - PROCESS | 1784 | 11:43:40 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:43:41 INFO - PROCESS | 1784 | RSS: Main: 302059520 11:43:41 INFO - PROCESS | 1784 | 11:43:43 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:43:43 INFO - PROCESS | 1784 | RSS: Main: 301023232 11:43:43 INFO - PROCESS | 1784 | 11:43:46 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:43:46 INFO - PROCESS | 1784 | RSS: Main: 306802688 11:43:46 INFO - PROCESS | 1784 | 11:43:49 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:43:50 INFO - PROCESS | 1784 | RSS: Main: 316416000 11:43:50 INFO - PROCESS | 1784 | 11:43:53 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:43:54 INFO - PROCESS | 1784 | RSS: Main: 318816256 11:43:54 INFO - PROCESS | 1784 | 11:43:55 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 11:43:56 INFO - PROCESS | 1784 | RSS: Main: 362450944 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | __start_tp_report 11:43:56 INFO - PROCESS | 1784 | _x_x_mozilla_page_load 11:43:56 INFO - PROCESS | 1784 | _x_x_mozilla_page_load_details 11:43:56 INFO - PROCESS | 1784 | |i|pagename|runs| 11:43:56 INFO - PROCESS | 1784 | |0;ai-astar;97;115;107;112;113;112;116;112;113;113 11:43:56 INFO - PROCESS | 1784 | |1;audio-beat-detection;146;134;161;183;109;129;126;109;162;106 11:43:56 INFO - PROCESS | 1784 | |2;audio-dft;185;183;184;183;181;184;178;182;183;182 11:43:56 INFO - PROCESS | 1784 | |3;audio-fft;70;97;83;153;58;57;56;85;92;57 11:43:56 INFO - PROCESS | 1784 | |4;audio-oscillator;83;91;136;71;72;72;114;72;89;146 11:43:56 INFO - PROCESS | 1784 | |5;imaging-gaussian-blur;86;83;86;84;86;84;87;83;86;83 11:43:56 INFO - PROCESS | 1784 | |6;imaging-darkroom;91;102;87;130;150;113;92;94;93;92 11:43:56 INFO - PROCESS | 1784 | |7;imaging-desaturate;84;87;85;84;84;81;80;84;84;84 11:43:56 INFO - PROCESS | 1784 | |8;json-parse-financial;54;53;54;54;54;54;55;54;56;54 11:43:56 INFO - PROCESS | 1784 | |9;json-stringify-tinderbox;67;66;70;82;74;71;69;68;68;70 11:43:56 INFO - PROCESS | 1784 | |10;stanford-crypto-aes;86;85;87;84;85;84;86;87;90;85 11:43:56 INFO - PROCESS | 1784 | |11;stanford-crypto-ccm;143;137;137;136;137;142;120;143;227;153 11:43:56 INFO - PROCESS | 1784 | |12;stanford-crypto-pbkdf2;170;165;166;216;230;166;165;165;167;225 11:43:56 INFO - PROCESS | 1784 | |13;stanford-crypto-sha256-iterative;68;68;69;68;66;68;67;67;67;68 11:43:56 INFO - PROCESS | 1784 | __end_tp_report 11:43:56 INFO - PROCESS | 1784 | __start_cc_report 11:43:56 INFO - PROCESS | 1784 | _x_x_mozilla_cycle_collect,2072 11:43:56 INFO - PROCESS | 1784 | __end_cc_report 11:43:56 INFO - PROCESS | 1784 | __startTimestamp1471977836065__endTimestamp 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | ------- Summary: start ------- 11:43:56 INFO - PROCESS | 1784 | Number of tests: 14 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#0] ai-astar Cycles:10 Average:111.00 Median:113.00 stddev:5.46 (4.8%) stddev-sans-first:2.51 11:43:56 INFO - PROCESS | 1784 | Values: 97.0 115.0 107.0 112.0 113.0 112.0 116.0 112.0 113.0 113.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:140.00 stddev:26.07 (18.6%) stddev-sans-first:27.43 11:43:56 INFO - PROCESS | 1784 | Values: 146.0 134.0 161.0 183.0 109.0 129.0 126.0 109.0 162.0 106.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.96 (1.1%) stddev-sans-first:1.86 11:43:56 INFO - PROCESS | 1784 | Values: 185.0 183.0 184.0 183.0 181.0 184.0 178.0 182.0 183.0 182.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.50 stddev:29.83 (39.0%) stddev-sans-first:31.38 11:43:56 INFO - PROCESS | 1784 | Values: 70.0 97.0 83.0 153.0 58.0 57.0 56.0 85.0 92.0 57.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:72.00 stddev:27.89 (38.7%) stddev-sans-first:29.26 11:43:56 INFO - PROCESS | 1784 | Values: 83.0 91.0 136.0 71.0 72.0 72.0 114.0 72.0 89.0 146.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:86.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 11:43:56 INFO - PROCESS | 1784 | Values: 86.0 83.0 86.0 84.0 86.0 84.0 87.0 83.0 86.0 83.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:91.50 stddev:20.66 (22.6%) stddev-sans-first:21.34 11:43:56 INFO - PROCESS | 1784 | Values: 91.0 102.0 87.0 130.0 150.0 113.0 92.0 94.0 93.0 92.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:84.00 stddev:1.95 (2.3%) stddev-sans-first:2.06 11:43:56 INFO - PROCESS | 1784 | Values: 84.0 87.0 85.0 84.0 84.0 81.0 80.0 84.0 84.0 84.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.83 11:43:56 INFO - PROCESS | 1784 | Values: 54.0 53.0 54.0 54.0 54.0 54.0 55.0 54.0 56.0 54.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.00 stddev:4.62 (6.6%) stddev-sans-first:4.73 11:43:56 INFO - PROCESS | 1784 | Values: 67.0 66.0 70.0 82.0 74.0 71.0 69.0 68.0 68.0 70.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:1.79 (2.1%) stddev-sans-first:1.90 11:43:56 INFO - PROCESS | 1784 | Values: 86.0 85.0 87.0 84.0 85.0 84.0 86.0 87.0 90.0 85.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:142.50 stddev:29.14 (20.4%) stddev-sans-first:30.86 11:43:56 INFO - PROCESS | 1784 | Values: 143.0 137.0 137.0 136.0 137.0 142.0 120.0 143.0 227.0 153.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.50 Median:168.50 stddev:27.96 (16.6%) stddev-sans-first:29.22 11:43:56 INFO - PROCESS | 1784 | Values: 170.0 165.0 166.0 216.0 230.0 166.0 165.0 165.0 167.0 225.0 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - PROCESS | 1784 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.84 (1.2%) stddev-sans-first:0.88 11:43:56 INFO - PROCESS | 1784 | Values: 68.0 68.0 69.0 68.0 66.0 68.0 67.0 67.0 67.0 68.0 11:43:56 INFO - PROCESS | 1784 | -------- Summary: end -------- 11:43:56 INFO - PROCESS | 1784 | 11:43:56 INFO - TEST-INFO | 1784: exit 0 11:43:56 INFO - TEST-OK | kraken | took 58987ms 11:43:56 INFO - SUITE-END | took 1176s 11:43:57 INFO - Completed test suite (00:19:36) 11:43:57 INFO - javascript benchmark 11:43:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2683.9060632877854, 3267.1342094344996, 3305.173706573357, 3334.883279085231, 3353.698167164956, 5503.91744123838, 5508.862278443041, 5627.184058311543, 5707.315122185339, 5736.455036771506, 5590.524805391547, 5633.408492108331, 5656.773729050843, 5714.1428785682165, 5726.226959360485, 5071.56891664209, 5091.872703182432, 5168.586513078963, 5170.482951704818, 5245.055889939809, 5211.687298762067, 5216.7391630418515, 5262.184361423985, 5263.710495922723, 5276.34045744282, 5052.469490703496, 5068.518490743373, 5071.3407257056715, 5103.4896510349035, 5226.1899405592, 5135.717535535544, 5146.974265128688, 5163.979072242742, 5193.792248310063, 5226.738663066832, 5143.151380022292, 5172.405173405069, 5178.171492561172, 5188.7146206958605, 5199.922001169985, 5500.3949565547755, 5621.988042152411, 5674.375818659944, 5744.023516002248, 5769.3076830780155, 6112.174856394375, 6263.0, 6264.592801467936, 6273.529485288622, 6281.340459251758, 5957.374475680034, 6269.968650156766, 6305.87388252237, 6343.111964324999, 6352.904706429407, 4851.369321988154, 5638.830835074954, 5694.572907031907, 5729.656220626775, 5786.797462088891, 6054.636721796706, 6346.428821406095, 6347.587406818542, 6365.2679941806355, 6384.52116091295, 5437.864053398696, 6102.8169154924535, 6115.296740874767, 6125.84685382869, 6133.233345831771, 5996.220491336098, 6126.417990290915, 6194.38056194377, 6225.750969961151, 6272.153259310026, 6153.661548614779, 6164.845878853064, 6195.504359651217, 6197.411245931629, 6248.593841400385, 6209.068639704081, 6221.875562488724, 6223.408776166257, 6226.782062627786, 6233.283172435228, 4630.9536904631195, 4679.789409476582, 4757.167495688241, 4812.590929770939, 4818.4217893852265, 4399.054203346296, 4404.097160082176, 4419.845305414295, 4421.955780442218, 4485.237509623372, 5727.541796656257, 5727.8854422912145, 5733.31200255972, 5735.397783232784, 5739.827805165852, 6105.206323177958, 6105.358937311607, 6184.5980011299125, 6328.905066424008, 6332.271788744261, 5630.971845140748, 5636.915446268309, 5692.345380281321, 5694.914576281359, 5772.336181339116, 5390.676559406448, 5436.374816896028, 5438.83683489496, 5441.755121019563, 5453.072977593819, 6309.652969086653, 6309.842253943688, 6317.336679648571, 6321.652309123042, 6322.589031712924, 4919.729414882073, 6164.845878853064, 6230.59501132425, 6285.402886725754, 6289.245290565162, 5556.611037227355, 6318.715657795502, 6319.9052014218905, 6321.8735625288155, 6337.714802833976, 6118.082287656887, 6131.938680613315, 6152.692365381624, 6157.476614487819, 6163.445289923929, 6105.961986462224, 6178.1350610913605, 6199.597026193283, 6277.686115694106, 6286.9371306288185, 6059.666718330534, 6116.204893363834, 6119.938800611937, 6125.663088529996, 6141.539384546088, 6106.6336019838955, 6133.785317513865, 6135.11040899076, 6142.662153581596, 6145.35473775265, 5707.857303567444, 5733.426657334233, 5761.942380576141, 5783.190353350618, 5932.377100404396, 6000.909986350295, 6005.819825405245, 6008.309044459943, 6016.368281330398, 6021.006533921854, 5788.942110578841, 5796.652200867793, 5840.123981402823, 5840.474357307863, 5877.500412464989], "value": 5645.147011062654, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.97475638839853, 510.82631905152266, 519.5479932458761, 524.4807640435969, 533.6611251855071, 31413.528797068033, 34205.0, 41353.793231033844, 42592.361114583226, 42800.0, 43336.783316083376, 43629.56370436295, 43655.78172109143, 43777.0, 43812.561874381245, 9786.902130978706, 9984.650537231199, 10041.096301332878, 10042.899571004305, 10045.89954100457, 8984.685536006242, 9190.0, 9190.954045229799, 9203.4477931324, 9211.907880921206, 12114.515419383215, 12389.62831115068, 12400.503979840796, 12605.621831345074, 12635.873641263608, 10201.744956376077, 12284.938575307066, 12300.753984920297, 12303.753924921451, 12313.07651926109, 12195.756084878252, 12287.815682764765, 12288.0, 12293.200941938834, 12305.815412768816, 41355.58644413547, 41632.37551436731, 41704.58295417068, 41725.0, 41772.37341439881, 3427.3145370925913, 3497.3704733148024, 3503.754737168399, 3929.8035098244973, 4281.357796330544, 31363.529547056583, 32531.83734081338, 32541.674583254102, 32713.0, 32824.50763238553, 3202.2154572129743, 3355.798652080883, 3404.165979335054, 3432.7597068205237, 3462.2036931505895, 412715.0, 456255.0, 457184.0, 457883.0, 465478.67260663817, 61830.0, 62377.37622623716, 62396.06405903915, 62431.37568624256, 62597.0, 64470.35529644737, 64501.67749161318, 64601.0, 64633.0, 64717.35282647207, 16369.754453683205, 16589.336426542803, 16611.91694041522, 16633.916830415772, 16642.83357166437, 12846.743065138831, 12892.097553171188, 12915.16051456671, 12925.935370323088, 12942.352882355844, 15241.3903443861, 15315.61710957235, 15616.687666246611, 17396.0, 17730.5567360817, 6421.8073457796345, 6501.586666999769, 6571.901421478682, 6587.9670601646685, 6594.8351291218105, 896.3591032411726, 989.4656885282011, 1015.0, 1043.0612448796144, 1128.9500764289126, 24935.625965610532, 30433.48131890085, 30541.389172216877, 31146.582644710146, 31503.36993260168, 990.5988074829705, 1016.6492560066765, 1061.920355973305, 1086.5870969031716, 1128.8250321200062, 15958.573847265732, 15988.600284992968, 16043.0, 16200.51398458048, 16479.0, 658465.0, 673906.0, 674922.0, 675678.0, 675866.0, 50486.495135048914, 60853.0, 60864.0, 60881.0, 60893.39106608966, 1201.2792324605166, 1249.7500499900057, 1262.7916393795106, 1412.2867951684539, 1416.8795652369663, 30860.382792344477, 31890.840545797124, 32043.67956320407, 32849.67150328466, 33035.669643303256, 51787.48212517826, 55058.0, 57195.0, 57232.0, 62649.0, 590724.0, 593468.0, 593476.0, 594993.0, 595478.0, 25288.873555632104, 26570.202893913214, 26733.0, 27349.589756153277, 27769.861150694116, 40575.0, 49074.26388604245, 49093.509064908896, 49464.0, 50041.0, 37979.05052373602, 40219.99450013773, 40248.0, 40741.0, 41907.0, 34367.0, 39802.40296395614, 40481.59518404778, 40975.38536922008, 41265.0, 38144.80927595344, 38634.04587174336, 39098.0, 39195.41206881842, 39250.411243831935, 34462.48306275458, 34831.12922176966, 35953.28093438169, 35998.64001359953, 36005.45991810073, 25536.23391298264, 25814.741852581235, 26077.348066298495, 26115.347116322246, 26319.868400657873], "value": 20620.519892612374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.49417086480884, 372.23877171184927, 374.73206657240087, 377.8564145624662, 401.64645145858464, 15609.921950390246, 15981.280842362092, 20072.49818754532, 20542.794572054274, 20620.48448788781, 21246.0, 21437.892810535963, 21750.782492175073, 21761.564768704615, 21911.904404779776, 5950.285965684124, 5964.284285885689, 6168.228971378578, 6250.156228909107, 6264.906026409608, 4265.936010959823, 6101.877962440749, 6206.255249370082, 6208.565400421972, 6216.906746398808, 4425.955740442602, 6233.437143354533, 6809.421199198074, 6864.485163612724, 6864.759733409332, 6566.967165164191, 6821.55659882111, 6841.247462779091, 6850.588964662112, 6857.0, 5318.867028324285, 5700.828975130753, 5914.201582786355, 5934.465898069195, 5942.465178133945, 23890.402739931465, 25519.617205741928, 26095.869520652464, 26168.084117056, 26578.6710664466, 2513.0576033987254, 2564.807639427032, 2568.768810807018, 2722.891084356623, 2893.4357800228963, 10233.283670143093, 14213.502527411594, 14924.477643282542, 14965.0, 15843.920780396023, 1633.8774591905535, 1785.803561608222, 1800.2708902894349, 1862.8416584590327, 1907.399169261678, 124521.0, 128970.0, 130879.0, 132075.0, 132511.33744331216, 9451.243900487945, 10952.890471095347, 11385.487653055632, 11426.88573114275, 11482.540698372139, 14320.997530172788, 14380.496682616267, 14381.78427323591, 14396.640083997983, 14404.85595144056, 8248.340132789483, 8526.658933642584, 8555.828883422297, 8651.653933842701, 8674.913250867537, 8458.957705211435, 8477.237048665527, 8502.234798868132, 8665.696700615574, 8669.523176225368, 3700.6299370063316, 4441.578050085237, 4475.776211189427, 4506.684532082722, 4560.4071470708595, 1594.3702237616076, 1732.8007279162805, 1827.0079875472136, 1889.8866068035961, 1903.9428817135508, 671.7078071039114, 707.8183564875916, 888.7911340834899, 937.1565590968047, 973.8539219117189, 21222.0, 21236.0, 22147.889260553915, 22447.775522244894, 23430.882845586006, 899.2042042792176, 947.1286416496839, 979.9216062714966, 982.062130665213, 984.3355734879071, 10438.425886576159, 11239.94380028111, 11377.189205774253, 11407.201495895373, 11538.0, 469213.0, 470186.0, 611926.0, 615950.0, 638538.0, 117281.0, 140156.0, 141427.0, 142061.28969355294, 145827.0, 434.5697759218414, 455.3897776978864, 459.4326007380882, 459.72186826969204, 461.2274440312463, 11143.052840508324, 11196.94401527987, 11343.48954297075, 11455.369954652575, 11564.190506664452, 46505.37231196892, 47828.0, 55615.72192139013, 57404.0, 57943.551411215056, 82386.0, 82762.0, 83838.0, 84752.0, 85010.1498985027, 7055.788326350218, 7654.846903062019, 7735.187805280365, 8091.919080809116, 8132.959335203286, 11444.656660300205, 12659.87340126587, 12844.293563854078, 13166.47334106625, 13214.141080829715, 9806.803863922823, 11076.113910886948, 11611.245269057485, 11685.298882067103, 11770.58802941893, 11159.776804463703, 11186.608468703556, 11525.596604118815, 11706.063514918616, 11801.29192248468, 11303.660890173307, 11433.256838305484, 11808.232464889754, 11842.585509507126, 11880.643580692593, 8230.588470576567, 8247.752567422987, 8304.792380190544, 8344.749657510285, 8429.861968634401, 9440.197583205505, 9591.28065395112, 11354.886451135382, 11540.422978851191, 11570.487509871326], "value": 9426.211389495382, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.148133897653, 1527.19822093401, 1666.6083470384458, 1828.9634207315846, 1848.6949653307213, 8435.70475033374, 8979.730608081753, 9008.639654413824, 9015.14356136166, 9033.141851524111, 6541.901871471931, 8039.276465118138, 8449.0, 8651.913480865189, 8802.64789408423, 2332.4402143485527, 2363.917262895799, 2364.444355576447, 2369.0642196332437, 2371.1700904683394, 2328.93013209604, 2354.1878052072007, 2369.8815059247054, 2374.881255937205, 2376.3821406434363, 2346.6010778167665, 2355.2463211772238, 2357.6581395697613, 2359.6578496118054, 2371.0989823866907, 1874.3627166763229, 2322.547103314854, 2337.2871274261342, 2348.5889969255313, 2355.3993731598503, 1950.7171460138343, 1959.3925882976323, 2345.5543446745064, 2350.74141844397, 2356.1164563288767, 8815.118488151198, 8854.645814167425, 8950.820983580292, 8955.820883582292, 9033.322500812465, 5182.170852663556, 5342.545883599899, 5348.064088784447, 5361.490658387447, 5372.355317361941, 3108.5246751750115, 3256.576645036154, 3604.2431089471243, 3731.776093434403, 3736.346139425617, 1114.1031469666914, 1128.949197286124, 1781.4477511971202, 1830.405118336546, 1879.9060046997731, 11643.01034412076, 11650.53397864093, 11685.532578696842, 11692.240004399688, 11714.355710435922, 4304.74171549708, 4584.472785629629, 4620.283856002325, 4621.098885717252, 4633.837815676435, 9203.539823008823, 9204.263658907272, 9213.0, 9217.308701847389, 9253.444793312423, 5251.711155886386, 5254.97372513135, 5255.5795536357, 5256.684598924076, 5265.131253343232, 3679.411294192916, 4345.847895323648, 4811.759412029384, 4897.755112244374, 4918.729469879119, 3297.5713157289397, 3351.949720754191, 3759.605241449663, 3800.8289626966853, 3860.0, 1004.5278719002057, 1172.4841069929205, 1180.6681803438141, 1193.0, 1197.9580714674944, 904.4980036079908, 922.686286662538, 926.9582868770786, 937.4438432202511, 982.0719420147892, 5741.7990370337675, 5966.015607424726, 6055.636661800306, 6167.620242701341, 6180.969095154585, 453.3652885959683, 455.5717625432083, 463.3605624238529, 472.98581042568776, 476.8569429171235, 4006.6193711597352, 4017.77902215381, 4087.4890638670167, 4108.67130629549, 4134.710570260053, 10439.060484556427, 10938.343699378063, 10964.890351096547, 10971.23201375896, 10996.890031099747, 10915.508802103925, 10926.890731092588, 10926.945365273123, 10940.070094041936, 10948.124150067975, 8264.710735124241, 8305.044919834176, 8335.95832020836, 8340.457870238415, 8391.790205244917, 5299.443558426464, 5749.252597162342, 5987.191729116514, 6098.939010609837, 6219.564630475823, 8230.670773169006, 9429.3399462037, 9759.512024398893, 9861.605535778488, 9939.701808945743, 9292.674756383494, 9658.90341096608, 10322.48387580633, 10339.63811266602, 10339.689809305733, 7364.41084713211, 7570.545767253982, 7634.045744281965, 7680.6543705534505, 7766.805829854299, 8272.669093236203, 8381.161883811814, 8452.0, 8453.830923381374, 8463.365247606578, 7351.926480735338, 7419.183889772229, 7482.551046937201, 7621.1616722161625, 7629.961850190713, 6614.239362473377, 7175.605341706257, 7341.6696248670005, 7373.115226172894, 7465.06686664167, 6964.512484126062, 7019.684114214975, 7071.929280707127, 7147.714091436493, 7159.642017899188, 5038.571721403721, 5092.668976516515, 5127.2052831811125, 5158.3552055993005, 5245.895082098413, 4895.8041678332465, 4996.975015125046, 5064.302659508054, 5323.414424413234, 5339.893202136013], "value": 4511.365088210487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.5340628837878, 308.3247687564234, 312.219451371571, 316.7291965369607, 319.8656564243018, 4566.337881007252, 5260.684358938462, 5380.24676545284, 5382.300300960879, 5415.837524874255, 4473.753943533105, 4617.0, 5192.92210616841, 5331.493508116732, 5340.279062326585, 3099.6361600895534, 3903.726739128262, 3967.642912137907, 4092.3452247640384, 4126.071633882382, 3582.964170358302, 3991.8602848900296, 4024.839006439739, 4037.1336351190544, 4057.97971010146, 3701.666849983501, 3979.4030895365636, 3999.440078389028, 4026.396040593905, 4046.332355161395, 3241.2383089973837, 3799.5630502492013, 3910.4720862683744, 4032.9798351008203, 4059.8782036538805, 3481.918502125637, 3483.7038851697635, 3489.232368878868, 3510.859565617372, 3544.3088597723454, 4651.279051746985, 4915.5084491550915, 5059.2916991621205, 5088.338515992898, 5088.6946783193125, 3824.9808750956345, 4004.2191772604356, 4017.7589344639414, 4177.874663760092, 4345.456817897762, 4728.763561821896, 5372.979133964534, 5416.995711586699, 5420.674759514442, 5508.669479831223, 3233.9514907276175, 3712.4407748745593, 3746.98831315546, 3779.45197946299, 3784.3944968805135, 5445.264889239981, 5747.792963477675, 5760.041585860786, 5780.3063632363965, 5798.0, 3771.2080463103057, 3798.506194194737, 3805.5052843130215, 3815.523059617548, 3830.885073447801, 3861.2856621525107, 3895.49358583388, 3897.232964595169, 3902.8681682311812, 3946.625070618287, 3800.1259710266795, 4593.885152871138, 4594.356790049395, 4598.379218805485, 4648.2562789953445, 5035.597152227813, 5145.536901678792, 5432.864178395572, 5467.726613669301, 5536.283752036801, 1116.5198964445203, 1178.5462596900254, 1267.5359959247085, 1285.190330092036, 1323.6889331007205, 762.7864198024562, 775.3951917504355, 785.4187900953368, 803.702630026882, 805.7139715400948, 4181.372794080851, 4311.827526898889, 4503.369528266046, 4578.748168850745, 4988.72562009093, 5168.069747445421, 5300.284461597635, 5695.345035320908, 5696.974544582015, 5748.827535173951, 4372.6939114261695, 4916.5820905222745, 5093.414257360377, 5124.693887750527, 5158.819441319536, 2396.676448679406, 2983.925401864927, 2988.520682262294, 2988.9850550747105, 2997.430488207234, 4975.825846095369, 5878.794847056343, 5885.852853678693, 5889.819497471318, 5905.704714764244, 5342.946570534323, 5761.740721667535, 5781.571739869265, 5831.591788574759, 5846.970765146232, 5296.947030529645, 5483.163140347949, 5947.732352044169, 5964.701764911824, 5998.640081595118, 5390.460953904578, 5644.717764111861, 5690.687012214368, 5736.569757268138, 5757.539396848326, 5513.476219759116, 5637.802676906289, 5703.602617358773, 5708.058170401837, 5717.8570535736935, 5452.991196628478, 5659.886802264014, 5945.910811337747, 5976.505873531617, 5988.760449582142, 3710.6474884885893, 3821.7324787264624, 3932.646061854447, 3936.5669776324007, 3948.723589348833, 4762.52374762521, 4877.9024419512125, 5137.614678899172, 5743.8851222976145, 5815.244018277597, 5543.501084902378, 5556.305461817273, 5564.33228012641, 5571.495696162095, 5591.692456914746, 5234.659747116425, 5530.391656917655, 5550.805721799879, 5585.748641311232, 5622.3815380307315, 5423.240746295601, 5430.83707488776, 5443.972780136074, 5448.0, 5462.590305727007, 4737.881552961066, 5284.9735751320995, 5513.566472717042, 5540.891821635609, 5561.443855614568, 5329.946700532945, 5397.676139431646, 5430.972845135749, 5449.564034877121, 5489.890202195854], "value": 4025.0604769749725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.889616557516, 736.9078865141856, 776.2547953964194, 825.7481468152215, 847.2586486824028, 15440.768388474182, 16537.338506459742, 16542.503724888244, 16547.83452165478, 16552.75170872438, 14839.851601483982, 14973.92513037436, 15000.624984375396, 15019.023763455367, 15035.02272352299, 711.0898050495348, 731.2212493694199, 862.1593945902741, 871.9346049046316, 898.285862739124, 855.2302927365358, 867.761365624452, 868.3183700794877, 870.1037930931151, 870.1907226279558, 914.4421902639384, 916.6425094213262, 917.7338571814166, 918.1598837064079, 918.8897332320098, 730.390124246255, 757.9956557561253, 760.9657565409569, 907.2107266678032, 907.3467103685336, 564.7317524176025, 568.4656422962444, 684.5926673629183, 710.4919982212742, 714.8963400306978, 13666.316684165751, 13693.794593081111, 13697.178169309873, 13703.177809331371, 13715.794263086062, 14157.858421415756, 14424.206668633222, 14467.0, 14535.345909434101, 14642.780358294634, 13072.215667060007, 13200.603981880558, 13331.266780327078, 13338.19970801755, 13837.0, 4332.805023773938, 4417.983863711364, 4692.319613656035, 4801.975990120062, 4903.754812259408, 98521.81479179615, 106806.3979040315, 114494.0, 116728.0, 117681.0, 11458.94270528642, 11480.540778368771, 11559.479823407799, 11722.355270460206, 11733.4133293335, 23015.309540713806, 23017.19439819598, 23018.0, 23028.0, 23047.654285185734, 7145.321194486515, 7767.223277672188, 8014.919850801417, 8059.30241838928, 8080.797980050428, 4332.545082766327, 4453.51011388747, 4459.0, 4462.107578484316, 4495.707778994355, 3415.5047518109736, 3489.3719130556237, 3536.876209332661, 3543.6633519815578, 3549.5563054618174, 623.9032949892774, 628.7579281976381, 632.9556931014877, 636.7930422612669, 653.5066025151076, 562.6285207848937, 565.8981383351037, 598.5451057196562, 620.8478922663886, 622.2284367384501, 8631.136886311444, 8915.866262006075, 9215.308851836138, 9607.711768646952, 9783.46190959504, 645.5674697952384, 647.1683886206202, 657.8881590129594, 669.493639311549, 677.7788191652497, 3953.0864444200033, 4308.74147551148, 4669.813207471731, 4690.507496712864, 4702.741349225757, 148222.0, 150943.49056509513, 153892.0, 154242.0, 155147.0, 123629.0, 130960.0, 135251.64748352589, 137633.0, 141075.1871053545, 116573.41713291379, 117025.24462133074, 117774.0, 118914.0, 119467.0, 9112.498812565373, 9171.954140229256, 9181.0, 9192.770180745267, 9259.953700231456, 44159.77920110379, 47632.52367476281, 62750.0, 63584.36415635784, 65741.34258657352, 84669.0, 87809.0, 88937.55531222561, 91159.54420228122, 93231.0, 12572.622821315375, 13533.729325413633, 13571.98210134224, 13694.452221911011, 14050.859491404955, 13177.275249860966, 14553.272336382926, 14818.369499472697, 15251.161186134868, 15587.76618350703, 9412.48231347255, 12441.0, 12446.25322480654, 14383.208923509308, 14618.85381146175, 9950.701478955643, 12008.519659213884, 12201.572944946884, 12221.694457638274, 12512.562060327844, 11830.17188796776, 12024.0, 12034.097442691658, 12265.448054837376, 12388.504459821506, 9833.262505311986, 10049.0, 10151.086402223835, 10204.336718113298, 10287.43419111926, 9559.0, 10400.791984160123, 10406.063454289151, 10442.477876106317, 10446.895531044593], "value": 7788.342704368249, "unit": "score"}], "name": "dromaeo_css", "value": 7330.737474213865, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 115.0, 107.0, 112.0, 113.0, 112.0, 116.0, 112.0, 113.0, 113.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 134.0, 161.0, 183.0, 109.0, 129.0, 126.0, 109.0, 162.0, 106.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 183.0, 181.0, 184.0, 178.0, 182.0, 183.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 97.0, 83.0, 153.0, 58.0, 57.0, 56.0, 85.0, 92.0, 57.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 136.0, 71.0, 72.0, 72.0, 114.0, 72.0, 89.0, 146.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 84.0, 87.0, 83.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 102.0, 87.0, 130.0, 150.0, 113.0, 92.0, 94.0, 93.0, 92.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 85.0, 84.0, 84.0, 81.0, 80.0, 84.0, 84.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 56.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 82.0, 74.0, 71.0, 69.0, 68.0, 68.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 85.0, 84.0, 86.0, 87.0, 90.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 137.0, 137.0, 136.0, 137.0, 142.0, 120.0, 143.0, 227.0, 153.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 166.0, 216.0, 230.0, 166.0, 165.0, 165.0, 167.0, 225.0], "value": 183.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 66.0, 68.0, 67.0, 67.0, 67.0, 68.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1487.5, "alertThreshold": 2.0}]} 11:43:57 INFO - Return code: 0 11:43:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:43:57 INFO - # TBPL SUCCESS # 11:43:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:43:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:43:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:43:57 INFO - 2016-08-23 11:43:57.390 screenresolution[1794:9848] starting screenresolution argv=screenresolution get 11:43:57 INFO - 2016-08-23 11:43:57.392 screenresolution[1794:9848] Display 0: 1600x1200x32@60 11:43:57 INFO - 2016-08-23 11:43:57.398 screenresolution[1795:9851] starting screenresolution argv=screenresolution list 11:43:57 INFO - Available Modes on Display 0 11:43:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:43:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:43:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:43:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:43:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:43:57 INFO - Intel Iris: 11:43:57 INFO - Chipset Model: Intel Iris 11:43:57 INFO - Type: GPU 11:43:57 INFO - Bus: Built-In 11:43:57 INFO - VRAM (Dynamic, Max): 1536 MB 11:43:57 INFO - Vendor: Intel (0x8086) 11:43:57 INFO - Device ID: 0x0a2e 11:43:57 INFO - Revision ID: 0x0009 11:43:57 INFO - Displays: 11:43:57 INFO - Display: 11:43:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:43:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:43:57 INFO - Display Serial Number: 1600x1200 60 11:43:57 INFO - Main Display: Yes 11:43:57 INFO - Mirror: Off 11:43:57 INFO - Online: Yes 11:43:57 INFO - Rotation: Supported 11:43:57 INFO - Return code: 0 11:43:57 INFO - Running post-action listener: _resource_record_post_action 11:43:57 INFO - [mozharness: 2016-08-23 18:43:57.634581Z] Finished run-tests step (success) 11:43:57 INFO - Running post-run listener: _resource_record_post_run 11:43:57 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37295104; Write bytes: 707095040; Read time: 239; Write time: 2033 11:43:57 INFO - TinderboxPrint: CPU usage
53.7% 11:43:57 INFO - TinderboxPrint: I/O read bytes / time
37,295,104 / 239 11:43:57 INFO - TinderboxPrint: I/O write bytes / time
707,095,040 / 2,033 11:43:57 INFO - TinderboxPrint: CPU idle
2,244.2 (46.3%) 11:43:57 INFO - TinderboxPrint: CPU system
972.7 (20.1%) 11:43:57 INFO - TinderboxPrint: CPU user
1,628.6 (33.6%) 11:43:57 INFO - TinderboxPrint: Swap in / out
443,387,904 / 0 11:43:57 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401953280; Write bytes: 396121600; Read time: 30083; Write time: 818 11:43:57 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34587648; Write bytes: 296809472; Read time: 214; Write time: 1175 11:43:58 INFO - Running post-run listener: _upload_blobber_files 11:43:58 INFO - Blob upload gear active. 11:43:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:43:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:43:58 INFO - (blobuploader) - INFO - Open directory for files ... 11:43:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:43:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:43:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:59 INFO - (blobuploader) - INFO - Done attempting. 11:43:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:43:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:44:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:00 INFO - (blobuploader) - INFO - Done attempting. 11:44:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:44:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:44:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:03 INFO - (blobuploader) - INFO - Done attempting. 11:44:03 INFO - (blobuploader) - INFO - Iteration through files over. 11:44:03 INFO - Return code: 0 11:44:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:44:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:44:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47e29e0ef684867c76792474309972ecad11065440b9638e69ff7eee6023fd31241e7cc000415919ef4d058fafb2bf394df4d174fce92e0e2435771b6aedf55d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3dc97a269dbfc5bdb2f1b50752bc284c9a844131e2586d568abb7e5a183644f74174ceb2a5587bbbf3673f4cd02c927bfcab730fff60a10061947679c7b95606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/845cdb14eb0f5259a1ff14f7837d9f06b88c641ed66b9f48e67f3dd6d5feae257b523b1458e085477c05a8073dec4fbec1920d52329f58d41109261be93ae9ec"} 11:44:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:44:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:44:03 INFO - Contents: 11:44:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47e29e0ef684867c76792474309972ecad11065440b9638e69ff7eee6023fd31241e7cc000415919ef4d058fafb2bf394df4d174fce92e0e2435771b6aedf55d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3dc97a269dbfc5bdb2f1b50752bc284c9a844131e2586d568abb7e5a183644f74174ceb2a5587bbbf3673f4cd02c927bfcab730fff60a10061947679c7b95606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/845cdb14eb0f5259a1ff14f7837d9f06b88c641ed66b9f48e67f3dd6d5feae257b523b1458e085477c05a8073dec4fbec1920d52329f58d41109261be93ae9ec"} 11:44:03 INFO - Running post-run listener: copy_logs_to_upload_dir 11:44:03 INFO - Copying logs to upload dir... 11:44:03 INFO - mkdir: /builds/slave/test/build/upload/logs 11:44:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.001699 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-23 11:44:03.984186) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 11:44:03.988981) ========= 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.StmgNpbdHq/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.5baC9gqFHb/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47e29e0ef684867c76792474309972ecad11065440b9638e69ff7eee6023fd31241e7cc000415919ef4d058fafb2bf394df4d174fce92e0e2435771b6aedf55d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3dc97a269dbfc5bdb2f1b50752bc284c9a844131e2586d568abb7e5a183644f74174ceb2a5587bbbf3673f4cd02c927bfcab730fff60a10061947679c7b95606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/845cdb14eb0f5259a1ff14f7837d9f06b88c641ed66b9f48e67f3dd6d5feae257b523b1458e085477c05a8073dec4fbec1920d52329f58d41109261be93ae9ec"} build_url:https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010977 build_url: 'https://queue.taskcluster.net/v1/task/LlFnVcpIS6mxPWzVu7pAUA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/47e29e0ef684867c76792474309972ecad11065440b9638e69ff7eee6023fd31241e7cc000415919ef4d058fafb2bf394df4d174fce92e0e2435771b6aedf55d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3dc97a269dbfc5bdb2f1b50752bc284c9a844131e2586d568abb7e5a183644f74174ceb2a5587bbbf3673f4cd02c927bfcab730fff60a10061947679c7b95606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/845cdb14eb0f5259a1ff14f7837d9f06b88c641ed66b9f48e67f3dd6d5feae257b523b1458e085477c05a8073dec4fbec1920d52329f58d41109261be93ae9ec"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 11:44:04.027312) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-23 11:44:04.029806) ========= 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.StmgNpbdHq/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.5baC9gqFHb/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.005003 ========= master_lag: 3.50 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-23 11:44:07.531935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 11:44:07.532365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 11:44:07.533736) ========= ========= Total master_lag: 3.90 =========