builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0140 starttime: 1468323640.72 results: success (0) buildid: 20160712021725 builduid: c121bab07f6947df9fb9617ce72c76d6 revision: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:40.716627) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:40.717646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:40.718134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:40.862076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:40.862496) ========= 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.eSb5QcmCP1/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.2clA0bqjWv/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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-07-12 04:40:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-07-12 04:40:41 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.023469) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.023834) ========= 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.eSb5QcmCP1/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.2clA0bqjWv/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.028055 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.072736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.073153) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb --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 fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eSb5QcmCP1/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.2clA0bqjWv/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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-07-12 04:40:41,153 truncating revision to first 12 chars 2016-07-12 04:40:41,153 Setting DEBUG logging. 2016-07-12 04:40:41,153 attempt 1/10 2016-07-12 04:40:41,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fcfa26398425?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 04:40:41,521 unpacking tar archive at: autoland-fcfa26398425/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654623 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.760873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.761821) ========= script_repo_revision: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.763106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.764068) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 04:40:41.788814) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-12 04:40:41.789217) ========= /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.eSb5QcmCP1/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.2clA0bqjWv/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:40:41 INFO - MultiFileLogger online at 20160712 04:40:41 in /builds/slave/test 04:40:41 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 04:40:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:40:41 INFO - {'append_to_log': False, 04:40:41 INFO - 'base_work_dir': '/builds/slave/test', 04:40:41 INFO - 'blob_upload_branch': 'Autoland', 04:40:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:40:41 INFO - 'branch': 'Autoland', 04:40:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:40:41 INFO - 'config_files': ('talos/mac_config.py',), 04:40:41 INFO - 'default_actions': ('clobber', 04:40:41 INFO - 'read-buildbot-config', 04:40:41 INFO - 'download-and-extract', 04:40:41 INFO - 'populate-webroot', 04:40:41 INFO - 'create-virtualenv', 04:40:41 INFO - 'install', 04:40:41 INFO - 'run-tests'), 04:40:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:40:41 INFO - 'download_minidump_stackwalk': True, 04:40:41 INFO - 'download_symbols': 'ondemand', 04:40:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:40:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:40:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:40:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:40:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:40:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:40:41 INFO - 'installer_path': 'installer.exe', 04:40:41 INFO - 'log_level': 'info', 04:40:41 INFO - 'log_name': 'talos', 04:40:41 INFO - 'log_to_console': True, 04:40:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:40:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:40:41 INFO - 'opt_config_files': (), 04:40:41 INFO - 'pip_index': False, 04:40:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:40:41 INFO - 'cmd': ('bash', 04:40:41 INFO - '-c', 04:40:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:40:41 INFO - 'enabled': True, 04:40:41 INFO - 'halt_on_failure': False, 04:40:41 INFO - 'name': 'check_screen_resolution'},), 04:40:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:40:41 INFO - 'cmd': ('bash', 04:40:41 INFO - '-c', 04:40:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:40:41 INFO - 'enabled': True, 04:40:41 INFO - 'halt_on_failure': False, 04:40:41 INFO - 'name': 'check_screen_resolution'},), 04:40:41 INFO - 'run_cmd_checks_enabled': True, 04:40:41 INFO - 'sps_profile': False, 04:40:41 INFO - 'sps_profile_interval': 0, 04:40:41 INFO - 'suite': 'dromaeojs', 04:40:41 INFO - 'system_bits': '32', 04:40:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:40:41 INFO - 'title': 't-yosemite-r7-0140', 04:40:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:40:41 INFO - 'use_talos_json': True, 04:40:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:40:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:40:41 INFO - 'work_dir': 'build'} 04:40:41 INFO - ##### 04:40:41 INFO - ##### Running clobber step. 04:40:41 INFO - ##### 04:40:41 INFO - Running pre-action listener: _resource_record_pre_action 04:40:41 INFO - Running main action method: clobber 04:40:41 INFO - rmtree: /builds/slave/test/build 04:40:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:40:43 INFO - Running post-action listener: _resource_record_post_action 04:40:43 INFO - ##### 04:40:43 INFO - ##### Finished clobber step (success) 04:40:43 INFO - ##### 04:40:43 INFO - ##### 04:40:43 INFO - ##### Running read-buildbot-config step. 04:40:43 INFO - ##### 04:40:43 INFO - Running pre-action listener: _resource_record_pre_action 04:40:43 INFO - Running main action method: read_buildbot_config 04:40:43 INFO - Using buildbot properties: 04:40:43 INFO - { 04:40:43 INFO - "project": "", 04:40:43 INFO - "product": "firefox", 04:40:43 INFO - "script_repo_revision": "production", 04:40:43 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 04:40:43 INFO - "repository": "", 04:40:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 04:40:43 INFO - "buildid": "20160712021725", 04:40:43 INFO - "pgo_build": "False", 04:40:43 INFO - "basedir": "/builds/slave/test", 04:40:43 INFO - "buildnumber": 52, 04:40:43 INFO - "slavename": "t-yosemite-r7-0140", 04:40:43 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:40:43 INFO - "platform": "yosemite_r7", 04:40:43 INFO - "scheduler": "tests-autoland-macosx64-talos", 04:40:43 INFO - "branch": "autoland", 04:40:43 INFO - "repo_path": "integration/autoland", 04:40:43 INFO - "revision": "fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb", 04:40:43 INFO - "stage_platform": "macosx64", 04:40:43 INFO - "builduid": "c121bab07f6947df9fb9617ce72c76d6", 04:40:43 INFO - "slavebuilddir": "test" 04:40:43 INFO - } 04:40:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:40:43 INFO - Running post-action listener: _resource_record_post_action 04:40:43 INFO - ##### 04:40:43 INFO - ##### Finished read-buildbot-config step (success) 04:40:43 INFO - ##### 04:40:43 INFO - ##### 04:40:43 INFO - ##### Running download-and-extract step. 04:40:43 INFO - ##### 04:40:43 INFO - Running pre-action listener: _resource_record_pre_action 04:40:43 INFO - Running main action method: download_and_extract 04:40:43 INFO - mkdir: /builds/slave/test/build/tests 04:40:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:43 INFO - https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:40:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:40:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:40:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:40:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 04:40:44 INFO - Downloaded 1199 bytes. 04:40:44 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:40:44 INFO - Using the following test package requirements: 04:40:44 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:40:44 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:40:44 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:40:44 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'jsshell-mac.zip'], 04:40:44 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:40:44 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:40:44 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:40:44 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:40:44 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:40:44 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:40:44 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:40:44 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:40: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:44 INFO - https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:40:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:40:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:40:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:40:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:40:45 INFO - Downloaded 18772497 bytes. 04:40:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:40:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:40:49 INFO - Return code: 0 04:40:49 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:40:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:49 INFO - https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:40:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:40:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:40:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:40:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:40:54 INFO - Downloaded 18772497 bytes. 04:40:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:40:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:40:58 INFO - Return code: 0 04:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:58 INFO - https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:40:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:40:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:40:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:40:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:40:59 INFO - Downloaded 14403317 bytes. 04:40:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:40:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:41:00 INFO - Return code: 0 04:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:00 INFO - https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:41:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:41:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:41:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:41:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 04:41:03 INFO - Downloaded 112457448 bytes. 04:41:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:41:03 INFO - mkdir: /builds/slave/test/properties 04:41:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:41:03 INFO - Writing to file /builds/slave/test/properties/build_url 04:41:03 INFO - Contents: 04:41:03 INFO - build_url:https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:41:04 INFO - Running post-action listener: _resource_record_post_action 04:41:04 INFO - Running post-action listener: set_extra_try_arguments 04:41:04 INFO - ##### 04:41:04 INFO - ##### Finished download-and-extract step (success) 04:41:04 INFO - ##### 04:41:04 INFO - ##### 04:41:04 INFO - ##### Running populate-webroot step. 04:41:04 INFO - ##### 04:41:04 INFO - Running pre-action listener: _resource_record_pre_action 04:41:04 INFO - Running main action method: populate_webroot 04:41:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:41:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:41:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:41:04 INFO - '--tppagecycles', 04:41:04 INFO - '7'], 04:41:04 INFO - 'tests': ['tsvgm']}}, 04:41:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:41:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:41:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:41:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:41:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:41:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:41:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['damp', 'tps']}, 04:41:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'tests': ['damp', 'tps']}, 04:41:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['dromaeo_dom']}, 04:41:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:41:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['basic_compositor_video']}, 04:41:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:41:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['a11yr', 04:41:04 INFO - 'ts_paint', 04:41:04 INFO - 'tpaint', 04:41:04 INFO - 'sessionrestore', 04:41:04 INFO - 'sessionrestore_no_auto_restore', 04:41:04 INFO - 'tabpaint']}, 04:41:04 INFO - 'other-e10s': {'tests': ['a11yr', 04:41:04 INFO - 'ts_paint', 04:41:04 INFO - 'tpaint', 04:41:04 INFO - 'sessionrestore', 04:41:04 INFO - 'sessionrestore_no_auto_restore', 04:41:04 INFO - 'tabpaint']}, 04:41:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:41:04 INFO - 'ts_paint', 04:41:04 INFO - 'tpaint', 04:41:04 INFO - 'sessionrestore', 04:41:04 INFO - 'sessionrestore_no_auto_restore', 04:41:04 INFO - 'tabpaint']}, 04:41:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:41:04 INFO - 'ts_paint', 04:41:04 INFO - 'tpaint', 04:41:04 INFO - 'sessionrestore', 04:41:04 INFO - 'sessionrestore_no_auto_restore', 04:41:04 INFO - 'tabpaint']}, 04:41:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['a11yr', 04:41:04 INFO - 'ts_paint', 04:41:04 INFO - 'tpaint', 04:41:04 INFO - 'sessionrestore', 04:41:04 INFO - 'sessionrestore_no_auto_restore', 04:41:04 INFO - 'tabpaint']}, 04:41:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['a11yr', 04:41:04 INFO - 'ts_paint', 04:41:04 INFO - 'tpaint', 04:41:04 INFO - 'sessionrestore', 04:41:04 INFO - 'sessionrestore_no_auto_restore', 04:41:04 INFO - 'tabpaint']}, 04:41:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['tsvgx', 04:41:04 INFO - 'tsvgr_opacity', 04:41:04 INFO - 'tart', 04:41:04 INFO - 'tscrollx', 04:41:04 INFO - 'cart']}, 04:41:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:41:04 INFO - 'tsvgr_opacity', 04:41:04 INFO - 'tart', 04:41:04 INFO - 'tscrollx', 04:41:04 INFO - 'cart']}, 04:41:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'talos_options': ['--disable-e10s'], 04:41:04 INFO - 'tests': ['tp5o']}, 04:41:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'tests': ['tp5o']}, 04:41:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'talos_options': ['--disable-e10s', 04:41:04 INFO - '--xperf_path', 04:41:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:41:04 INFO - 'tests': ['tp5n']}, 04:41:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:41:04 INFO - 'talos_options': ['--xperf_path', 04:41:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:41:04 INFO - 'tests': ['tp5n']}}, 04:41:04 INFO - 'talos.zip': {'path': '', 04:41:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:41:04 INFO - Running post-action listener: _resource_record_post_action 04:41:04 INFO - ##### 04:41:04 INFO - ##### Finished populate-webroot step (success) 04:41:04 INFO - ##### 04:41:04 INFO - ##### 04:41:04 INFO - ##### Running create-virtualenv step. 04:41:04 INFO - ##### 04:41:04 INFO - Running pre-action listener: _resource_record_pre_action 04:41:04 INFO - Running main action method: create_virtualenv 04:41:04 INFO - Creating virtualenv /builds/slave/test/build/venv 04:41:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:41:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:41:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:41:04 INFO - Using real prefix '/tools/python27' 04:41:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:41:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:41:07 INFO - Installing pip.................done. 04:41:07 INFO - Return code: 0 04:41:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:41:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:07 INFO - 'HOME': '/Users/cltbld', 04:41:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:07 INFO - 'LOGNAME': 'cltbld', 04:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:07 INFO - 'MOZ_NO_REMOTE': '1', 04:41:07 INFO - 'NO_EM_RESTART': '1', 04:41:07 INFO - 'PAGER': '/bin/cat', 04:41:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:07 INFO - 'PWD': '/builds/slave/test', 04:41:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:07 INFO - 'SHELL': '/bin/bash', 04:41:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:07 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:07 INFO - 'USER': 'cltbld', 04:41:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:07 INFO - 'XPC_FLAGS': '0x0', 04:41:07 INFO - 'XPC_SERVICE_NAME': '0', 04:41:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:41:08 INFO - Cleaning up... 04:41:08 INFO - Return code: 0 04:41:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:08 INFO - 'HOME': '/Users/cltbld', 04:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:08 INFO - 'LOGNAME': 'cltbld', 04:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:08 INFO - 'MOZ_NO_REMOTE': '1', 04:41:08 INFO - 'NO_EM_RESTART': '1', 04:41:08 INFO - 'PAGER': '/bin/cat', 04:41:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:08 INFO - 'PWD': '/builds/slave/test', 04:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:08 INFO - 'SHELL': '/bin/bash', 04:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:08 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:08 INFO - 'USER': 'cltbld', 04:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:08 INFO - 'XPC_FLAGS': '0x0', 04:41:08 INFO - 'XPC_SERVICE_NAME': '0', 04:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:08 INFO - Downloading/unpacking psutil>=3.1.1 04:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:41:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:41:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:41:11 INFO - Installing collected packages: psutil 04:41:11 INFO - Running setup.py install for psutil 04:41:11 INFO - building 'psutil._psutil_osx' extension 04:41:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:41:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:41:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:41:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:41:12 INFO - building 'psutil._psutil_posix' extension 04:41:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:41:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:41:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:41:12 INFO - ^ 04:41:12 INFO - 1 warning generated. 04:41:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:41:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:41:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:41:12 INFO - Successfully installed psutil 04:41:12 INFO - Cleaning up... 04:41:12 INFO - Return code: 0 04:41:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:41:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:41:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:12 INFO - 'HOME': '/Users/cltbld', 04:41:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:12 INFO - 'LOGNAME': 'cltbld', 04:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:12 INFO - 'MOZ_NO_REMOTE': '1', 04:41:12 INFO - 'NO_EM_RESTART': '1', 04:41:12 INFO - 'PAGER': '/bin/cat', 04:41:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:12 INFO - 'PWD': '/builds/slave/test', 04:41:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:12 INFO - 'SHELL': '/bin/bash', 04:41:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:12 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:12 INFO - 'USER': 'cltbld', 04:41:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:12 INFO - 'XPC_FLAGS': '0x0', 04:41:12 INFO - 'XPC_SERVICE_NAME': '0', 04:41:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:41:12 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 04:41:12 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 04:41:12 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 04:41:12 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 04:41:12 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 04:41:12 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 04:41:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:41:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:41:15 INFO - Installing collected packages: mozsystemmonitor 04:41:15 INFO - Running setup.py install for mozsystemmonitor 04:41:15 INFO - Successfully installed mozsystemmonitor 04:41:15 INFO - Cleaning up... 04:41:15 INFO - Return code: 0 04:41:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:41:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:15 INFO - 'HOME': '/Users/cltbld', 04:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:15 INFO - 'LOGNAME': 'cltbld', 04:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:15 INFO - 'MOZ_NO_REMOTE': '1', 04:41:15 INFO - 'NO_EM_RESTART': '1', 04:41:15 INFO - 'PAGER': '/bin/cat', 04:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:15 INFO - 'PWD': '/builds/slave/test', 04:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:15 INFO - 'SHELL': '/bin/bash', 04:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:15 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:15 INFO - 'USER': 'cltbld', 04:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:15 INFO - 'XPC_FLAGS': '0x0', 04:41:15 INFO - 'XPC_SERVICE_NAME': '0', 04:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:15 INFO - Downloading/unpacking jsonschema==2.5.1 04:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:41:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:18 INFO - Downloading functools32-3.2.3-2.tar.gz 04:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:41:18 INFO - warning: no files found matching '*.txt' 04:41:18 INFO - no previously-included directories found matching 'build' 04:41:18 INFO - no previously-included directories found matching 'dist' 04:41:18 INFO - no previously-included directories found matching '.git*' 04:41:18 INFO - Installing collected packages: jsonschema, functools32 04:41:18 INFO - Running setup.py install for functools32 04:41:19 INFO - warning: no files found matching '*.txt' 04:41:19 INFO - no previously-included directories found matching 'build' 04:41:19 INFO - no previously-included directories found matching 'dist' 04:41:19 INFO - no previously-included directories found matching '.git*' 04:41:19 INFO - Successfully installed jsonschema functools32 04:41:19 INFO - Cleaning up... 04:41:19 INFO - Return code: 0 04:41:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:19 INFO - 'HOME': '/Users/cltbld', 04:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:19 INFO - 'LOGNAME': 'cltbld', 04:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:19 INFO - 'MOZ_NO_REMOTE': '1', 04:41:19 INFO - 'NO_EM_RESTART': '1', 04:41:19 INFO - 'PAGER': '/bin/cat', 04:41:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:19 INFO - 'PWD': '/builds/slave/test', 04:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:19 INFO - 'SHELL': '/bin/bash', 04:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:19 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:19 INFO - 'USER': 'cltbld', 04:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:19 INFO - 'XPC_FLAGS': '0x0', 04:41:19 INFO - 'XPC_SERVICE_NAME': '0', 04:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:19 INFO - Downloading/unpacking blobuploader==1.2.4 04:41: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 04:41: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 04:41: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 04:41: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 04:41: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 04:41: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 04:41:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:41:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:41:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:41:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:41:23 INFO - Downloading docopt-0.6.1.tar.gz 04:41:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:41:23 INFO - Installing collected packages: blobuploader, requests, docopt 04:41:23 INFO - Running setup.py install for blobuploader 04:41:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:41:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:41:23 INFO - Running setup.py install for requests 04:41:23 INFO - Running setup.py install for docopt 04:41:23 INFO - Successfully installed blobuploader requests docopt 04:41:23 INFO - Cleaning up... 04:41:24 INFO - Return code: 0 04:41:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:41:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:24 INFO - 'HOME': '/Users/cltbld', 04:41:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:24 INFO - 'LOGNAME': 'cltbld', 04:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:24 INFO - 'MOZ_NO_REMOTE': '1', 04:41:24 INFO - 'NO_EM_RESTART': '1', 04:41:24 INFO - 'PAGER': '/bin/cat', 04:41:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:24 INFO - 'PWD': '/builds/slave/test', 04:41:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:24 INFO - 'SHELL': '/bin/bash', 04:41:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:24 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:24 INFO - 'USER': 'cltbld', 04:41:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:24 INFO - 'XPC_FLAGS': '0x0', 04:41:24 INFO - 'XPC_SERVICE_NAME': '0', 04:41:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-CF54Pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-msYfh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-oSil9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-pb4B6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-nWFS1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-QdPmTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:41:24 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-qJYXOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ghQdfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-lrwc8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-6MFb5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-nRpB7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-9mtqey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-L0ngdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-6OyLAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-zLnY8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Y1vEDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:41:25 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-BvVLSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:41:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:41:26 INFO - Running setup.py install for manifestparser 04:41:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:41:26 INFO - Running setup.py install for mozcrash 04:41:26 INFO - Running setup.py install for mozdebug 04:41:26 INFO - Running setup.py install for mozdevice 04:41:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:41:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:41:26 INFO - Running setup.py install for mozfile 04:41:26 INFO - Running setup.py install for mozhttpd 04:41:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:41:26 INFO - Running setup.py install for mozinfo 04:41:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Running setup.py install for mozInstall 04:41:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Running setup.py install for mozleak 04:41:27 INFO - Running setup.py install for mozlog 04:41:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Running setup.py install for moznetwork 04:41:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:41:27 INFO - Running setup.py install for mozprocess 04:41:27 INFO - Running setup.py install for mozprofile 04:41:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:41:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:41:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:41:28 INFO - Running setup.py install for mozrunner 04:41:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:41:28 INFO - Running setup.py install for mozscreenshot 04:41:28 INFO - Running setup.py install for moztest 04:41:28 INFO - Running setup.py install for mozversion 04:41:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:41:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:41:28 INFO - Cleaning up... 04:41:28 INFO - Return code: 0 04:41:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:28 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:28 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 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:28 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 04:41:28 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 04:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:28 INFO - 'HOME': '/Users/cltbld', 04:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:28 INFO - 'LOGNAME': 'cltbld', 04:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:28 INFO - 'MOZ_NO_REMOTE': '1', 04:41:28 INFO - 'NO_EM_RESTART': '1', 04:41:28 INFO - 'PAGER': '/bin/cat', 04:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:28 INFO - 'PWD': '/builds/slave/test', 04:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:28 INFO - 'SHELL': '/bin/bash', 04:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:28 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:28 INFO - 'USER': 'cltbld', 04:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:28 INFO - 'XPC_FLAGS': '0x0', 04:41:28 INFO - 'XPC_SERVICE_NAME': '0', 04:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:41:28 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-bx2Q2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Do_4JE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-nTfeEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-yDw1Rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-4BMvlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ADqPdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:41:29 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)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-DR7U9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:41:29 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)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-tlsyPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:41:29 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)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ILpIdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:41:29 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)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-on6UvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 04:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:41:29 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-X5dICd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:41:30 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-vgobgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:41:30 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-_CZteN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:41:30 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-jsD1ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:30 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-gyynC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:41:30 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)) 04:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:41:30 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-_2wWZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:41:30 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)) 04:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:41:30 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ww519v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:41:30 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)) 04:41:30 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)) 04:41:30 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)) 04:41:30 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)) 04:41:30 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)) 04:41:30 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)) 04:41:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:41: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 04:41: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 04:41: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 04:41: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 04:41: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 04:41: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 04:41:33 INFO - Downloading blessings-1.6.tar.gz 04:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:41:33 INFO - Installing collected packages: blessings 04:41:33 INFO - Running setup.py install for blessings 04:41:33 INFO - Successfully installed blessings 04:41:33 INFO - Cleaning up... 04:41:33 INFO - Return code: 0 04:41:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:41:33 INFO - Reading from file tmpfile_stdout 04:41:33 INFO - Current package versions: 04:41:33 INFO - blessings == 1.6 04:41:33 INFO - blobuploader == 1.2.4 04:41:33 INFO - docopt == 0.6.1 04:41:33 INFO - functools32 == 3.2.3-2 04:41:33 INFO - jsonschema == 2.5.1 04:41:33 INFO - manifestparser == 1.1 04:41:33 INFO - mozInstall == 1.12 04:41:33 INFO - mozcrash == 0.17 04:41:33 INFO - mozdebug == 0.1 04:41:33 INFO - mozdevice == 0.48 04:41:33 INFO - mozfile == 1.2 04:41:33 INFO - mozhttpd == 0.7 04:41:33 INFO - mozinfo == 0.9 04:41:33 INFO - mozleak == 0.1 04:41:33 INFO - mozlog == 3.2 04:41:33 INFO - moznetwork == 0.27 04:41:33 INFO - mozprocess == 0.23 04:41:33 INFO - mozprofile == 0.28 04:41:33 INFO - mozrunner == 6.12 04:41:33 INFO - mozscreenshot == 0.1 04:41:33 INFO - mozsystemmonitor == 0.3 04:41:33 INFO - moztest == 0.7 04:41:33 INFO - mozversion == 1.4 04:41:33 INFO - psutil == 3.1.1 04:41:33 INFO - requests == 1.2.3 04:41:33 INFO - wsgiref == 0.1.2 04:41:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:41:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:33 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 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:33 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 04:41:33 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 04:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:33 INFO - 'HOME': '/Users/cltbld', 04:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:33 INFO - 'LOGNAME': 'cltbld', 04:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:33 INFO - 'MOZ_NO_REMOTE': '1', 04:41:33 INFO - 'NO_EM_RESTART': '1', 04:41:33 INFO - 'PAGER': '/bin/cat', 04:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:33 INFO - 'PWD': '/builds/slave/test', 04:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:33 INFO - 'SHELL': '/bin/bash', 04:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:33 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:33 INFO - 'USER': 'cltbld', 04:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:33 INFO - 'XPC_FLAGS': '0x0', 04:41:33 INFO - 'XPC_SERVICE_NAME': '0', 04:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:41:34 INFO - Cleaning up... 04:41:34 INFO - Return code: 0 04:41:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:41:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108737a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9695a63b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, '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.eSb5QcmCP1/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.2clA0bqjWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 04:41:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:34 INFO - 'HOME': '/Users/cltbld', 04:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:34 INFO - 'LOGNAME': 'cltbld', 04:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:34 INFO - 'MOZ_NO_REMOTE': '1', 04:41:34 INFO - 'NO_EM_RESTART': '1', 04:41:34 INFO - 'PAGER': '/bin/cat', 04:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:34 INFO - 'PWD': '/builds/slave/test', 04:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:34 INFO - 'SHELL': '/bin/bash', 04:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:41:34 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:34 INFO - 'USER': 'cltbld', 04:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:34 INFO - 'XPC_FLAGS': '0x0', 04:41:34 INFO - 'XPC_SERVICE_NAME': '0', 04:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:41:34 INFO - Cleaning up... 04:41:34 INFO - Return code: 0 04:41:34 INFO - Running post-action listener: _resource_record_post_action 04:41:34 INFO - Running post-action listener: _start_resource_monitoring 04:41:34 INFO - Starting resource monitoring. 04:41:34 INFO - ##### 04:41:34 INFO - ##### Finished create-virtualenv step (success) 04:41:34 INFO - ##### 04:41:34 INFO - ##### 04:41:34 INFO - ##### Running install step. 04:41:34 INFO - ##### 04:41:34 INFO - Running pre-action listener: _resource_record_pre_action 04:41:34 INFO - Running main action method: install 04:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:41:34 INFO - Reading from file tmpfile_stdout 04:41:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:41:34 INFO - Reading from file tmpfile_stdout 04:41:34 INFO - Output received: 04:41:34 INFO - Usage: mozinstall [options] installer 04:41:34 INFO - Options: 04:41:34 INFO - -h, --help show this help message and exit 04:41:34 INFO - -d DEST, --destination=DEST 04:41:34 INFO - Directory to install application into. [default: 04:41:34 INFO - "/builds/slave/test"] 04:41:34 INFO - --app=APP Application being installed. [default: firefox] 04:41:34 INFO - mkdir: /builds/slave/test/build/application 04:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:42:09 INFO - Reading from file tmpfile_stdout 04:42:09 INFO - Output received: 04:42:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:42:09 INFO - Running post-action listener: _resource_record_post_action 04:42:09 INFO - ##### 04:42:09 INFO - ##### Finished install step (success) 04:42:09 INFO - ##### 04:42:09 INFO - ##### 04:42:09 INFO - ##### Running run-tests step. 04:42:09 INFO - ##### 04:42:09 INFO - Running pre-action listener: _resource_record_pre_action 04:42:09 INFO - Running main action method: run_tests 04:42:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:42:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:42:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:42:09 INFO - 2016-07-12 04:42:09.363 screenresolution[1637:7510] starting screenresolution argv=screenresolution get 04:42:09 INFO - 2016-07-12 04:42:09.366 screenresolution[1637:7510] Display 0: 1600x1200x32@60 04:42:09 INFO - 2016-07-12 04:42:09.371 screenresolution[1638:7512] starting screenresolution argv=screenresolution list 04:42:09 INFO - Available Modes on Display 0 04:42:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:42:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:42:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:42:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:42:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:42:09 INFO - Intel Iris: 04:42:09 INFO - Chipset Model: Intel Iris 04:42:09 INFO - Type: GPU 04:42:09 INFO - Bus: Built-In 04:42:09 INFO - VRAM (Dynamic, Max): 1536 MB 04:42:09 INFO - Vendor: Intel (0x8086) 04:42:09 INFO - Device ID: 0x0a2e 04:42:09 INFO - Revision ID: 0x0009 04:42:09 INFO - Displays: 04:42:09 INFO - Display: 04:42:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:42:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:42:09 INFO - Display Serial Number: 1600x1200 60 04:42:09 INFO - Main Display: Yes 04:42:09 INFO - Mirror: Off 04:42:09 INFO - Online: Yes 04:42:09 INFO - Rotation: Supported 04:42:09 INFO - Return code: 0 04:42:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:42:09 INFO - Python 2.7.3 04:42:09 INFO - Return code: 0 04:42:09 INFO - grabbing minidump binary from tooltool 04:42:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:09 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 0x10869bd30>, '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 0x108400db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108693b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:42:09 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 04:42:09 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 04:42:09 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 04:42:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:42:09 INFO - Return code: 0 04:42:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:42:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:42:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:42:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:42:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:42:09 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/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 04:42:09 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/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0140 --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 04:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eSb5QcmCP1/Render', 04:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:09 INFO - 'HOME': '/Users/cltbld', 04:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:09 INFO - 'LOGNAME': 'cltbld', 04:42:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:42:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:09 INFO - 'MOZ_NO_REMOTE': '1', 04:42:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:42:09 INFO - 'NO_EM_RESTART': '1', 04:42:09 INFO - 'PAGER': '/bin/cat', 04:42:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:09 INFO - 'PWD': '/builds/slave/test', 04:42:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:09 INFO - 'SHELL': '/bin/bash', 04:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2clA0bqjWv/Listeners', 04:42:09 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 04:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:09 INFO - 'USER': 'cltbld', 04:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:09 INFO - 'XPC_FLAGS': '0x0', 04:42:09 INFO - 'XPC_SERVICE_NAME': '0', 04:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:09 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/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 04:42:09 INFO - mozversion application_buildid: 20160712021725 04:42:09 INFO - mozversion application_changeset: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb 04:42:09 INFO - mozversion application_display_name: Nightly 04:42:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:42:09 INFO - mozversion application_name: Firefox 04:42:09 INFO - mozversion application_remotingname: firefox 04:42:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:42:09 INFO - mozversion application_vendor: Mozilla 04:42:09 INFO - mozversion application_version: 50.0a1 04:42:09 INFO - mozversion platform_buildid: 20160712021725 04:42:09 INFO - mozversion platform_changeset: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb 04:42:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:42:09 INFO - mozversion platform_version: 50.0a1 04:42:09 INFO - using testdate: 1468323729 04:42:09 INFO - actual date: 1468323729 04:42:09 INFO - starting webserver on 'localhost:49271' 04:42:09 INFO - SUITE-START | Running 2 tests 04:42:09 INFO - TEST-START | dromaeo_css 04:42:09 INFO - operating with platform_type : mac_ 04:42:09 INFO - Initialising browser for dromaeo_css test... 04:42:09 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpoFNl2q/profile http://localhost:49271/getInfo.html) 04:42:12 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 04:42:12 INFO - PROCESS | 1648 | colorDepth:24 04:42:12 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 04:42:12 INFO - PROCESS | 1648 | __metrics 04:42:12 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:42:12 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:42:13 INFO - TEST-INFO | 1648: exit 0 04:42:13 INFO - Browser initialized. 04:42:13 INFO - Running cycle 1/1 for dromaeo_css test... 04:42:13 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpoFNl2q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:25 INFO - PROCESS | 1651 | RSS: Main: 243785728 04:42:25 INFO - PROCESS | 1651 | 04:43:16 INFO - PROCESS | 1651 | 1468323796460 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] 04:45:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 04:45:29 INFO - PROCESS | 1651 | RSS: Main: 361082880 04:45:29 INFO - PROCESS | 1651 | 04:48:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 04:48:33 INFO - PROCESS | 1651 | RSS: Main: 372621312 04:48:33 INFO - PROCESS | 1651 | 04:51:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 04:51:35 INFO - PROCESS | 1651 | RSS: Main: 370704384 04:51:35 INFO - PROCESS | 1651 | 04:54:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 04:54:39 INFO - PROCESS | 1651 | RSS: Main: 371339264 04:54:39 INFO - PROCESS | 1651 | 04:57:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 04:57:43 INFO - PROCESS | 1651 | RSS: Main: 343605248 04:57:43 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:00:46 INFO - PROCESS | 1651 | RSS: Main: 379719680 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | __start_tp_report 05:00:46 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 05:00:46 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 05:00:46 INFO - PROCESS | 1651 | |i|pagename|runs| 05:00:46 INFO - PROCESS | 1651 | |0;dojo.html;2893.5659651052338;3377.3583019226344;3388.3731509670697;3391.3386889556537;3404.727621790257;5638.182463542794;5663.4336566343345;5727.513161381277;5784.103463963087;5797.652140871551;5777.971110144444;5801.709914504279;5879.8530036749;5880.529557635389;5898.7935422260225;4239.936400953973;4735.692180008288;4905.975470122645;5239.607029472805;5395.325584301962;5366.114591092465;5407.5133238008575;5429.321334833146;5437.429069947658;5462.918056229159;5220.947790522103;5239.187925871496;5246.895062098775;5271.551918086967;5297.364316282052;5293;5302.708351040691;5310.309659744247;5315.548178404841;5319.840404787824;5119.948800511985;5216.530512253916;5263.315768950012;5272.499112584298;5276.15581506957;5738.225339579145;5760.8271751847515;5772.278465191851;5778.711064446803;5794.985877471468;6296.212973378328;6341.460975817061;6358.523110766711;6362.650054247015;6366.172397588331;5275.973620131913;5442.292501974757;6247.906616342122;6291.1192433059405;6356.173697419306;5860;5862.736176872051;5897.823065308047;5925.4370834770625;5942.1383899335215;6352.841178970562;6362.141110949962;6362.9045564316575;6363.363663633692;6368.267649220399;6056.636601803906;6076.601159057303;6143.56995010345;6187.3503282154725;6281.685915704196;6185.381461853779;6218.782342617987;6234.5635805494085;6238.251409830849;6258.217722784652;6182.690865456799;6196.318404975449;6230.345813689588;6245.5628106032145;6252.74989000444;6212.596181248205;6212.9068063979075;6217.067439884054;6232.750689972441;6233.96883015582;4905;4926.58124059459;4966.230234313687;4991.950080499221;5010.3737032870895;4442.089371678798;4517.1417430688725;4517.34498497716;4525.366448697185;4530.88672783183;5865.090910908816;5950.464458198783;5987.64074155552;5992.34084250732;6048.243969503812;6246.843828904313;6273.623582584968;6289.0880822280515;6297.4647154992335;6307.4954003679595;5775.48020678141;5810.738516766755;5846.561507886926;5849.561282903799;5977.581569290195;5477.808276710296;5492.47821456961;5498.010358135574;5512.6968016759465;5542.251796007568;6337.746490140527;6385.074164246251;6385.2656944451055;6390.041493775878;6396.71214795326;6203.410675985774;6209.223847019122;6256.061590761422;6276.905846412399;6287.905681414874;6377.266614339411;6404.807855764335;6405.231372235362;6423.550351475352;6437.871242575028;6254.718537665907;6264.185655864709;6268.1851359323;6274.937250627618;6279.277883043508;6192.411720886508;6195.473384762346;6231.875362492816;6232;6310.085037669604;4672.556107169813;6172.98145805937;6216.968915155395;6246.281677606951;6266.530010249341;5985.970070149621;6216.409441103088;6222.377762223741;6271.24745030581;6274.152989346379;5087.974560127176;5971.820845374646;5972.850678733066;5998.070299103646;6102.0541816018585;6238.970569855923;6260.843478913063;6264.21697287839;6272.655003974643;6286.18279623646;5916.55625828056;5978.551608629283;6094.603850749687;6098.146259523582;6109.816705498843 05:00:46 INFO - PROCESS | 1651 | |1;ext.html;507.2264796185817;526.610308371805;526.8340472751083;544;546.3171036204744;28253.011144609918;28502.857485712593;30673.539896901537;31732.99999999997;42281.788591057004;37669;38631;43227.78386108073;43245.56754432455;43417.7829110854;7165.355118039375;7326.974223608699;7365.263473652619;7386.335229829314;7462.477626566142;8794.384393092487;8931.55342232889;8938.910610893905;9148.085191480865;9648.71053868385;8945.731628051137;10350.12023977959;11886.405679716023;11903.7619247615;12145.514179432856;10011.449370284632;12080.637580872544;12096.213746106476;12097.2136811107;12102.878971210263;8630.050694423608;11564.595239166589;11942.462589183508;11969;12070.275783452933;38923.22153556913;41193;41235.1752964942;41257.1748565027;41299;3450.2926899985696;3505.5618047744033;3560.4659301104784;3603.297357015383;3692.4461330800327;29783.40433191324;30422.84788576065;30698;32882.01353959385;33045.843395481286;3316.1046517440395;3331.487495697187;3354.580677415323;3374.5444365010653;3468.6357932416986;540578;544958;546241;547193.2640336812;548986.999999996;63928;64311.67844160843;64390.10244539007;64448.355516445175;64504;67977.66011169912;68072;68173.65913170403;68176.97734533987;68280.6585967077;16667.166641667867;16912.985220886785;16918.74621880673;16921.153942302837;16925.576860578585;11843.407829608484;12046.759064818654;12064.276143431422;12190.39048047594;12194.878051219552;18450.98519581436;19061.332853350064;19544.609107818047;19565.706514402296;19602.98138881526;7784.766457006299;7914.485558438683;7915.366770658334;7974.840503189904;7993.760187194393;909.4452384045727;919.3748251189255;979.7648564344506;1164.796160671879;1184.253226490919;25312.36719082001;30894;31170.68829311678;31542.36915261682;31686.366272674877;1080.9459527023616;1094.9233553651168;1114.8216285394299;1168.6260396673154;1199.622119032502;15857.920710396374;15996.280167392262;16080.839191607934;16279.10464924417;16308.592285192964;666304;666681;674633;674669;674684;60566.394336056954;61405;61436.692816536524;61490;61563;1259.7984322508355;1328.8206092177625;1396.1343966740685;1412.4491448334952;1434.8063011493523;31216.531752023282;31977.840110799298;31979.68020319767;32639.836800815843;32797;50795.74602126966;51487;51756.48243517667;51794;51812.22281665703;483411;527125;528311;530397;530842;27355;27597.172084837486;28426.289342766595;28475.857620711762;29221.269468262613;48933.26600101073;49403.7529812363;49993.75003125107;50311.49688503068;50545.73635658991;39965.80017099896;40827.59172408238;41177.38233926434;41863;42244.788776055924;40909.38635920404;41000;41038.38442423426;42308;42364.9408764771;31394;32880.01359959205;38456.03859903525;41329.1734165321;42166.789166053975;34449;36018.8199059003;36086;36452.81773591115;36623.45064823976;26124.34689132711;26243.475130497663;26297;26347.077852275077;27743.80559085172 05:00:46 INFO - PROCESS | 1651 | |2;jquery.html;362.2465272233754;380.47684433903385;391.37966323377447;393.7814512945316;414.57506056292294;15807.604809879744;16903.23935422906;20884.582308353823;21373.0382132804;21378.679319810177;21417.250396236133;21707.806070666105;21754.238601648947;21770.455738606543;21827.126914923385;5136.64043516954;5914.615549989258;5948.643081415129;6056.9091463627865;6149.538784591174;4977.925331120036;5935.436133567304;5947.88104237915;6010.27876654802;6076.756929722828;5730;5904.261967254093;5930.792422265222;5937.67342796146;6015.879682406349;5939.524838012971;5942.61373010753;5967.16459695643;6021.277446706379;6030.09548567714;5103.336566246401;5257.369115706101;5259.474052594748;5259.737013149365;5264.578833693334;25317.746822531906;25371.49257014868;25997.740022599723;26579.601305980425;26631.733682663315;2323.6398358254496;2336.5794157051723;2529.860857652828;2855.200543847726;3173.7778355515125;15366.769498457532;15934.840651593451;16069.517914462585;16274.43039493612;16668.249928753234;1890.981090189108;1961.6567100757452;1982.5836574319267;1990.532224927141;2058.5985732782115;108965.45517272443;113487.86512134856;117931;119476.40261798636;119859.40070299593;9080.774095497156;10599.894001060045;10856.294340867818;11084.445777711082;11160.218784684994;13669.043166978416;13781.586552403443;13788.655283617789;13791.620837916129;13793.724125517634;8546.572671366532;8803.119688031145;8834.867476987722;8850.20348168668;8869.822603547893;7496.925030749731;8720.040795512488;8881.73354799357;8937.061608531141;9090;5794.362620111784;6651.23510796255;6765.9661701691175;6776.780179567627;6865.258434153398;1802.9909850450663;1832.3522509973227;1833.5874428253537;1836.7336736173181;1838.1177035023263;877.591919757316;929.3401684803865;998.6205242008135;1061.379093230469;1128.2046157458972;17928;20274.235075744014;23070.884645576665;23092.65361019586;23107.6533851989;924.5900002496247;935.3164304252318;956.5743244256379;961.6969006995561;1054.894510548939;11234.38210898392;11679.474423650956;12213.69465763363;12216.633500994983;12230;422408.88795555825;481160;489604;491311;499416;125875.85310367131;131073;133724;150599.2470037643;156486;822.2599660305773;973.776031512756;1035.13566172247;1045.2213101239624;1053.3679792124817;18244.89836727786;20268.89865550663;20329.593408132052;20726.481837954172;20766.584668306852;56218.71890640521;58730.706346467996;58864.70567647134;59202.111968319645;59644;83276.83615819307;83469.58265208635;84302.57848710717;85443.57278213817;85471;8152.307053900485;8324.417290789586;8333.874991875005;8365.581720913808;8397.95801020991;12944.15862968904;13003.284819335027;13025.08824382322;13225.735485290434;13305.401256943655;10996.780064398827;11241.71895702614;11259.662210133709;11316.15128865355;11420.828687569527;11121.276202188674;11149.442527873412;11149.442527873412;11293.604723834625;11392.08863290918;11399.145064120059;11521.423928803693;11528.884711152781;11645.41772911368;11765.294082355085;6951.548149370275;7080.681369338495;7532.887006694795;8174.550399728072;8429.199226073513;11253.437328133397;11425.657230283105;11502.53989840397;11537.884621154017;11850.348230847387 05:00:46 INFO - PROCESS | 1651 | |3;mootools.html;1374.546399688103;1535.0405996252343;1563.0696090328445;1727.680379129861;1874.128530233441;7525.811854703628;8174.3460523158155;8348.206920342558;8357.122502137272;8509.106543812903;8094.91905080949;8335.999680038389;8336.74989750307;8337.91662083379;8343.332533397328;2033.766116896561;2114.534802343482;2179.585878683053;2344.5779759643215;2375.9783293183923;2346.9647955280684;2364.397078744926;2377.869217193054;2380.1312520929823;2382.809375249976;1888.9338873139443;2313.109452860644;2331.8484298520543;2335.311083230443;2338.941526461835;2035.0740413112073;2201.801837834603;2273.533925545259;2274.306336567346;2314.432963923841;2313.8264630152808;2328.592496313155;2333.195047708538;2334.1480359668767;2339.6724458575814;8355.373346999;8411.116832732598;8414.789630259293;8415.579221038923;8423.115572864823;5049.899002019939;5203.505666961632;5206.973965130188;5210.7915683373;5212.348456442945;3417.9145521362166;3471.63547827477;3586.3903136466865;3591.8204089795404;3596.2627661329625;1059.369675043348;1081.4862940103467;1089.2266490791546;1105.2815669814604;1106.4854842498278;10871.891281087166;10877.67366978992;10878.564857405694;10895.291806032583;10913.290636108628;4433.401490798766;4445.133199026159;4665.370175026396;4665.720056796603;4670.8365207217585;9432.52837358143;9435.858462123073;9439.24486041115;9447.480388578695;9453.33826632129;5211.739413029409;5213.374395072616;5213.921791173136;5214.217867319932;5214.7914083436235;4269.957300427018;4426.225410553205;4619.630429565694;4747.8100875965265;4782.760861956888;3801.752886062397;3836.194399176204;3923.8822835314986;3941.3693808990424;3968.761874287552;1073.3184427888348;1148.6955956671488;1169.602335206034;1174.712195512105;1272.1540175783186;792.6512334572769;828.1676914700687;879.1691851200554;921.9812107621116;922.2253307221965;5837.270341207349;5877.471027607536;5877.52979761618;5954.85112872173;6064.969675151596;946.8390373636498;963.9759006024906;974.7660561465198;1001.4041645221082;1002.9699109026741;3386.508956201337;3850;3994.900127496836;4061.6547593454297;4113.424120623115;8349;9771.31600787938;10062.446565438824;10142.18862490999;10147.543360548792;10096.192304615613;10099.14157296638;10108.545115469822;10118.038786315288;10123.949380253052;7453.366463850525;7819.6481158348015;7847.254510821463;7901.644426000844;7916.366690664733;5751.252337196138;5809.099589563624;5877.206577112035;5947.553933455095;6061.878762424815;7877.172896845977;9072.863907041532;9643.614255429704;9646.469444180637;9655.806883862424;9302.95348523253;9709.70870873875;9776.071273229032;9787.559559819683;9799.265055121037;6762.985552167214;7006;7141.750038748619;7285.7814265572115;7453.105627324756;7986.281234688906;8157;8166.918330816616;8181.222783835526;8182.140875208255;7190.137183538009;7270.382017528569;7337.229590893093;7385.556866588022;7479.326860582573;6907.447404207752;7094.503384763224;7117.039199707973;7148.964255178691;7168.319009694071;5664.971675141763;6848.589084654936;6855.07456493387;6864.897026544506;6870.106886104774;4810.254410566367;4891.779869905791;4911.607071434205;4923.0153969205585;5023.572996295356;4934.753262336941;4968.826091086795;4986.276989836526;4990.80036798524;5106.183010718267 05:00:46 INFO - PROCESS | 1651 | |4;prototype.html;306.4988743404533;315.84207896051976;317.8664960716498;323.7652701791202;326.3359064304141;4709.222978208596;5000.924986125202;5024.8895463905155;5514.448555144483;5531.3362396512375;4469.553044695529;4683.76581170941;5430.615193125747;5452.291202143716;5466.316710411199;3590.5870824855215;3618.7563710499526;4085.754854708712;4212.894677633069;4227.4927008759;3190.8245046522434;3948;4047.4535937648334;4197.580241975793;4239.682023848209;3372.831358432081;4094.0992981544164;4134.834606615732;4147.523034850986;4211;3259.511073339018;3394.490826376051;3821.197548514815;3953.1895961328;4177.164567086565;2970.3465237647683;3364.2934983653463;3638.963610363889;3642.923533585857;3672.797996110213;4293.840663020998;4729.59798417135;5343.65266257692;5348.064088784447;5412.56699464042;3751.8499260029566;3895.5130608673917;4777.355057067286;4825.589824864891;4828.637852161102;5641.492265696107;5658.699388464796;5667.8583035424035;5671.489565939085;5675.262215911946;4017.517897852247;4021.8190181441905;4027.6979226558124;4029.1135950090934;4074.2666320062376;5728.825590753885;6037.577369584087;6045.969770151165;6077.848053798646;6143.969280153616;4074.8573799916862;4081.9999100220043;4087.302380428962;4091;4108.473288927348;4170.6852696752985;4180.623743863006;4182.034443811403;4191.308434108399;4197.979010104991;4478.865634030984;4630;4761.40482439695;4779.976100119477;4821.156297647897;5067.8986420271385;5250.737463126905;5578.716895114147;5758.74085666146;5792.710364481759;1141.8866605060166;1334.9332533373486;1399.2514005007272;1402.3479082226813;1407.7043820797542;727.0657205490919;744.7132853851306;872.6640243506296;881.8897637795276;888.7289376740156;5065.848024559269;5229.607779416559;5235.816746413857;5250.503606472137;5257.7896884124975;4786.798072886511;5119.235612769063;5847.1229315603;5875.559333050039;5931.851703707442;5158.767855446439;5311;5349.389162315421;5359.3032905722;5400.62195646301;2573.3309339571847;2783.4850552647827;2854.82871027739;3036.817790932551;3062.433449811792;5023.49765023502;6008.09878518217;6020.8795824084145;6036.577439579188;6079.726412311456;5439.918401223985;5679.858003549862;5910.734016969247;5939.910901336483;6114.57197996136;5744.707440825864;5807.415700013454;6077.574569780073;6108.694565271719;6175.2948231061;5072.746362681925;5542.334919809648;5859.384764599656;5886.41135886425;5909.320428150818;4491.932621010622;5787.9131813021995;5821.243238378984;5835.44563266489;5849;5357.410684824744;5778.104393818965;6028.372339468407;6097.878042439215;6114.715909659021;3004.564338170909;3659.8353074111196;3665.4318580620047;4023.4769479967417;4040.393940908887;4974.900501990012;5520.012795393574;5688.943110568841;5985.491233245223;6081.665508397081;5885.73514191871;5898.970505147447;5900.468957793819;5905.291365036223;5910.231669882888;4694.600958918393;4892.926606100982;5289.920651190158;5595.35653399848;5670.149477578396;4641.907161856811;5225.764840582106;5461.5357694596405;5606.355269144101;5669.574781891292;4653.371794807657;4919.901601968012;4957.686213153589;5358.97320513395;5539.695316757617;4523.5250298719475;4539.296409056601;4745.4374637695255;5074.974625126851;5392.514673679388 05:00:46 INFO - PROCESS | 1651 | |5;yui.html;742.8031571633514;743.8512297540492;767.9539227646342;802.5064585280054;807.8303556253187;12529.248245105277;15542.456014039512;16266.512004639864;16268.267927943256;16679.416220432286;14985.400583976656;15023.549293521211;15145.545633630982;15184.31670574824;15345.002574832655;701.863136688346;729.6205972894119;872.8908886389218;894.4901406198437;898.2409863665192;875.8423483772918;916.1937495004387;917.554985831871;918.3571499950061;919.6919032124247;941.4304345870752;953;953.8616900549416;954.3176628710471;955.2644463762898;734.5776178697217;764.7208768799348;766.203148725325;945.1871390604075;945.3288165402572;560.5235549782693;560.7392562458476;673.2896793882452;698.6157613312708;699.3810477727197;15064.397424103132;15110;15111.942164048522;15115.924420377938;15134.243287835565;11974.042076633848;13594.38825252866;13714.24571648559;13720.108192967526;14128.22294773787;11472.827907581393;11944;12516.3741812909;12521.624351269476;12582.307973061477;3780.3006443807985;4356.259435895905;4478.708883922567;4482.820687172509;4524.434445694288;108317;108552.4572377141;108577.91422085756;108705.82588348188;108756;10675.893241067646;10868.945655271673;10919.235653504338;10919.836202456969;10992.835107473235;21582.35252942414;21631.026603802868;21637;21650.458738531346;21693.67459488109;7828.412869034845;7922.4850384724805;7949.3243074339325;7957.124716281204;8062.879056814152;4373.540778218241;4396.780160991938;4433.955660443419;4442.822287108479;4579.702319349299;3016.7480495594523;3194.185482701896;3267.624223214361;3322.551455553469;3368.6631336866117;628.0485065126337;634.3751404866204;638.1831255992337;644.3266786208424;650.5218664281749;608.1728848765675;609.1441524657863;628.2555172121051;633.2432742872315;647.2976820150095;8749.868751968726;8828.8675669865;8899.510526920953;8982.955085224663;9065.229455496225;603.3363300369558;610.6275172144988;629.1286568103119;629.3863483104028;645.2934037229227;4362.8909277267685;4377.255866502702;4421.204183246982;4437.42313499243;4496.3705081288645;93413;93742.53128734312;94078;103782;149644;86175.56912215399;86282.7057594124;102030.48984755029;125758.16660167374;138737;97260.51369743106;101709.99999999852;101911.47132793014;102020.48989755152;102944;8441.95779021101;9630.701666833073;9671.854922176313;9802.950985245028;9803.656872009731;51616;52548.73725631347;53084.20373694321;53411.19883201833;54228;81037;82716;87394;87714.56142719246;88352.5582372084;13094.345282736016;13218.890085832725;13349.532766353519;13354;13375.665608359479;12085.456154472893;12593.93703031479;13107.213567186;14642.623507498447;15248.847511524742;12264.938675306566;12315.507379704712;12528.809763072493;12531.248125112523;12543.93728031354;11951.820722689341;12281.263124212575;12420.751584968431;12503.56237531682;12549.18430302064;11098.61154859576;11108.777824443305;11267.073582917445;11654.592089276835;12017.75964480688;9922.603095876084;9993.350432221881;10086.445245511273;10115.14021308197;10168.49157542105;9960.750981225527;10175.135113515435;10195.388276703421;10206.285560010729;10217.13154381886 05:00:46 INFO - PROCESS | 1651 | __end_tp_report 05:00:46 INFO - PROCESS | 1651 | __start_cc_report 05:00:46 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1182 05:00:46 INFO - PROCESS | 1651 | __end_cc_report 05:00:46 INFO - PROCESS | 1651 | __startTimestamp1468324846700__endTimestamp 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | ------- Summary: start ------- 05:00:46 INFO - PROCESS | 1651 | Number of tests: 6 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5786.20 Median:5992.34 stddev:673.84 (11.2%) stddev-sans-first:636.55 05:00:46 INFO - PROCESS | 1651 | Values: 2893.6 3377.4 3388.4 3391.3 3404.7 5638.2 5663.4 5727.5 5784.1 5797.7 5778.0 5801.7 5879.9 5880.5 5898.8 4239.9 4735.7 4906.0 5239.6 5395.3 5366.1 5407.5 5429.3 5437.4 5462.9 5220.9 5239.2 5246.9 5271.6 5297.4 5293.0 5302.7 5310.3 5315.5 5319.8 5119.9 5216.5 5263.3 5272.5 5276.2 5738.2 5760.8 5772.3 5778.7 5795.0 6296.2 6341.5 6358.5 6362.7 6366.2 5276.0 5442.3 6247.9 6291.1 6356.2 5860.0 5862.7 5897.8 5925.4 5942.1 6352.8 6362.1 6362.9 6363.4 6368.3 6056.6 6076.6 6143.6 6187.4 6281.7 6185.4 6218.8 6234.6 6238.3 6258.2 6182.7 6196.3 6230.3 6245.6 6252.7 6212.6 6212.9 6217.1 6232.8 6234.0 4905.0 4926.6 4966.2 4992.0 5010.4 4442.1 4517.1 4517.3 4525.4 4530.9 5865.1 5950.5 5987.6 5992.3 6048.2 6246.8 6273.6 6289.1 6297.5 6307.5 5775.5 5810.7 5846.6 5849.6 5977.6 5477.8 5492.5 5498.0 5512.7 5542.3 6337.7 6385.1 6385.3 6390.0 6396.7 6203.4 6209.2 6256.1 6276.9 6287.9 6377.3 6404.8 6405.2 6423.6 6437.9 6254.7 6264.2 6268.2 6274.9 6279.3 6192.4 6195.5 6231.9 6232.0 6310.1 4672.6 6173.0 6217.0 6246.3 6266.5 5986.0 6216.4 6222.4 6271.2 6274.2 5088.0 5971.8 5972.9 5998.1 6102.1 6239.0 6260.8 6264.2 6272.7 6286.2 5916.6 5978.6 6094.6 6098.1 6109.8 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:71786.05 Median:20680.72 stddev:155715.10 (752.9%) stddev-sans-first:156059.96 05:00:46 INFO - PROCESS | 1651 | Values: 507.2 526.6 526.8 544.0 546.3 28253.0 28502.9 30673.5 31733.0 42281.8 37669.0 38631.0 43227.8 43245.6 43417.8 7165.4 7327.0 7365.3 7386.3 7462.5 8794.4 8931.6 8938.9 9148.1 9648.7 8945.7 10350.1 11886.4 11903.8 12145.5 10011.4 12080.6 12096.2 12097.2 12102.9 8630.1 11564.6 11942.5 11969.0 12070.3 38923.2 41193.0 41235.2 41257.2 41299.0 3450.3 3505.6 3560.5 3603.3 3692.4 29783.4 30422.8 30698.0 32882.0 33045.8 3316.1 3331.5 3354.6 3374.5 3468.6 540578.0 544958.0 546241.0 547193.3 548987.0 63928.0 64311.7 64390.1 64448.4 64504.0 67977.7 68072.0 68173.7 68177.0 68280.7 16667.2 16913.0 16918.7 16921.2 16925.6 11843.4 12046.8 12064.3 12190.4 12194.9 18451.0 19061.3 19544.6 19565.7 19603.0 7784.8 7914.5 7915.4 7974.8 7993.8 909.4 919.4 979.8 1164.8 1184.3 25312.4 30894.0 31170.7 31542.4 31686.4 1080.9 1094.9 1114.8 1168.6 1199.6 15857.9 15996.3 16080.8 16279.1 16308.6 666304.0 666681.0 674633.0 674669.0 674684.0 60566.4 61405.0 61436.7 61490.0 61563.0 1259.8 1328.8 1396.1 1412.4 1434.8 31216.5 31977.8 31979.7 32639.8 32797.0 50795.7 51487.0 51756.5 51794.0 51812.2 483411.0 527125.0 528311.0 530397.0 530842.0 27355.0 27597.2 28426.3 28475.9 29221.3 48933.3 49403.8 49993.8 50311.5 50545.7 39965.8 40827.6 41177.4 41863.0 42244.8 40909.4 41000.0 41038.4 42308.0 42364.9 31394.0 32880.0 38456.0 41329.2 42166.8 34449.0 36018.8 36086.0 36452.8 36623.5 26124.3 26243.5 26297.0 26347.1 27743.8 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:32586.64 Median:2330.11 stddev:81498.08 (3497.6%) stddev-sans-first:81690.78 05:00:46 INFO - PROCESS | 1651 | Values: 362.2 380.5 391.4 393.8 414.6 15807.6 16903.2 20884.6 21373.0 21378.7 21417.3 21707.8 21754.2 21770.5 21827.1 5136.6 5914.6 5948.6 6056.9 6149.5 4977.9 5935.4 5947.9 6010.3 6076.8 5730.0 5904.3 5930.8 5937.7 6015.9 5939.5 5942.6 5967.2 6021.3 6030.1 5103.3 5257.4 5259.5 5259.7 5264.6 25317.7 25371.5 25997.7 26579.6 26631.7 2323.6 2336.6 2529.9 2855.2 3173.8 15366.8 15934.8 16069.5 16274.4 16668.2 1891.0 1961.7 1982.6 1990.5 2058.6 108965.5 113487.9 117931.0 119476.4 119859.4 9080.8 10599.9 10856.3 11084.4 11160.2 13669.0 13781.6 13788.7 13791.6 13793.7 8546.6 8803.1 8834.9 8850.2 8869.8 7496.9 8720.0 8881.7 8937.1 9090.0 5794.4 6651.2 6766.0 6776.8 6865.3 1803.0 1832.4 1833.6 1836.7 1838.1 877.6 929.3 998.6 1061.4 1128.2 17928.0 20274.2 23070.9 23092.7 23107.7 924.6 935.3 956.6 961.7 1054.9 11234.4 11679.5 12213.7 12216.6 12230.0 422408.9 481160.0 489604.0 491311.0 499416.0 125875.9 131073.0 133724.0 150599.2 156486.0 822.3 973.8 1035.1 1045.2 1053.4 18244.9 20268.9 20329.6 20726.5 20766.6 56218.7 58730.7 58864.7 59202.1 59644.0 83276.8 83469.6 84302.6 85443.6 85471.0 8152.3 8324.4 8333.9 8365.6 8398.0 12944.2 13003.3 13025.1 13225.7 13305.4 10996.8 11241.7 11259.7 11316.2 11420.8 11121.3 11149.4 11149.4 11293.6 11392.1 11399.1 11521.4 11528.9 11645.4 11765.3 6951.5 7080.7 7532.9 8174.6 8429.2 11253.4 11425.7 11502.5 11537.9 11850.3 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5489.20 Median:5154.84 stddev:2992.61 (58.1%) stddev-sans-first:2985.03 05:00:46 INFO - PROCESS | 1651 | Values: 1374.5 1535.0 1563.1 1727.7 1874.1 7525.8 8174.3 8348.2 8357.1 8509.1 8094.9 8336.0 8336.7 8337.9 8343.3 2033.8 2114.5 2179.6 2344.6 2376.0 2347.0 2364.4 2377.9 2380.1 2382.8 1888.9 2313.1 2331.8 2335.3 2338.9 2035.1 2201.8 2273.5 2274.3 2314.4 2313.8 2328.6 2333.2 2334.1 2339.7 8355.4 8411.1 8414.8 8415.6 8423.1 5049.9 5203.5 5207.0 5210.8 5212.3 3417.9 3471.6 3586.4 3591.8 3596.3 1059.4 1081.5 1089.2 1105.3 1106.5 10871.9 10877.7 10878.6 10895.3 10913.3 4433.4 4445.1 4665.4 4665.7 4670.8 9432.5 9435.9 9439.2 9447.5 9453.3 5211.7 5213.4 5213.9 5214.2 5214.8 4270.0 4426.2 4619.6 4747.8 4782.8 3801.8 3836.2 3923.9 3941.4 3968.8 1073.3 1148.7 1169.6 1174.7 1272.2 792.7 828.2 879.2 922.0 922.2 5837.3 5877.5 5877.5 5954.9 6065.0 946.8 964.0 974.8 1001.4 1003.0 3386.5 3850.0 3994.9 4061.7 4113.4 8349.0 9771.3 10062.4 10142.2 10147.5 10096.2 10099.1 10108.5 10118.0 10123.9 7453.4 7819.6 7847.3 7901.6 7916.4 5751.3 5809.1 5877.2 5947.6 6061.9 7877.2 9072.9 9643.6 9646.5 9655.8 9303.0 9709.7 9776.1 9787.6 9799.3 6763.0 7006.0 7141.8 7285.8 7453.1 7986.3 8157.0 8166.9 8181.2 8182.1 7190.1 7270.4 7337.2 7385.6 7479.3 6907.4 7094.5 7117.0 7149.0 7168.3 5665.0 6848.6 6855.1 6864.9 6870.1 4810.3 4891.8 4911.6 4923.0 5023.6 4934.8 4968.8 4986.3 4990.8 5106.2 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4575.50 Median:5066.87 stddev:1430.89 (28.2%) stddev-sans-first:1398.57 05:00:46 INFO - PROCESS | 1651 | Values: 306.5 315.8 317.9 323.8 326.3 4709.2 5000.9 5024.9 5514.4 5531.3 4469.6 4683.8 5430.6 5452.3 5466.3 3590.6 3618.8 4085.8 4212.9 4227.5 3190.8 3948.0 4047.5 4197.6 4239.7 3372.8 4094.1 4134.8 4147.5 4211.0 3259.5 3394.5 3821.2 3953.2 4177.2 2970.3 3364.3 3639.0 3642.9 3672.8 4293.8 4729.6 5343.7 5348.1 5412.6 3751.8 3895.5 4777.4 4825.6 4828.6 5641.5 5658.7 5667.9 5671.5 5675.3 4017.5 4021.8 4027.7 4029.1 4074.3 5728.8 6037.6 6046.0 6077.8 6144.0 4074.9 4082.0 4087.3 4091.0 4108.5 4170.7 4180.6 4182.0 4191.3 4198.0 4478.9 4630.0 4761.4 4780.0 4821.2 5067.9 5250.7 5578.7 5758.7 5792.7 1141.9 1334.9 1399.3 1402.3 1407.7 727.1 744.7 872.7 881.9 888.7 5065.8 5229.6 5235.8 5250.5 5257.8 4786.8 5119.2 5847.1 5875.6 5931.9 5158.8 5311.0 5349.4 5359.3 5400.6 2573.3 2783.5 2854.8 3036.8 3062.4 5023.5 6008.1 6020.9 6036.6 6079.7 5439.9 5679.9 5910.7 5939.9 6114.6 5744.7 5807.4 6077.6 6108.7 6175.3 5072.7 5542.3 5859.4 5886.4 5909.3 4491.9 5787.9 5821.2 5835.4 5849.0 5357.4 5778.1 6028.4 6097.9 6114.7 3004.6 3659.8 3665.4 4023.5 4040.4 4974.9 5520.0 5688.9 5985.5 6081.7 5885.7 5899.0 5900.5 5905.3 5910.2 4694.6 4892.9 5289.9 5595.4 5670.1 4641.9 5225.8 5461.5 5606.4 5669.6 4653.4 4919.9 4957.7 5359.0 5539.7 4523.5 4539.3 4745.4 5075.0 5392.5 05:00:46 INFO - PROCESS | 1651 | 05:00:46 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:22209.24 Median:4387.02 stddev:33960.16 (774.1%) stddev-sans-first:34017.18 05:00:46 INFO - PROCESS | 1651 | Values: 742.8 743.9 768.0 802.5 807.8 12529.2 15542.5 16266.5 16268.3 16679.4 14985.4 15023.5 15145.5 15184.3 15345.0 701.9 729.6 872.9 894.5 898.2 875.8 916.2 917.6 918.4 919.7 941.4 953.0 953.9 954.3 955.3 734.6 764.7 766.2 945.2 945.3 560.5 560.7 673.3 698.6 699.4 15064.4 15110.0 15111.9 15115.9 15134.2 11974.0 13594.4 13714.2 13720.1 14128.2 11472.8 11944.0 12516.4 12521.6 12582.3 3780.3 4356.3 4478.7 4482.8 4524.4 108317.0 108552.5 108577.9 108705.8 108756.0 10675.9 10868.9 10919.2 10919.8 10992.8 21582.4 21631.0 21637.0 21650.5 21693.7 7828.4 7922.5 7949.3 7957.1 8062.9 4373.5 4396.8 4434.0 4442.8 4579.7 3016.7 3194.2 3267.6 3322.6 3368.7 628.0 634.4 638.2 644.3 650.5 608.2 609.1 628.3 633.2 647.3 8749.9 8828.9 8899.5 8983.0 9065.2 603.3 610.6 629.1 629.4 645.3 4362.9 4377.3 4421.2 4437.4 4496.4 93413.0 93742.5 94078.0 103782.0 149644.0 86175.6 86282.7 102030.5 125758.2 138737.0 97260.5 101710.0 101911.5 102020.5 102944.0 8442.0 9630.7 9671.9 9803.0 9803.7 51616.0 52548.7 53084.2 53411.2 54228.0 81037.0 82716.0 87394.0 87714.6 88352.6 13094.3 13218.9 13349.5 13354.0 13375.7 12085.5 12593.9 13107.2 14642.6 15248.8 12264.9 12315.5 12528.8 12531.2 12543.9 11951.8 12281.3 12420.8 12503.6 12549.2 11098.6 11108.8 11267.1 11654.6 12017.8 9922.6 9993.4 10086.4 10115.1 10168.5 9960.8 10175.1 10195.4 10206.3 10217.1 05:00:46 INFO - PROCESS | 1651 | -------- Summary: end -------- 05:00:46 INFO - PROCESS | 1651 | 05:00:47 INFO - TEST-INFO | 1651: exit 0 05:00:47 INFO - TEST-OK | dromaeo_css | took 1117425ms 05:00:47 INFO - TEST-START | kraken 05:00:47 INFO - operating with platform_type : mac_ 05:00:47 INFO - Initialising browser for kraken test... 05:00:47 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpcyEJgc/profile http://localhost:49271/getInfo.html) 05:00:49 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 05:00:49 INFO - PROCESS | 1661 | colorDepth:24 05:00:49 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 05:00:49 INFO - PROCESS | 1661 | __metrics 05:00:49 INFO - PROCESS | 1661 | 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" 05:00:49 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:00:50 INFO - TEST-INFO | 1661: exit 0 05:00:50 INFO - Browser initialized. 05:00:50 INFO - Running cycle 1/1 for kraken test... 05:00:50 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpcyEJgc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:02 INFO - PROCESS | 1662 | RSS: Main: 243830784 05:01:02 INFO - PROCESS | 1662 | 05:01:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:01:06 INFO - PROCESS | 1662 | RSS: Main: 293756928 05:01:06 INFO - PROCESS | 1662 | 05:01:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:01:09 INFO - PROCESS | 1662 | RSS: Main: 304234496 05:01:09 INFO - PROCESS | 1662 | 05:01:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:01:13 INFO - PROCESS | 1662 | RSS: Main: 387862528 05:01:13 INFO - PROCESS | 1662 | 05:01:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:01:16 INFO - PROCESS | 1662 | RSS: Main: 289259520 05:01:16 INFO - PROCESS | 1662 | 05:01:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:01:19 INFO - PROCESS | 1662 | RSS: Main: 293117952 05:01:19 INFO - PROCESS | 1662 | 05:01:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:01:22 INFO - PROCESS | 1662 | RSS: Main: 330424320 05:01:22 INFO - PROCESS | 1662 | 05:01:25 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:01:26 INFO - PROCESS | 1662 | RSS: Main: 333422592 05:01:26 INFO - PROCESS | 1662 | 05:01:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:01:29 INFO - PROCESS | 1662 | RSS: Main: 334426112 05:01:29 INFO - PROCESS | 1662 | 05:01:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:01:31 INFO - PROCESS | 1662 | RSS: Main: 313331712 05:01:31 INFO - PROCESS | 1662 | 05:01:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:01:34 INFO - PROCESS | 1662 | RSS: Main: 320548864 05:01:34 INFO - PROCESS | 1662 | 05:01:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:01:37 INFO - PROCESS | 1662 | RSS: Main: 317734912 05:01:37 INFO - PROCESS | 1662 | 05:01:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:01:40 INFO - PROCESS | 1662 | RSS: Main: 327004160 05:01:40 INFO - PROCESS | 1662 | 05:01:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:01:44 INFO - PROCESS | 1662 | RSS: Main: 328171520 05:01:44 INFO - PROCESS | 1662 | 05:01:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:01:46 INFO - PROCESS | 1662 | RSS: Main: 371884032 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | __start_tp_report 05:01:46 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:01:46 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:01:46 INFO - PROCESS | 1662 | |i|pagename|runs| 05:01:46 INFO - PROCESS | 1662 | |0;ai-astar;101;163;168;112;112;112;113;112;112;113 05:01:46 INFO - PROCESS | 1662 | |1;audio-beat-detection;142;109;104;160;107;105;128;105;140;107 05:01:46 INFO - PROCESS | 1662 | |2;audio-dft;180;184;185;183;223;262;223;183;182;180 05:01:46 INFO - PROCESS | 1662 | |3;audio-fft;76;75;58;108;74;81;67;54;110;82 05:01:46 INFO - PROCESS | 1662 | |4;audio-oscillator;96;91;91;70;70;68;123;96;99;131 05:01:46 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;90;86;90;87;89;87;91;87;91;87 05:01:46 INFO - PROCESS | 1662 | |6;imaging-darkroom;95;94;104;95;96;92;96;93;131;95 05:01:46 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;83;84;84;86;85;84;85;84;83 05:01:46 INFO - PROCESS | 1662 | |8;json-parse-financial;57;56;56;58;55;54;56;55;57;54 05:01:46 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;67;77;77;72;71;71;72;70;71 05:01:46 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;83;84;86;82;83;83;82;86;86;77 05:01:46 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;137;151;146;154;134;137;133;134;186;139 05:01:46 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;218;186;164;175;163;163;164;163;162;164 05:01:46 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;66;66;65;67;66;66;66;64;66 05:01:46 INFO - PROCESS | 1662 | __end_tp_report 05:01:46 INFO - PROCESS | 1662 | __start_cc_report 05:01:46 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2069 05:01:46 INFO - PROCESS | 1662 | __end_cc_report 05:01:46 INFO - PROCESS | 1662 | __startTimestamp1468324906223__endTimestamp 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:01:46 INFO - PROCESS | 1662 | Number of tests: 14 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:121.80 Median:112.50 stddev:23.33 (20.7%) stddev-sans-first:23.50 05:01:46 INFO - PROCESS | 1662 | Values: 101.0 163.0 168.0 112.0 112.0 112.0 113.0 112.0 112.0 113.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:118.50 stddev:20.30 (17.1%) stddev-sans-first:20.01 05:01:46 INFO - PROCESS | 1662 | Values: 142.0 109.0 104.0 160.0 107.0 105.0 128.0 105.0 140.0 107.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:198.50 Median:184.50 stddev:28.01 (15.2%) stddev-sans-first:28.90 05:01:46 INFO - PROCESS | 1662 | Values: 180.0 184.0 185.0 183.0 223.0 262.0 223.0 183.0 182.0 180.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:78.50 Median:74.50 stddev:18.48 (24.8%) stddev-sans-first:19.58 05:01:46 INFO - PROCESS | 1662 | Values: 76.0 75.0 58.0 108.0 74.0 81.0 67.0 54.0 110.0 82.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:91.00 stddev:21.31 (23.4%) stddev-sans-first:22.58 05:01:46 INFO - PROCESS | 1662 | Values: 96.0 91.0 91.0 70.0 70.0 68.0 123.0 96.0 99.0 131.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.50 stddev:1.90 (2.1%) stddev-sans-first:1.94 05:01:46 INFO - PROCESS | 1662 | Values: 90.0 86.0 90.0 87.0 89.0 87.0 91.0 87.0 91.0 87.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:95.00 stddev:11.67 (12.3%) stddev-sans-first:12.28 05:01:46 INFO - PROCESS | 1662 | Values: 95.0 94.0 104.0 95.0 96.0 92.0 96.0 93.0 131.0 95.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.07 (1.3%) stddev-sans-first:0.97 05:01:46 INFO - PROCESS | 1662 | Values: 86.0 83.0 84.0 84.0 86.0 85.0 84.0 85.0 84.0 83.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.32 (2.4%) stddev-sans-first:1.32 05:01:46 INFO - PROCESS | 1662 | Values: 57.0 56.0 56.0 58.0 55.0 54.0 56.0 55.0 57.0 54.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.50 stddev:3.57 (5.0%) stddev-sans-first:3.20 05:01:46 INFO - PROCESS | 1662 | Values: 66.0 67.0 77.0 77.0 72.0 71.0 71.0 72.0 70.0 71.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.50 stddev:2.70 (3.2%) stddev-sans-first:2.86 05:01:46 INFO - PROCESS | 1662 | Values: 83.0 84.0 86.0 82.0 83.0 83.0 82.0 86.0 86.0 77.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:142.50 stddev:16.16 (11.3%) stddev-sans-first:16.87 05:01:46 INFO - PROCESS | 1662 | Values: 137.0 151.0 146.0 154.0 134.0 137.0 133.0 134.0 186.0 139.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:164.00 stddev:17.81 (10.9%) stddev-sans-first:8.10 05:01:46 INFO - PROCESS | 1662 | Values: 218.0 186.0 164.0 175.0 163.0 163.0 164.0 163.0 162.0 164.0 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 05:01:46 INFO - PROCESS | 1662 | Values: 66.0 66.0 66.0 65.0 67.0 66.0 66.0 66.0 64.0 66.0 05:01:46 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:01:46 INFO - PROCESS | 1662 | 05:01:46 INFO - TEST-INFO | 1662: exit 0 05:01:47 INFO - TEST-OK | kraken | took 59652ms 05:01:47 INFO - SUITE-END | took 1177s 05:01:47 INFO - Completed test suite (00:19:37) 05:01:47 INFO - javascript benchmark 05:01:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2893.5659651052338, 3377.3583019226344, 3388.3731509670697, 3391.3386889556537, 3404.727621790257, 5638.182463542794, 5663.4336566343345, 5727.513161381277, 5784.103463963087, 5797.652140871551, 5777.971110144444, 5801.709914504279, 5879.8530036749, 5880.529557635389, 5898.7935422260225, 4239.936400953973, 4735.692180008288, 4905.975470122645, 5239.607029472805, 5395.325584301962, 5366.114591092465, 5407.5133238008575, 5429.321334833146, 5437.429069947658, 5462.918056229159, 5220.947790522103, 5239.187925871496, 5246.895062098775, 5271.551918086967, 5297.364316282052, 5293.0, 5302.708351040691, 5310.309659744247, 5315.548178404841, 5319.840404787824, 5119.948800511985, 5216.530512253916, 5263.315768950012, 5272.499112584298, 5276.15581506957, 5738.225339579145, 5760.8271751847515, 5772.278465191851, 5778.711064446803, 5794.985877471468, 6296.212973378328, 6341.460975817061, 6358.523110766711, 6362.650054247015, 6366.172397588331, 5275.973620131913, 5442.292501974757, 6247.906616342122, 6291.1192433059405, 6356.173697419306, 5860.0, 5862.736176872051, 5897.823065308047, 5925.4370834770625, 5942.1383899335215, 6352.841178970562, 6362.141110949962, 6362.9045564316575, 6363.363663633692, 6368.267649220399, 6056.636601803906, 6076.601159057303, 6143.56995010345, 6187.3503282154725, 6281.685915704196, 6185.381461853779, 6218.782342617987, 6234.5635805494085, 6238.251409830849, 6258.217722784652, 6182.690865456799, 6196.318404975449, 6230.345813689588, 6245.5628106032145, 6252.74989000444, 6212.596181248205, 6212.9068063979075, 6217.067439884054, 6232.750689972441, 6233.96883015582, 4905.0, 4926.58124059459, 4966.230234313687, 4991.950080499221, 5010.3737032870895, 4442.089371678798, 4517.1417430688725, 4517.34498497716, 4525.366448697185, 4530.88672783183, 5865.090910908816, 5950.464458198783, 5987.64074155552, 5992.34084250732, 6048.243969503812, 6246.843828904313, 6273.623582584968, 6289.0880822280515, 6297.4647154992335, 6307.4954003679595, 5775.48020678141, 5810.738516766755, 5846.561507886926, 5849.561282903799, 5977.581569290195, 5477.808276710296, 5492.47821456961, 5498.010358135574, 5512.6968016759465, 5542.251796007568, 6337.746490140527, 6385.074164246251, 6385.2656944451055, 6390.041493775878, 6396.71214795326, 6203.410675985774, 6209.223847019122, 6256.061590761422, 6276.905846412399, 6287.905681414874, 6377.266614339411, 6404.807855764335, 6405.231372235362, 6423.550351475352, 6437.871242575028, 6254.718537665907, 6264.185655864709, 6268.1851359323, 6274.937250627618, 6279.277883043508, 6192.411720886508, 6195.473384762346, 6231.875362492816, 6232.0, 6310.085037669604, 4672.556107169813, 6172.98145805937, 6216.968915155395, 6246.281677606951, 6266.530010249341, 5985.970070149621, 6216.409441103088, 6222.377762223741, 6271.24745030581, 6274.152989346379, 5087.974560127176, 5971.820845374646, 5972.850678733066, 5998.070299103646, 6102.0541816018585, 6238.970569855923, 6260.843478913063, 6264.21697287839, 6272.655003974643, 6286.18279623646, 5916.55625828056, 5978.551608629283, 6094.603850749687, 6098.146259523582, 6109.816705498843], "value": 5742.123394415245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.2264796185817, 526.610308371805, 526.8340472751083, 544.0, 546.3171036204744, 28253.011144609918, 28502.857485712593, 30673.539896901537, 31732.99999999997, 42281.788591057004, 37669.0, 38631.0, 43227.78386108073, 43245.56754432455, 43417.7829110854, 7165.355118039375, 7326.974223608699, 7365.263473652619, 7386.335229829314, 7462.477626566142, 8794.384393092487, 8931.55342232889, 8938.910610893905, 9148.085191480865, 9648.71053868385, 8945.731628051137, 10350.12023977959, 11886.405679716023, 11903.7619247615, 12145.514179432856, 10011.449370284632, 12080.637580872544, 12096.213746106476, 12097.2136811107, 12102.878971210263, 8630.050694423608, 11564.595239166589, 11942.462589183508, 11969.0, 12070.275783452933, 38923.22153556913, 41193.0, 41235.1752964942, 41257.1748565027, 41299.0, 3450.2926899985696, 3505.5618047744033, 3560.4659301104784, 3603.297357015383, 3692.4461330800327, 29783.40433191324, 30422.84788576065, 30698.0, 32882.01353959385, 33045.843395481286, 3316.1046517440395, 3331.487495697187, 3354.580677415323, 3374.5444365010653, 3468.6357932416986, 540578.0, 544958.0, 546241.0, 547193.2640336812, 548986.999999996, 63928.0, 64311.67844160843, 64390.10244539007, 64448.355516445175, 64504.0, 67977.66011169912, 68072.0, 68173.65913170403, 68176.97734533987, 68280.6585967077, 16667.166641667867, 16912.985220886785, 16918.74621880673, 16921.153942302837, 16925.576860578585, 11843.407829608484, 12046.759064818654, 12064.276143431422, 12190.39048047594, 12194.878051219552, 18450.98519581436, 19061.332853350064, 19544.609107818047, 19565.706514402296, 19602.98138881526, 7784.766457006299, 7914.485558438683, 7915.366770658334, 7974.840503189904, 7993.760187194393, 909.4452384045727, 919.3748251189255, 979.7648564344506, 1164.796160671879, 1184.253226490919, 25312.36719082001, 30894.0, 31170.68829311678, 31542.36915261682, 31686.366272674877, 1080.9459527023616, 1094.9233553651168, 1114.8216285394299, 1168.6260396673154, 1199.622119032502, 15857.920710396374, 15996.280167392262, 16080.839191607934, 16279.10464924417, 16308.592285192964, 666304.0, 666681.0, 674633.0, 674669.0, 674684.0, 60566.394336056954, 61405.0, 61436.692816536524, 61490.0, 61563.0, 1259.7984322508355, 1328.8206092177625, 1396.1343966740685, 1412.4491448334952, 1434.8063011493523, 31216.531752023282, 31977.840110799298, 31979.68020319767, 32639.836800815843, 32797.0, 50795.74602126966, 51487.0, 51756.48243517667, 51794.0, 51812.22281665703, 483411.0, 527125.0, 528311.0, 530397.0, 530842.0, 27355.0, 27597.172084837486, 28426.289342766595, 28475.857620711762, 29221.269468262613, 48933.26600101073, 49403.7529812363, 49993.75003125107, 50311.49688503068, 50545.73635658991, 39965.80017099896, 40827.59172408238, 41177.38233926434, 41863.0, 42244.788776055924, 40909.38635920404, 41000.0, 41038.38442423426, 42308.0, 42364.9408764771, 31394.0, 32880.01359959205, 38456.03859903525, 41329.1734165321, 42166.789166053975, 34449.0, 36018.8199059003, 36086.0, 36452.81773591115, 36623.45064823976, 26124.34689132711, 26243.475130497663, 26297.0, 26347.077852275077, 27743.80559085172], "value": 20551.8204897104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.2465272233754, 380.47684433903385, 391.37966323377447, 393.7814512945316, 414.57506056292294, 15807.604809879744, 16903.23935422906, 20884.582308353823, 21373.0382132804, 21378.679319810177, 21417.250396236133, 21707.806070666105, 21754.238601648947, 21770.455738606543, 21827.126914923385, 5136.64043516954, 5914.615549989258, 5948.643081415129, 6056.9091463627865, 6149.538784591174, 4977.925331120036, 5935.436133567304, 5947.88104237915, 6010.27876654802, 6076.756929722828, 5730.0, 5904.261967254093, 5930.792422265222, 5937.67342796146, 6015.879682406349, 5939.524838012971, 5942.61373010753, 5967.16459695643, 6021.277446706379, 6030.09548567714, 5103.336566246401, 5257.369115706101, 5259.474052594748, 5259.737013149365, 5264.578833693334, 25317.746822531906, 25371.49257014868, 25997.740022599723, 26579.601305980425, 26631.733682663315, 2323.6398358254496, 2336.5794157051723, 2529.860857652828, 2855.200543847726, 3173.7778355515125, 15366.769498457532, 15934.840651593451, 16069.517914462585, 16274.43039493612, 16668.249928753234, 1890.981090189108, 1961.6567100757452, 1982.5836574319267, 1990.532224927141, 2058.5985732782115, 108965.45517272443, 113487.86512134856, 117931.0, 119476.40261798636, 119859.40070299593, 9080.774095497156, 10599.894001060045, 10856.294340867818, 11084.445777711082, 11160.218784684994, 13669.043166978416, 13781.586552403443, 13788.655283617789, 13791.620837916129, 13793.724125517634, 8546.572671366532, 8803.119688031145, 8834.867476987722, 8850.20348168668, 8869.822603547893, 7496.925030749731, 8720.040795512488, 8881.73354799357, 8937.061608531141, 9090.0, 5794.362620111784, 6651.23510796255, 6765.9661701691175, 6776.780179567627, 6865.258434153398, 1802.9909850450663, 1832.3522509973227, 1833.5874428253537, 1836.7336736173181, 1838.1177035023263, 877.591919757316, 929.3401684803865, 998.6205242008135, 1061.379093230469, 1128.2046157458972, 17928.0, 20274.235075744014, 23070.884645576665, 23092.65361019586, 23107.6533851989, 924.5900002496247, 935.3164304252318, 956.5743244256379, 961.6969006995561, 1054.894510548939, 11234.38210898392, 11679.474423650956, 12213.69465763363, 12216.633500994983, 12230.0, 422408.88795555825, 481160.0, 489604.0, 491311.0, 499416.0, 125875.85310367131, 131073.0, 133724.0, 150599.2470037643, 156486.0, 822.2599660305773, 973.776031512756, 1035.13566172247, 1045.2213101239624, 1053.3679792124817, 18244.89836727786, 20268.89865550663, 20329.593408132052, 20726.481837954172, 20766.584668306852, 56218.71890640521, 58730.706346467996, 58864.70567647134, 59202.111968319645, 59644.0, 83276.83615819307, 83469.58265208635, 84302.57848710717, 85443.57278213817, 85471.0, 8152.307053900485, 8324.417290789586, 8333.874991875005, 8365.581720913808, 8397.95801020991, 12944.15862968904, 13003.284819335027, 13025.08824382322, 13225.735485290434, 13305.401256943655, 10996.780064398827, 11241.71895702614, 11259.662210133709, 11316.15128865355, 11420.828687569527, 11121.276202188674, 11149.442527873412, 11149.442527873412, 11293.604723834625, 11392.08863290918, 11399.145064120059, 11521.423928803693, 11528.884711152781, 11645.41772911368, 11765.294082355085, 6951.548149370275, 7080.681369338495, 7532.887006694795, 8174.550399728072, 8429.199226073513, 11253.437328133397, 11425.657230283105, 11502.53989840397, 11537.884621154017, 11850.348230847387], "value": 9926.604955792995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.546399688103, 1535.0405996252343, 1563.0696090328445, 1727.680379129861, 1874.128530233441, 7525.811854703628, 8174.3460523158155, 8348.206920342558, 8357.122502137272, 8509.106543812903, 8094.91905080949, 8335.999680038389, 8336.74989750307, 8337.91662083379, 8343.332533397328, 2033.766116896561, 2114.534802343482, 2179.585878683053, 2344.5779759643215, 2375.9783293183923, 2346.9647955280684, 2364.397078744926, 2377.869217193054, 2380.1312520929823, 2382.809375249976, 1888.9338873139443, 2313.109452860644, 2331.8484298520543, 2335.311083230443, 2338.941526461835, 2035.0740413112073, 2201.801837834603, 2273.533925545259, 2274.306336567346, 2314.432963923841, 2313.8264630152808, 2328.592496313155, 2333.195047708538, 2334.1480359668767, 2339.6724458575814, 8355.373346999, 8411.116832732598, 8414.789630259293, 8415.579221038923, 8423.115572864823, 5049.899002019939, 5203.505666961632, 5206.973965130188, 5210.7915683373, 5212.348456442945, 3417.9145521362166, 3471.63547827477, 3586.3903136466865, 3591.8204089795404, 3596.2627661329625, 1059.369675043348, 1081.4862940103467, 1089.2266490791546, 1105.2815669814604, 1106.4854842498278, 10871.891281087166, 10877.67366978992, 10878.564857405694, 10895.291806032583, 10913.290636108628, 4433.401490798766, 4445.133199026159, 4665.370175026396, 4665.720056796603, 4670.8365207217585, 9432.52837358143, 9435.858462123073, 9439.24486041115, 9447.480388578695, 9453.33826632129, 5211.739413029409, 5213.374395072616, 5213.921791173136, 5214.217867319932, 5214.7914083436235, 4269.957300427018, 4426.225410553205, 4619.630429565694, 4747.8100875965265, 4782.760861956888, 3801.752886062397, 3836.194399176204, 3923.8822835314986, 3941.3693808990424, 3968.761874287552, 1073.3184427888348, 1148.6955956671488, 1169.602335206034, 1174.712195512105, 1272.1540175783186, 792.6512334572769, 828.1676914700687, 879.1691851200554, 921.9812107621116, 922.2253307221965, 5837.270341207349, 5877.471027607536, 5877.52979761618, 5954.85112872173, 6064.969675151596, 946.8390373636498, 963.9759006024906, 974.7660561465198, 1001.4041645221082, 1002.9699109026741, 3386.508956201337, 3850.0, 3994.900127496836, 4061.6547593454297, 4113.424120623115, 8349.0, 9771.31600787938, 10062.446565438824, 10142.18862490999, 10147.543360548792, 10096.192304615613, 10099.14157296638, 10108.545115469822, 10118.038786315288, 10123.949380253052, 7453.366463850525, 7819.6481158348015, 7847.254510821463, 7901.644426000844, 7916.366690664733, 5751.252337196138, 5809.099589563624, 5877.206577112035, 5947.553933455095, 6061.878762424815, 7877.172896845977, 9072.863907041532, 9643.614255429704, 9646.469444180637, 9655.806883862424, 9302.95348523253, 9709.70870873875, 9776.071273229032, 9787.559559819683, 9799.265055121037, 6762.985552167214, 7006.0, 7141.750038748619, 7285.7814265572115, 7453.105627324756, 7986.281234688906, 8157.0, 8166.918330816616, 8181.222783835526, 8182.140875208255, 7190.137183538009, 7270.382017528569, 7337.229590893093, 7385.556866588022, 7479.326860582573, 6907.447404207752, 7094.503384763224, 7117.039199707973, 7148.964255178691, 7168.319009694071, 5664.971675141763, 6848.589084654936, 6855.07456493387, 6864.897026544506, 6870.106886104774, 4810.254410566367, 4891.779869905791, 4911.607071434205, 4923.0153969205585, 5023.572996295356, 4934.753262336941, 4968.826091086795, 4986.276989836526, 4990.80036798524, 5106.183010718267], "value": 4448.831985296875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.4988743404533, 315.84207896051976, 317.8664960716498, 323.7652701791202, 326.3359064304141, 4709.222978208596, 5000.924986125202, 5024.8895463905155, 5514.448555144483, 5531.3362396512375, 4469.553044695529, 4683.76581170941, 5430.615193125747, 5452.291202143716, 5466.316710411199, 3590.5870824855215, 3618.7563710499526, 4085.754854708712, 4212.894677633069, 4227.4927008759, 3190.8245046522434, 3948.0, 4047.4535937648334, 4197.580241975793, 4239.682023848209, 3372.831358432081, 4094.0992981544164, 4134.834606615732, 4147.523034850986, 4211.0, 3259.511073339018, 3394.490826376051, 3821.197548514815, 3953.1895961328, 4177.164567086565, 2970.3465237647683, 3364.2934983653463, 3638.963610363889, 3642.923533585857, 3672.797996110213, 4293.840663020998, 4729.59798417135, 5343.65266257692, 5348.064088784447, 5412.56699464042, 3751.8499260029566, 3895.5130608673917, 4777.355057067286, 4825.589824864891, 4828.637852161102, 5641.492265696107, 5658.699388464796, 5667.8583035424035, 5671.489565939085, 5675.262215911946, 4017.517897852247, 4021.8190181441905, 4027.6979226558124, 4029.1135950090934, 4074.2666320062376, 5728.825590753885, 6037.577369584087, 6045.969770151165, 6077.848053798646, 6143.969280153616, 4074.8573799916862, 4081.9999100220043, 4087.302380428962, 4091.0, 4108.473288927348, 4170.6852696752985, 4180.623743863006, 4182.034443811403, 4191.308434108399, 4197.979010104991, 4478.865634030984, 4630.0, 4761.40482439695, 4779.976100119477, 4821.156297647897, 5067.8986420271385, 5250.737463126905, 5578.716895114147, 5758.74085666146, 5792.710364481759, 1141.8866605060166, 1334.9332533373486, 1399.2514005007272, 1402.3479082226813, 1407.7043820797542, 727.0657205490919, 744.7132853851306, 872.6640243506296, 881.8897637795276, 888.7289376740156, 5065.848024559269, 5229.607779416559, 5235.816746413857, 5250.503606472137, 5257.7896884124975, 4786.798072886511, 5119.235612769063, 5847.1229315603, 5875.559333050039, 5931.851703707442, 5158.767855446439, 5311.0, 5349.389162315421, 5359.3032905722, 5400.62195646301, 2573.3309339571847, 2783.4850552647827, 2854.82871027739, 3036.817790932551, 3062.433449811792, 5023.49765023502, 6008.09878518217, 6020.8795824084145, 6036.577439579188, 6079.726412311456, 5439.918401223985, 5679.858003549862, 5910.734016969247, 5939.910901336483, 6114.57197996136, 5744.707440825864, 5807.415700013454, 6077.574569780073, 6108.694565271719, 6175.2948231061, 5072.746362681925, 5542.334919809648, 5859.384764599656, 5886.41135886425, 5909.320428150818, 4491.932621010622, 5787.9131813021995, 5821.243238378984, 5835.44563266489, 5849.0, 5357.410684824744, 5778.104393818965, 6028.372339468407, 6097.878042439215, 6114.715909659021, 3004.564338170909, 3659.8353074111196, 3665.4318580620047, 4023.4769479967417, 4040.393940908887, 4974.900501990012, 5520.012795393574, 5688.943110568841, 5985.491233245223, 6081.665508397081, 5885.73514191871, 5898.970505147447, 5900.468957793819, 5905.291365036223, 5910.231669882888, 4694.600958918393, 4892.926606100982, 5289.920651190158, 5595.35653399848, 5670.149477578396, 4641.907161856811, 5225.764840582106, 5461.5357694596405, 5606.355269144101, 5669.574781891292, 4653.371794807657, 4919.901601968012, 4957.686213153589, 5358.97320513395, 5539.695316757617, 4523.5250298719475, 4539.296409056601, 4745.4374637695255, 5074.974625126851, 5392.514673679388], "value": 4119.356466137264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.8031571633514, 743.8512297540492, 767.9539227646342, 802.5064585280054, 807.8303556253187, 12529.248245105277, 15542.456014039512, 16266.512004639864, 16268.267927943256, 16679.416220432286, 14985.400583976656, 15023.549293521211, 15145.545633630982, 15184.31670574824, 15345.002574832655, 701.863136688346, 729.6205972894119, 872.8908886389218, 894.4901406198437, 898.2409863665192, 875.8423483772918, 916.1937495004387, 917.554985831871, 918.3571499950061, 919.6919032124247, 941.4304345870752, 953.0, 953.8616900549416, 954.3176628710471, 955.2644463762898, 734.5776178697217, 764.7208768799348, 766.203148725325, 945.1871390604075, 945.3288165402572, 560.5235549782693, 560.7392562458476, 673.2896793882452, 698.6157613312708, 699.3810477727197, 15064.397424103132, 15110.0, 15111.942164048522, 15115.924420377938, 15134.243287835565, 11974.042076633848, 13594.38825252866, 13714.24571648559, 13720.108192967526, 14128.22294773787, 11472.827907581393, 11944.0, 12516.3741812909, 12521.624351269476, 12582.307973061477, 3780.3006443807985, 4356.259435895905, 4478.708883922567, 4482.820687172509, 4524.434445694288, 108317.0, 108552.4572377141, 108577.91422085756, 108705.82588348188, 108756.0, 10675.893241067646, 10868.945655271673, 10919.235653504338, 10919.836202456969, 10992.835107473235, 21582.35252942414, 21631.026603802868, 21637.0, 21650.458738531346, 21693.67459488109, 7828.412869034845, 7922.4850384724805, 7949.3243074339325, 7957.124716281204, 8062.879056814152, 4373.540778218241, 4396.780160991938, 4433.955660443419, 4442.822287108479, 4579.702319349299, 3016.7480495594523, 3194.185482701896, 3267.624223214361, 3322.551455553469, 3368.6631336866117, 628.0485065126337, 634.3751404866204, 638.1831255992337, 644.3266786208424, 650.5218664281749, 608.1728848765675, 609.1441524657863, 628.2555172121051, 633.2432742872315, 647.2976820150095, 8749.868751968726, 8828.8675669865, 8899.510526920953, 8982.955085224663, 9065.229455496225, 603.3363300369558, 610.6275172144988, 629.1286568103119, 629.3863483104028, 645.2934037229227, 4362.8909277267685, 4377.255866502702, 4421.204183246982, 4437.42313499243, 4496.3705081288645, 93413.0, 93742.53128734312, 94078.0, 103782.0, 149644.0, 86175.56912215399, 86282.7057594124, 102030.48984755029, 125758.16660167374, 138737.0, 97260.51369743106, 101709.99999999852, 101911.47132793014, 102020.48989755152, 102944.0, 8441.95779021101, 9630.701666833073, 9671.854922176313, 9802.950985245028, 9803.656872009731, 51616.0, 52548.73725631347, 53084.20373694321, 53411.19883201833, 54228.0, 81037.0, 82716.0, 87394.0, 87714.56142719246, 88352.5582372084, 13094.345282736016, 13218.890085832725, 13349.532766353519, 13354.0, 13375.665608359479, 12085.456154472893, 12593.93703031479, 13107.213567186, 14642.623507498447, 15248.847511524742, 12264.938675306566, 12315.507379704712, 12528.809763072493, 12531.248125112523, 12543.93728031354, 11951.820722689341, 12281.263124212575, 12420.751584968431, 12503.56237531682, 12549.18430302064, 11098.61154859576, 11108.777824443305, 11267.073582917445, 11654.592089276835, 12017.75964480688, 9922.603095876084, 9993.350432221881, 10086.445245511273, 10115.14021308197, 10168.49157542105, 9960.750981225527, 10175.135113515435, 10195.388276703421, 10206.285560010729, 10217.13154381886], "value": 7513.41004123139, "unit": "score"}], "name": "dromaeo_css", "value": 7378.148089305133, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 163.0, 168.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0, 113.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 104.0, 160.0, 107.0, 105.0, 128.0, 105.0, 140.0, 107.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 184.0, 185.0, 183.0, 223.0, 262.0, 223.0, 183.0, 182.0, 180.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 58.0, 108.0, 74.0, 81.0, 67.0, 54.0, 110.0, 82.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 91.0, 91.0, 70.0, 70.0, 68.0, 123.0, 96.0, 99.0, 131.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 87.0, 89.0, 87.0, 91.0, 87.0, 91.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 104.0, 95.0, 96.0, 92.0, 96.0, 93.0, 131.0, 95.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 84.0, 86.0, 85.0, 84.0, 85.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 58.0, 55.0, 54.0, 56.0, 55.0, 57.0, 54.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 77.0, 77.0, 72.0, 71.0, 71.0, 72.0, 70.0, 71.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 86.0, 82.0, 83.0, 83.0, 82.0, 86.0, 86.0, 77.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 151.0, 146.0, 154.0, 134.0, 137.0, 133.0, 134.0, 186.0, 139.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 186.0, 164.0, 175.0, 163.0, 163.0, 164.0, 163.0, 162.0, 164.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 64.0, 66.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1478.4999999999998, "alertThreshold": 2.0}]} 05:01:47 INFO - Return code: 0 05:01:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:01:47 INFO - # TBPL SUCCESS # 05:01:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:47 INFO - 2016-07-12 05:01:47.545 screenresolution[1666:9740] starting screenresolution argv=screenresolution get 05:01:47 INFO - 2016-07-12 05:01:47.547 screenresolution[1666:9740] Display 0: 1600x1200x32@60 05:01:47 INFO - 2016-07-12 05:01:47.553 screenresolution[1667:9743] starting screenresolution argv=screenresolution list 05:01:47 INFO - Available Modes on Display 0 05:01:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:47 INFO - Intel Iris: 05:01:47 INFO - Chipset Model: Intel Iris 05:01:47 INFO - Type: GPU 05:01:47 INFO - Bus: Built-In 05:01:47 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:47 INFO - Vendor: Intel (0x8086) 05:01:47 INFO - Device ID: 0x0a2e 05:01:47 INFO - Revision ID: 0x0009 05:01:47 INFO - Displays: 05:01:47 INFO - Display: 05:01:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:47 INFO - Display Serial Number: 1600x1200 60 05:01:47 INFO - Main Display: Yes 05:01:47 INFO - Mirror: Off 05:01:47 INFO - Online: Yes 05:01:47 INFO - Rotation: Supported 05:01:47 INFO - Return code: 0 05:01:47 INFO - Running post-action listener: _resource_record_post_action 05:01:47 INFO - ##### 05:01:47 INFO - ##### Finished run-tests step (success) 05:01:47 INFO - ##### 05:01:47 INFO - Running post-run listener: _resource_record_post_run 05:01:47 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37893632; Write bytes: 701322752; Read time: 391; Write time: 2094 05:01:47 INFO - TinderboxPrint: CPU usage
53.2% 05:01:47 INFO - TinderboxPrint: I/O read bytes / time
37,893,632 / 391 05:01:47 INFO - TinderboxPrint: I/O write bytes / time
701,322,752 / 2,094 05:01:47 INFO - TinderboxPrint: CPU idle
2,269.7 (46.8%) 05:01:47 INFO - TinderboxPrint: CPU system
964.8 (19.9%) 05:01:47 INFO - TinderboxPrint: CPU user
1,615.2 (33.3%) 05:01:47 INFO - TinderboxPrint: Swap in / out
458,657,792 / 0 05:01:47 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387652096; Write bytes: 381175296; Read time: 28921; Write time: 841 05:01:47 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36935168; Write bytes: 292917248; Read time: 375; Write time: 1200 05:01:48 INFO - Running post-run listener: _upload_blobber_files 05:01:48 INFO - Blob upload gear active. 05:01:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:01:48 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 05:01:48 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'] 05:01:48 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 05:01:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:01:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:01:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:01:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:49 INFO - (blobuploader) - INFO - Done attempting. 05:01:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:01:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:01:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:50 INFO - (blobuploader) - INFO - Done attempting. 05:01:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:01:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:01:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:53 INFO - (blobuploader) - INFO - Done attempting. 05:01:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:01:53 INFO - Return code: 0 05:01:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:01:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:01:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d184c668d323d647c0020fa776c7296ce05e329ea98c4f9a98ac1b23bdee1f31aaf0e65ff848e513e3444cae9bd2db3079efdef1f8950b515a3fb2d41d4574a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53d5b07c21dbd940df0ba079f33202708a1cb76eddf5826f6268cb61aad757367100eb9e4945d68b28c7bbc8b690f52c8931bd05d1e7cde93ab96d5fab8b094c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ddb17aa76fe3f7f2cf7d7f8833b9ce261f0e629021de8a5641f27fff7c112efacd9030b348e049fbe3797071e78a46f02b0f893b928c8150f0ea909951d3be2"} 05:01:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:01:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:01:53 INFO - Contents: 05:01:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d184c668d323d647c0020fa776c7296ce05e329ea98c4f9a98ac1b23bdee1f31aaf0e65ff848e513e3444cae9bd2db3079efdef1f8950b515a3fb2d41d4574a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53d5b07c21dbd940df0ba079f33202708a1cb76eddf5826f6268cb61aad757367100eb9e4945d68b28c7bbc8b690f52c8931bd05d1e7cde93ab96d5fab8b094c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ddb17aa76fe3f7f2cf7d7f8833b9ce261f0e629021de8a5641f27fff7c112efacd9030b348e049fbe3797071e78a46f02b0f893b928c8150f0ea909951d3be2"} 05:01:53 INFO - Running post-run listener: copy_logs_to_upload_dir 05:01:53 INFO - Copying logs to upload dir... 05:01:53 INFO - mkdir: /builds/slave/test/build/upload/logs 05:01:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.432760 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-07-12 05:01:53.259545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 05:01:53.263069) ========= 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.eSb5QcmCP1/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.2clA0bqjWv/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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/6d184c668d323d647c0020fa776c7296ce05e329ea98c4f9a98ac1b23bdee1f31aaf0e65ff848e513e3444cae9bd2db3079efdef1f8950b515a3fb2d41d4574a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53d5b07c21dbd940df0ba079f33202708a1cb76eddf5826f6268cb61aad757367100eb9e4945d68b28c7bbc8b690f52c8931bd05d1e7cde93ab96d5fab8b094c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ddb17aa76fe3f7f2cf7d7f8833b9ce261f0e629021de8a5641f27fff7c112efacd9030b348e049fbe3797071e78a46f02b0f893b928c8150f0ea909951d3be2"} build_url:https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/ZreaN0dETYGJQzV1uDp8CQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d184c668d323d647c0020fa776c7296ce05e329ea98c4f9a98ac1b23bdee1f31aaf0e65ff848e513e3444cae9bd2db3079efdef1f8950b515a3fb2d41d4574a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53d5b07c21dbd940df0ba079f33202708a1cb76eddf5826f6268cb61aad757367100eb9e4945d68b28c7bbc8b690f52c8931bd05d1e7cde93ab96d5fab8b094c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ddb17aa76fe3f7f2cf7d7f8833b9ce261f0e629021de8a5641f27fff7c112efacd9030b348e049fbe3797071e78a46f02b0f893b928c8150f0ea909951d3be2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 05:01:53.296084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:01:53.296429) ========= 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.eSb5QcmCP1/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.2clA0bqjWv/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.005053 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:01:53.345372) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 05:01:53.345731) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 05:01:53.346045) ========= ========= Total master_lag: 0.18 =========