builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0330 starttime: 1467744712.66 results: success (0) buildid: 20160705090825 builduid: 158124dc7e534257bda68efccd2882bc revision: 8d66e60af350fd0255f798e608db1b6bfe61beda ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.661731) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.662182) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.662464) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.687098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.687375) ========= 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.mincwp86lk/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.3VOEnSez8m/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-05 11:51:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.6M=0.001s 2016-07-05 11:51:52 (21.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.833228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.833797) ========= 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.mincwp86lk/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.3VOEnSez8m/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.025575 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:52.885621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 11:51:52.886474) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8d66e60af350fd0255f798e608db1b6bfe61beda --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 8d66e60af350fd0255f798e608db1b6bfe61beda --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mincwp86lk/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.3VOEnSez8m/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-05 11:51:52,967 truncating revision to first 12 chars 2016-07-05 11:51:52,967 Setting DEBUG logging. 2016-07-05 11:51:52,967 attempt 1/10 2016-07-05 11:51:52,967 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8d66e60af350?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 11:51:53,696 unpacking tar archive at: autoland-8d66e60af350/testing/mozharness/ program finished with exit code 0 elapsedTime=0.998847 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 11:51:54.240142) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:54.240434) ========= script_repo_revision: 8d66e60af350fd0255f798e608db1b6bfe61beda ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:54.240801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:54.244127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 11:51:54.263854) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-05 11:51:54.264183) ========= /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.mincwp86lk/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.3VOEnSez8m/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:51:54 INFO - MultiFileLogger online at 20160705 11:51:54 in /builds/slave/test 11:51:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:51:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:51:54 INFO - {'append_to_log': False, 11:51:54 INFO - 'base_work_dir': '/builds/slave/test', 11:51:54 INFO - 'blob_upload_branch': 'Autoland', 11:51:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:51:54 INFO - 'branch': 'Autoland', 11:51:54 INFO - 'buildbot_json_path': 'buildprops.json', 11:51:54 INFO - 'config_files': ('talos/mac_config.py',), 11:51:54 INFO - 'default_actions': ('clobber', 11:51:54 INFO - 'read-buildbot-config', 11:51:54 INFO - 'download-and-extract', 11:51:54 INFO - 'populate-webroot', 11:51:54 INFO - 'create-virtualenv', 11:51:54 INFO - 'install', 11:51:54 INFO - 'run-tests'), 11:51:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:51:54 INFO - 'download_minidump_stackwalk': True, 11:51:54 INFO - 'download_symbols': 'ondemand', 11:51:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:51:54 INFO - 'tooltool.py': '/tools/tooltool.py', 11:51:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:51:54 INFO - '/tools/misc-python/virtualenv.py')}, 11:51:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:51:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:51:54 INFO - 'installer_path': 'installer.exe', 11:51:54 INFO - 'log_level': 'info', 11:51:54 INFO - 'log_name': 'talos', 11:51:54 INFO - 'log_to_console': True, 11:51:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:51:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:51:54 INFO - 'opt_config_files': (), 11:51:54 INFO - 'pip_index': False, 11:51:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:51:54 INFO - 'cmd': ('bash', 11:51:54 INFO - '-c', 11:51:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:51:54 INFO - 'enabled': True, 11:51:54 INFO - 'halt_on_failure': False, 11:51:54 INFO - 'name': 'check_screen_resolution'},), 11:51:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:51:54 INFO - 'cmd': ('bash', 11:51:54 INFO - '-c', 11:51:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:51:54 INFO - 'enabled': True, 11:51:54 INFO - 'halt_on_failure': False, 11:51:54 INFO - 'name': 'check_screen_resolution'},), 11:51:54 INFO - 'run_cmd_checks_enabled': True, 11:51:54 INFO - 'sps_profile': False, 11:51:54 INFO - 'sps_profile_interval': 0, 11:51:54 INFO - 'suite': 'dromaeojs', 11:51:54 INFO - 'system_bits': '32', 11:51:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:51:54 INFO - 'title': 't-yosemite-r7-0330', 11:51:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:51:54 INFO - 'use_talos_json': True, 11:51:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:51:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:51:54 INFO - 'work_dir': 'build'} 11:51:54 INFO - ##### 11:51:54 INFO - ##### Running clobber step. 11:51:54 INFO - ##### 11:51:54 INFO - Running pre-action listener: _resource_record_pre_action 11:51:54 INFO - Running main action method: clobber 11:51:54 INFO - rmtree: /builds/slave/test/build 11:51:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:51:56 INFO - Running post-action listener: _resource_record_post_action 11:51:56 INFO - ##### 11:51:56 INFO - ##### Finished clobber step (success) 11:51:56 INFO - ##### 11:51:56 INFO - ##### 11:51:56 INFO - ##### Running read-buildbot-config step. 11:51:56 INFO - ##### 11:51:56 INFO - Running pre-action listener: _resource_record_pre_action 11:51:56 INFO - Running main action method: read_buildbot_config 11:51:56 INFO - Using buildbot properties: 11:51:56 INFO - { 11:51:56 INFO - "project": "", 11:51:56 INFO - "product": "firefox", 11:51:56 INFO - "script_repo_revision": "production", 11:51:56 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:51:56 INFO - "repository": "", 11:51:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:51:56 INFO - "buildid": "20160705090825", 11:51:56 INFO - "pgo_build": "False", 11:51:56 INFO - "basedir": "/builds/slave/test", 11:51:56 INFO - "buildnumber": 5, 11:51:56 INFO - "slavename": "t-yosemite-r7-0330", 11:51:56 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:51:56 INFO - "platform": "yosemite_r7", 11:51:56 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:51:56 INFO - "branch": "autoland", 11:51:56 INFO - "repo_path": "integration/autoland", 11:51:56 INFO - "revision": "8d66e60af350fd0255f798e608db1b6bfe61beda", 11:51:56 INFO - "stage_platform": "macosx64", 11:51:56 INFO - "builduid": "158124dc7e534257bda68efccd2882bc", 11:51:56 INFO - "slavebuilddir": "test" 11:51:56 INFO - } 11:51:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:51:56 INFO - Running post-action listener: _resource_record_post_action 11:51:56 INFO - ##### 11:51:56 INFO - ##### Finished read-buildbot-config step (success) 11:51:56 INFO - ##### 11:51:56 INFO - ##### 11:51:56 INFO - ##### Running download-and-extract step. 11:51:56 INFO - ##### 11:51:56 INFO - Running pre-action listener: _resource_record_pre_action 11:51:56 INFO - Running main action method: download_and_extract 11:51:56 INFO - mkdir: /builds/slave/test/build/tests 11:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:51:56 INFO - https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:51:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:51:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:51:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:51:57 INFO - Downloaded 1199 bytes. 11:51:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:51:57 INFO - Using the following test package requirements: 11:51:57 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:51:57 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:51:57 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:51:57 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'jsshell-mac.zip'], 11:51:57 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:51:57 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:51:57 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:51:57 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:51:57 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:51:57 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:51:57 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:51:57 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:51:57 INFO - https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:51:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:51:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:51:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:51:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:51:58 INFO - Downloaded 18802258 bytes. 11:51:58 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'] 11:51:58 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 11:52:01 INFO - Return code: 0 11:52:01 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 11:52:01 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')]} 11:52:01 INFO - https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:52:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:52:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:52:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:52:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:52:03 INFO - Downloaded 18802258 bytes. 11:52:03 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'] 11:52:03 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 11:52:07 INFO - Return code: 0 11:52: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')]} 11:52:07 INFO - https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:52:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:52:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:52:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:52:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:52:08 INFO - Downloaded 14403333 bytes. 11:52:08 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'] 11:52:08 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 11:52:08 INFO - Return code: 0 11:52: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')]} 11:52:08 INFO - https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:52:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:52:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:52:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:52:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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 11:52:11 INFO - Downloaded 112540590 bytes. 11:52:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:52:11 INFO - mkdir: /builds/slave/test/properties 11:52:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:52:11 INFO - Writing to file /builds/slave/test/properties/build_url 11:52:11 INFO - Contents: 11:52:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:52:12 INFO - Running post-action listener: _resource_record_post_action 11:52:12 INFO - Running post-action listener: set_extra_try_arguments 11:52:12 INFO - ##### 11:52:12 INFO - ##### Finished download-and-extract step (success) 11:52:12 INFO - ##### 11:52:12 INFO - ##### 11:52:12 INFO - ##### Running populate-webroot step. 11:52:12 INFO - ##### 11:52:12 INFO - Running pre-action listener: _resource_record_pre_action 11:52:12 INFO - Running main action method: populate_webroot 11:52:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:52:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:52:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:52:12 INFO - '--tppagecycles', 11:52:12 INFO - '7'], 11:52:12 INFO - 'tests': ['tsvgm']}}, 11:52:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:52:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:52:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:52:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:52:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['damp', 'tps']}, 11:52:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'tests': ['damp', 'tps']}, 11:52:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['dromaeo_dom']}, 11:52:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:52:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['basic_compositor_video']}, 11:52:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:52:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['a11yr', 11:52:12 INFO - 'ts_paint', 11:52:12 INFO - 'tpaint', 11:52:12 INFO - 'sessionrestore', 11:52:12 INFO - 'sessionrestore_no_auto_restore', 11:52:12 INFO - 'tabpaint']}, 11:52:12 INFO - 'other-e10s': {'tests': ['a11yr', 11:52:12 INFO - 'ts_paint', 11:52:12 INFO - 'tpaint', 11:52:12 INFO - 'sessionrestore', 11:52:12 INFO - 'sessionrestore_no_auto_restore', 11:52:12 INFO - 'tabpaint']}, 11:52:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:52:12 INFO - 'ts_paint', 11:52:12 INFO - 'tpaint', 11:52:12 INFO - 'sessionrestore', 11:52:12 INFO - 'sessionrestore_no_auto_restore', 11:52:12 INFO - 'tabpaint']}, 11:52:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:52:12 INFO - 'ts_paint', 11:52:12 INFO - 'tpaint', 11:52:12 INFO - 'sessionrestore', 11:52:12 INFO - 'sessionrestore_no_auto_restore', 11:52:12 INFO - 'tabpaint']}, 11:52:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['a11yr', 11:52:12 INFO - 'ts_paint', 11:52:12 INFO - 'tpaint', 11:52:12 INFO - 'sessionrestore', 11:52:12 INFO - 'sessionrestore_no_auto_restore', 11:52:12 INFO - 'tabpaint']}, 11:52:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['a11yr', 11:52:12 INFO - 'ts_paint', 11:52:12 INFO - 'tpaint', 11:52:12 INFO - 'sessionrestore', 11:52:12 INFO - 'sessionrestore_no_auto_restore', 11:52:12 INFO - 'tabpaint']}, 11:52:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['tsvgx', 11:52:12 INFO - 'tsvgr_opacity', 11:52:12 INFO - 'tart', 11:52:12 INFO - 'tscrollx', 11:52:12 INFO - 'cart']}, 11:52:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:52:12 INFO - 'tsvgr_opacity', 11:52:12 INFO - 'tart', 11:52:12 INFO - 'tscrollx', 11:52:12 INFO - 'cart']}, 11:52:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'talos_options': ['--disable-e10s'], 11:52:12 INFO - 'tests': ['tp5o']}, 11:52:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'tests': ['tp5o']}, 11:52:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'talos_options': ['--disable-e10s', 11:52:12 INFO - '--xperf_path', 11:52:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:12 INFO - 'tests': ['tp5n']}, 11:52:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:12 INFO - 'talos_options': ['--xperf_path', 11:52:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:12 INFO - 'tests': ['tp5n']}}, 11:52:12 INFO - 'talos.zip': {'path': '', 11:52:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:52:12 INFO - Running post-action listener: _resource_record_post_action 11:52:12 INFO - ##### 11:52:12 INFO - ##### Finished populate-webroot step (success) 11:52:12 INFO - ##### 11:52:12 INFO - ##### 11:52:12 INFO - ##### Running create-virtualenv step. 11:52:12 INFO - ##### 11:52:12 INFO - Running pre-action listener: _resource_record_pre_action 11:52:12 INFO - Running main action method: create_virtualenv 11:52:12 INFO - Creating virtualenv /builds/slave/test/build/venv 11:52:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:52:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:52:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:52:12 INFO - Using real prefix '/tools/python27' 11:52:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:52:13 INFO - Installing distribute.............................................................................................................................................................................................done. 11:52:16 INFO - Installing pip.................done. 11:52:16 INFO - Return code: 0 11:52:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:16 INFO - 'HOME': '/Users/cltbld', 11:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:16 INFO - 'LOGNAME': 'cltbld', 11:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:16 INFO - 'MOZ_NO_REMOTE': '1', 11:52:16 INFO - 'NO_EM_RESTART': '1', 11:52:16 INFO - 'PAGER': '/bin/cat', 11:52:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:16 INFO - 'PWD': '/builds/slave/test', 11:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:16 INFO - 'SHELL': '/bin/bash', 11:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:16 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:16 INFO - 'USER': 'cltbld', 11:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:16 INFO - 'XPC_FLAGS': '0x0', 11:52:16 INFO - 'XPC_SERVICE_NAME': '0', 11:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:52:16 INFO - Cleaning up... 11:52:16 INFO - Return code: 0 11:52:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:52:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:16 INFO - 'HOME': '/Users/cltbld', 11:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:16 INFO - 'LOGNAME': 'cltbld', 11:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:16 INFO - 'MOZ_NO_REMOTE': '1', 11:52:16 INFO - 'NO_EM_RESTART': '1', 11:52:16 INFO - 'PAGER': '/bin/cat', 11:52:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:16 INFO - 'PWD': '/builds/slave/test', 11:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:16 INFO - 'SHELL': '/bin/bash', 11:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:16 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:16 INFO - 'USER': 'cltbld', 11:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:16 INFO - 'XPC_FLAGS': '0x0', 11:52:16 INFO - 'XPC_SERVICE_NAME': '0', 11:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:16 INFO - Downloading/unpacking psutil>=3.1.1 11:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:52:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:19 INFO - Installing collected packages: psutil 11:52:19 INFO - Running setup.py install for psutil 11:52:19 INFO - building 'psutil._psutil_osx' extension 11:52:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:52:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:52:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:52:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:52:20 INFO - building 'psutil._psutil_posix' extension 11:52:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:52:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:52:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:52:20 INFO - ^ 11:52:20 INFO - 1 warning generated. 11:52:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:52:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:20 INFO - Successfully installed psutil 11:52:20 INFO - Cleaning up... 11:52:20 INFO - Return code: 0 11:52:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:20 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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:52:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:20 INFO - 'HOME': '/Users/cltbld', 11:52:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:20 INFO - 'LOGNAME': 'cltbld', 11:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:20 INFO - 'MOZ_NO_REMOTE': '1', 11:52:20 INFO - 'NO_EM_RESTART': '1', 11:52:20 INFO - 'PAGER': '/bin/cat', 11:52:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:20 INFO - 'PWD': '/builds/slave/test', 11:52:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:20 INFO - 'SHELL': '/bin/bash', 11:52:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:20 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:20 INFO - 'USER': 'cltbld', 11:52:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:20 INFO - 'XPC_FLAGS': '0x0', 11:52:20 INFO - 'XPC_SERVICE_NAME': '0', 11:52:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:52:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:52:23 INFO - Installing collected packages: mozsystemmonitor 11:52:23 INFO - Running setup.py install for mozsystemmonitor 11:52:23 INFO - Successfully installed mozsystemmonitor 11:52:23 INFO - Cleaning up... 11:52:23 INFO - Return code: 0 11:52:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:23 INFO - 'HOME': '/Users/cltbld', 11:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:23 INFO - 'LOGNAME': 'cltbld', 11:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:23 INFO - 'MOZ_NO_REMOTE': '1', 11:52:23 INFO - 'NO_EM_RESTART': '1', 11:52:23 INFO - 'PAGER': '/bin/cat', 11:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:23 INFO - 'PWD': '/builds/slave/test', 11:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:23 INFO - 'SHELL': '/bin/bash', 11:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:23 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:23 INFO - 'USER': 'cltbld', 11:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:23 INFO - 'XPC_FLAGS': '0x0', 11:52:23 INFO - 'XPC_SERVICE_NAME': '0', 11:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:24 INFO - Downloading/unpacking jsonschema==2.5.1 11:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:52:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:26 INFO - Downloading functools32-3.2.3-2.tar.gz 11:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:52:27 INFO - warning: no files found matching '*.txt' 11:52:27 INFO - no previously-included directories found matching 'build' 11:52:27 INFO - no previously-included directories found matching 'dist' 11:52:27 INFO - no previously-included directories found matching '.git*' 11:52:27 INFO - Installing collected packages: jsonschema, functools32 11:52:27 INFO - Running setup.py install for functools32 11:52:27 INFO - warning: no files found matching '*.txt' 11:52:27 INFO - no previously-included directories found matching 'build' 11:52:27 INFO - no previously-included directories found matching 'dist' 11:52:27 INFO - no previously-included directories found matching '.git*' 11:52:27 INFO - Successfully installed jsonschema functools32 11:52:27 INFO - Cleaning up... 11:52:27 INFO - Return code: 0 11:52:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:27 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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:52:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:27 INFO - 'HOME': '/Users/cltbld', 11:52:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:27 INFO - 'LOGNAME': 'cltbld', 11:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:27 INFO - 'MOZ_NO_REMOTE': '1', 11:52:27 INFO - 'NO_EM_RESTART': '1', 11:52:27 INFO - 'PAGER': '/bin/cat', 11:52:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:27 INFO - 'PWD': '/builds/slave/test', 11:52:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:27 INFO - 'SHELL': '/bin/bash', 11:52:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:27 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:27 INFO - 'USER': 'cltbld', 11:52:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:27 INFO - 'XPC_FLAGS': '0x0', 11:52:27 INFO - 'XPC_SERVICE_NAME': '0', 11:52:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:27 INFO - Downloading/unpacking blobuploader==1.2.4 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:30 INFO - Downloading blobuploader-1.2.4.tar.gz 11:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:52:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:52:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:31 INFO - Downloading docopt-0.6.1.tar.gz 11:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:52:31 INFO - Installing collected packages: blobuploader, requests, docopt 11:52:31 INFO - Running setup.py install for blobuploader 11:52:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:52:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:52:31 INFO - Running setup.py install for requests 11:52:32 INFO - Running setup.py install for docopt 11:52:32 INFO - Successfully installed blobuploader requests docopt 11:52:32 INFO - Cleaning up... 11:52:32 INFO - Return code: 0 11:52:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:32 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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:32 INFO - 'HOME': '/Users/cltbld', 11:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:32 INFO - 'LOGNAME': 'cltbld', 11:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:32 INFO - 'MOZ_NO_REMOTE': '1', 11:52:32 INFO - 'NO_EM_RESTART': '1', 11:52:32 INFO - 'PAGER': '/bin/cat', 11:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:32 INFO - 'PWD': '/builds/slave/test', 11:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:32 INFO - 'SHELL': '/bin/bash', 11:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:32 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:32 INFO - 'USER': 'cltbld', 11:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:32 INFO - 'XPC_FLAGS': '0x0', 11:52:32 INFO - 'XPC_SERVICE_NAME': '0', 11:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:32 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-3LbvU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:32 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-yKOJmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:32 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-I4cBVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:32 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-r0aXq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:32 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-04sTma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PcVl11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-x1GDEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-3622MB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PiALLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-SZqHVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-s2Xc9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-JuYO2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-TYB7iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-pNhGK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:33 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-wrOrFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-kMqzCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:34 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-E6CDNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:52:34 INFO - Running setup.py install for manifestparser 11:52:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:52:34 INFO - Running setup.py install for mozcrash 11:52:34 INFO - Running setup.py install for mozdebug 11:52:34 INFO - Running setup.py install for mozdevice 11:52:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:52:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:52:34 INFO - Running setup.py install for mozfile 11:52:35 INFO - Running setup.py install for mozhttpd 11:52:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozinfo 11:52:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozInstall 11:52:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozleak 11:52:35 INFO - Running setup.py install for mozlog 11:52:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for moznetwork 11:52:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozprocess 11:52:36 INFO - Running setup.py install for mozprofile 11:52:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for mozrunner 11:52:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for mozscreenshot 11:52:36 INFO - Running setup.py install for moztest 11:52:36 INFO - Running setup.py install for mozversion 11:52:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:52:36 INFO - Cleaning up... 11:52:36 INFO - Return code: 0 11:52:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:36 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')]} 11:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:36 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')]} 11:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:36 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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:36 INFO - 'HOME': '/Users/cltbld', 11:52:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:36 INFO - 'LOGNAME': 'cltbld', 11:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:36 INFO - 'MOZ_NO_REMOTE': '1', 11:52:36 INFO - 'NO_EM_RESTART': '1', 11:52:36 INFO - 'PAGER': '/bin/cat', 11:52:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:36 INFO - 'PWD': '/builds/slave/test', 11:52:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:36 INFO - 'SHELL': '/bin/bash', 11:52:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:36 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:36 INFO - 'USER': 'cltbld', 11:52:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:36 INFO - 'XPC_FLAGS': '0x0', 11:52:36 INFO - 'XPC_SERVICE_NAME': '0', 11:52:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-SrL4dJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ii5B0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-AeqOVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-8C8kcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zpMtLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-vw3O3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-LLPFeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zNnTWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:37 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-4oMfb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-KXwfEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-owyqen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-_mN2uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-NPLflr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-N70Ejg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-5xpa6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PBsZU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:38 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-kuVzrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:41 INFO - Downloading blessings-1.6.tar.gz 11:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:52:41 INFO - Installing collected packages: blessings 11:52:41 INFO - Running setup.py install for blessings 11:52:41 INFO - Successfully installed blessings 11:52:41 INFO - Cleaning up... 11:52:41 INFO - Return code: 0 11:52:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:52:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:41 INFO - Reading from file tmpfile_stdout 11:52:41 INFO - Current package versions: 11:52:41 INFO - blessings == 1.6 11:52:41 INFO - blobuploader == 1.2.4 11:52:41 INFO - docopt == 0.6.1 11:52:41 INFO - functools32 == 3.2.3-2 11:52:41 INFO - jsonschema == 2.5.1 11:52:41 INFO - manifestparser == 1.1 11:52:41 INFO - mozInstall == 1.12 11:52:41 INFO - mozcrash == 0.17 11:52:41 INFO - mozdebug == 0.1 11:52:41 INFO - mozdevice == 0.48 11:52:41 INFO - mozfile == 1.2 11:52:41 INFO - mozhttpd == 0.7 11:52:41 INFO - mozinfo == 0.9 11:52:41 INFO - mozleak == 0.1 11:52:41 INFO - mozlog == 3.2 11:52:41 INFO - moznetwork == 0.27 11:52:41 INFO - mozprocess == 0.22 11:52:41 INFO - mozprofile == 0.28 11:52:41 INFO - mozrunner == 6.11 11:52:41 INFO - mozscreenshot == 0.1 11:52:41 INFO - mozsystemmonitor == 0.3 11:52:41 INFO - moztest == 0.7 11:52:41 INFO - mozversion == 1.4 11:52:41 INFO - psutil == 3.1.1 11:52:41 INFO - requests == 1.2.3 11:52:41 INFO - wsgiref == 0.1.2 11:52:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:41 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')]} 11:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:41 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')]} 11:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:41 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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:41 INFO - 'HOME': '/Users/cltbld', 11:52:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:41 INFO - 'LOGNAME': 'cltbld', 11:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:41 INFO - 'MOZ_NO_REMOTE': '1', 11:52:41 INFO - 'NO_EM_RESTART': '1', 11:52:41 INFO - 'PAGER': '/bin/cat', 11:52:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:41 INFO - 'PWD': '/builds/slave/test', 11:52:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:41 INFO - 'SHELL': '/bin/bash', 11:52:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:41 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:41 INFO - 'USER': 'cltbld', 11:52:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:41 INFO - 'XPC_FLAGS': '0x0', 11:52:41 INFO - 'XPC_SERVICE_NAME': '0', 11:52:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:42 INFO - Cleaning up... 11:52:42 INFO - Return code: 0 11:52:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:42 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 0x10e8dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e972618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb93149a280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, '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.mincwp86lk/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.3VOEnSez8m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:42 INFO - 'HOME': '/Users/cltbld', 11:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:42 INFO - 'LOGNAME': 'cltbld', 11:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:42 INFO - 'MOZ_NO_REMOTE': '1', 11:52:42 INFO - 'NO_EM_RESTART': '1', 11:52:42 INFO - 'PAGER': '/bin/cat', 11:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:42 INFO - 'PWD': '/builds/slave/test', 11:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:42 INFO - 'SHELL': '/bin/bash', 11:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:52:42 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:42 INFO - 'USER': 'cltbld', 11:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:42 INFO - 'XPC_FLAGS': '0x0', 11:52:42 INFO - 'XPC_SERVICE_NAME': '0', 11:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:52:42 INFO - Cleaning up... 11:52:42 INFO - Return code: 0 11:52:42 INFO - Running post-action listener: _resource_record_post_action 11:52:42 INFO - Running post-action listener: _start_resource_monitoring 11:52:42 INFO - Starting resource monitoring. 11:52:42 INFO - ##### 11:52:42 INFO - ##### Finished create-virtualenv step (success) 11:52:42 INFO - ##### 11:52:42 INFO - ##### 11:52:42 INFO - ##### Running install step. 11:52:42 INFO - ##### 11:52:42 INFO - Running pre-action listener: _resource_record_pre_action 11:52:42 INFO - Running main action method: install 11:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:42 INFO - Reading from file tmpfile_stdout 11:52:42 INFO - Detecting whether we're running mozinstall >=1.0... 11:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:52:42 INFO - Reading from file tmpfile_stdout 11:52:42 INFO - Output received: 11:52:42 INFO - Usage: mozinstall [options] installer 11:52:42 INFO - Options: 11:52:42 INFO - -h, --help show this help message and exit 11:52:42 INFO - -d DEST, --destination=DEST 11:52:42 INFO - Directory to install application into. [default: 11:52:42 INFO - "/builds/slave/test"] 11:52:42 INFO - --app=APP Application being installed. [default: firefox] 11:52:42 INFO - mkdir: /builds/slave/test/build/application 11:52:42 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'] 11:52:42 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 11:53:18 INFO - Reading from file tmpfile_stdout 11:53:18 INFO - Output received: 11:53:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:53:18 INFO - Running post-action listener: _resource_record_post_action 11:53:18 INFO - ##### 11:53:18 INFO - ##### Finished install step (success) 11:53:18 INFO - ##### 11:53:18 INFO - ##### 11:53:18 INFO - ##### Running run-tests step. 11:53:18 INFO - ##### 11:53:18 INFO - Running pre-action listener: _resource_record_pre_action 11:53:18 INFO - Running main action method: run_tests 11:53:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:53:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:53:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:53:18 INFO - 2016-07-05 11:53:18.090 screenresolution[1655:7529] starting screenresolution argv=screenresolution get 11:53:18 INFO - 2016-07-05 11:53:18.093 screenresolution[1655:7529] Display 0: 1600x1200x32@60 11:53:18 INFO - 2016-07-05 11:53:18.099 screenresolution[1656:7531] starting screenresolution argv=screenresolution list 11:53:18 INFO - Available Modes on Display 0 11:53:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:53:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:53:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:53:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:53:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:53:18 INFO - Intel Iris: 11:53:18 INFO - Chipset Model: Intel Iris 11:53:18 INFO - Type: GPU 11:53:18 INFO - Bus: Built-In 11:53:18 INFO - VRAM (Dynamic, Max): 1536 MB 11:53:18 INFO - Vendor: Intel (0x8086) 11:53:18 INFO - Device ID: 0x0a2e 11:53:18 INFO - Revision ID: 0x0009 11:53:18 INFO - Displays: 11:53:18 INFO - Display: 11:53:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:53:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:53:18 INFO - Display Serial Number: 1600x1200 60 11:53:18 INFO - Main Display: Yes 11:53:18 INFO - Mirror: Off 11:53:18 INFO - Online: Yes 11:53:18 INFO - Rotation: Supported 11:53:18 INFO - Return code: 0 11:53:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:53:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:53:18 INFO - Python 2.7.3 11:53:18 INFO - Return code: 0 11:53:18 INFO - grabbing minidump binary from tooltool 11:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:53:18 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 0x10e841d30>, '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 0x10e5a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e839b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:53:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:53:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:53:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:53:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:53:18 INFO - Return code: 0 11:53:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:53:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:53:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:53:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:53:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:53:18 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/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:53:18 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/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mincwp86lk/Render', 11:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:18 INFO - 'HOME': '/Users/cltbld', 11:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:18 INFO - 'LOGNAME': 'cltbld', 11:53:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:53:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:18 INFO - 'MOZ_NO_REMOTE': '1', 11:53:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:53:18 INFO - 'NO_EM_RESTART': '1', 11:53:18 INFO - 'PAGER': '/bin/cat', 11:53:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:18 INFO - 'PWD': '/builds/slave/test', 11:53:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:18 INFO - 'SHELL': '/bin/bash', 11:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3VOEnSez8m/Listeners', 11:53:18 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 11:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:18 INFO - 'USER': 'cltbld', 11:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:18 INFO - 'XPC_FLAGS': '0x0', 11:53:18 INFO - 'XPC_SERVICE_NAME': '0', 11:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:18 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/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:53:18 INFO - mozversion application_buildid: 20160705090825 11:53:18 INFO - mozversion application_changeset: 8d66e60af350fd0255f798e608db1b6bfe61beda 11:53:18 INFO - mozversion application_display_name: Nightly 11:53:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:53:18 INFO - mozversion application_name: Firefox 11:53:18 INFO - mozversion application_remotingname: firefox 11:53:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:53:18 INFO - mozversion application_vendor: Mozilla 11:53:18 INFO - mozversion application_version: 50.0a1 11:53:18 INFO - mozversion platform_buildid: 20160705090825 11:53:18 INFO - mozversion platform_changeset: 8d66e60af350fd0255f798e608db1b6bfe61beda 11:53:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:53:18 INFO - mozversion platform_version: 50.0a1 11:53:18 INFO - using testdate: 1467744798 11:53:18 INFO - actual date: 1467744798 11:53:18 INFO - starting webserver on 'localhost:49282' 11:53:18 INFO - SUITE-START | Running 2 tests 11:53:18 INFO - TEST-START | dromaeo_css 11:53:18 INFO - operating with platform_type : mac_ 11:53:18 INFO - Initialising browser for dromaeo_css test... 11:53:18 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpCFkYX0/profile http://localhost:49282/getInfo.html) 11:53:21 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 11:53:21 INFO - PROCESS | 1666 | colorDepth:24 11:53:21 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 11:53:21 INFO - PROCESS | 1666 | __metrics 11:53:21 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:53:21 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:53:21 INFO - TEST-INFO | 1666: exit 0 11:53:21 INFO - Browser initialized. 11:53:21 INFO - Running cycle 1/1 for dromaeo_css test... 11:53:21 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpCFkYX0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:53:33 INFO - PROCESS | 1668 | RSS: Main: 243830784 11:53:33 INFO - PROCESS | 1668 | 11:54:25 INFO - PROCESS | 1668 | 1467744865013 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:56:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49282/tests/dromaeo/cssquery-ext.html) 11:56:38 INFO - PROCESS | 1668 | RSS: Main: 415723520 11:56:38 INFO - PROCESS | 1668 | 11:59:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-ext.html (next: http://localhost:49282/tests/dromaeo/cssquery-jquery.html) 11:59:42 INFO - PROCESS | 1668 | RSS: Main: 383623168 11:59:42 INFO - PROCESS | 1668 | 12:02:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49282/tests/dromaeo/cssquery-mootools.html) 12:02:45 INFO - PROCESS | 1668 | RSS: Main: 372387840 12:02:45 INFO - PROCESS | 1668 | 12:05:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49282/tests/dromaeo/cssquery-prototype.html) 12:05:48 INFO - PROCESS | 1668 | RSS: Main: 374124544 12:05:48 INFO - PROCESS | 1668 | 12:08:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49282/tests/dromaeo/cssquery-yui.html) 12:08:53 INFO - PROCESS | 1668 | RSS: Main: 342097920 12:08:53 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-yui.html (next: http://localhost:49282/tests/dromaeo/cssquery-dojo.html) 12:11:56 INFO - PROCESS | 1668 | RSS: Main: 388251648 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | __start_tp_report 12:11:56 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 12:11:56 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 12:11:56 INFO - PROCESS | 1668 | |i|pagename|runs| 12:11:56 INFO - PROCESS | 1668 | |0;dojo.html;3368.1579605098723;3380.9830950845244;3384.6107697614766;3414.470757032661;3446.2590543033243;5726.68503232322;5741.799037033705;5788.652680839153;5870.354261031283;5924.140999555062;5798.9130163047585;5802.767889284434;5853.678047707374;5860.824175274738;5868.090445980869;5256.8160114396005;5297.920531192035;5320.574354051667;5348.010618035656;5395.379531353886;5314.7608357624;5318.707471089088;5325.920111198335;5329.680219186842;5335.67985920846;5297.629165958385;5305.761240744157;5319.707416092114;5330.573554115662;5362.973185134068;5416;5422.53908417785;5423.105187644034;5441.673499589998;5448.809291674812;5153.201253805629;5242.004019236333;5261.316028916254;5320.787168513255;5330.200469929541;5692.203091567184;5751.712414379264;5832.2418085649015;5856.121581762727;5861.062230043172;6321.209848768904;6360.459360954324;6363.649999250039;6367.554271201017;6375.521835862282;6213.068039794022;6233.968830155865;6253.312135665074;6298.968505157491;6314.463270622003;5945.10823376497;5954.166416701665;5965.821025369159;5980.5514586405325;5988.820335389945;6431;6431.903521447182;6451.064595633607;6454.515911306671;6456.515761317826;6109.356888764571;6154.784582539679;6229.127922090911;6247.875042499125;6278.466330361879;6261.060840873814;6301.117843501914;6305.211848518935;6309.432151106331;6310.337414571496;6253.030780229072;6321.273053598895;6340.904886426707;6350.4602108820345;6354.015127655221;6140.600860944024;6214.130021796952;6232.25212974446;6256.468200203034;6288.99376099831;4914.950850491521;4941.407031156285;4954.752262386866;5012.448630650625;5012.674176178537;3681.37416639172;4473.418455600751;4489.236829738952;4512.661550383668;4513.909721805546;5845.357010728816;5845.970770146122;5849.6782676952325;5853;5864.120381942743;6197.907031394533;6215.192025036717;6274.372562743781;6277.246730392382;6288.151099601587;5697.173909783059;5777.45114214149;5815;5818.0981947798155;5820.70896455184;5488.725563721798;5500.614956953055;5508.944910550923;5527.419620939743;5542.63972841764;6392.808215753535;6407.679616019181;6415.903761443582;6428.582142160838;6435.903461447989;6222.502199824094;6271.78048768291;6295.307516173126;6302.3067462578165;6303.527235457267;6343.270523889812;6375.74497020114;6386.233651961795;6474.482041436768;6487.026945958144;6249.406306400884;6296.748130074929;6312.652804095819;6316.842078948063;6317.115603815378;6148.077788331786;6226.12834203221;6239.282482514569;6282.057691346152;6289.559730818799;6185.165002724754;6266.717997690023;6282.905756413749;6285.43431091204;6302.149209856794;6256.405641464054;6285.340039295991;6290.21372328459;6293.150424692791;6298.874022519615;5957.255343082114;5989.8203053908455;6147.692615369124;6150.261968563801;6164.722587483664;6173.567850250619;6190.1952746142715;6213.65824879636;6221.657808820558;6223;6048.879022419615;6105.186826067751;6134.04922237054;6137.7851775187655;6149.923763341487 12:11:56 INFO - PROCESS | 1668 | |1;ext.html;439.7010033177439;475.9457800970851;486.5791090706539;538.9595780316478;546.6938514431919;30771.69228307719;31062.37875242494;32404.67595324046;32516.512252316206;32527;33039.83480082602;44110.55889441105;44831;44880;47677.7616111919;9855.310128291023;9993.350432221918;10002.899971000306;10015.44915029673;10026.248031397638;8861.335399845006;9830.508474576278;9968.75078123049;9972.6509572165;9976.700698979006;12036.157468977175;12257.14200006;12323.445444954954;12502.49990000399;12521.436535355931;12227;12306;12311.69220769488;12319.322437265946;12331.815022774666;8815.603297851612;8966.372353935227;8967.148120928501;12156.149069565134;12259;32895.83552082224;41325.173496529904;41645.583544164474;41664;43587.34618980718;3492.3015396920464;3503.4744788281705;3528.523649307336;3675.7978311192874;3891.9610803891883;32435.51346729801;33238.50142247868;33592.99221023373;33702.6629733702;33727.98816035498;3371.679690429405;3379.6958273755235;3422.144463884029;3436.570428696413;3473.426884564044;546108;547699;551861;551915;552161.2391938014;55215.447845521034;66212.66893665501;66676;66956.6652166736;67015;68145.31854681489;68860.6556967212;69122.3087769126;72061.91907121397;72171.63914180396;16533.50399488017;16720;16727.665446690997;16730.16349182536;16741.246643901053;12709.555165569162;12734.299613521345;12836.550720724917;12843.972482201401;12873.935630321788;17286.308547657954;17510;19924.90037549832;20002;20152.596948060956;6986.580805151707;8009.71844504888;8031.55826429552;8039.638216280281;8049.758507244792;957.6791774755535;971.9260217459747;992.6823416506793;1067.9839802402971;1134.659602119361;30873.691263087083;31175.688243117278;31293.8435307822;31305.747770089398;31834.840825795724;1116.564539829467;1131.5191043806349;1153.3080151908787;1161.7850697620968;1201.408134222159;15941.681166376608;15974.520764377086;16021.359145634044;16102.919485402732;16601.667966640598;706894.4655276793;710217;715063;718896;718973.405132971;61720;62314;62456.68771656203;62510.374896251364;62695.37304626896;1246.9251844888972;1282.9807552886714;1375.3535838156051;1438.5756201920458;1463.0490181382186;31855;32013;32116.03651890447;32859.83570082134;32865.50701739428;60045.69977150086;60810.39189608047;60828;64340.67829660822;64344;651563;668029;669112;669654;670349;37278.627213727515;44663.77668111639;45403.77298113488;45495.77252113718;45543;48124;49203.753981229864;49523;50247.748761255956;50406.991860163325;40827.59172408238;40887.56893508713;41281;41319.58680413158;42347.729568113005;40218.59781402149;40549.594504054585;41038.1792364157;41805;41946.790266048476;38812;39201;39460.01349966159;39787;40099.198016038936;34360;35426.82286588551;35462.0354689888;36021.81989090038;36157.638423615426;23216.187433439747;26077.348066297734;26257.27633847184;26335.20994370172;26753.33116672099 12:11:56 INFO - PROCESS | 1668 | |2;jquery.html;344.7293874308667;396.55981860135245;399.15777709033915;409.05712333072256;418.36199795312143;18264.726029109574;22081.006354714038;22604;22820.65769013466;23125.76874231257;21371.252006179788;21436.356909292706;22020;22194;22264.109435622555;5758.3090029196555;6091.360407157251;6102.450779429851;6104.08438734189;6104.572679912408;4191.2246234446575;4341.6092551670345;4357.281048626973;4421.889452763675;5895.852603684921;5801.796937107202;6780.1524809398825;6791.3548212919695;6806.965965170167;6809.931900680979;6404.071409645599;6550.901736473957;6601.339866013408;6727.831804204885;6772.458203343721;3708.701954315995;4493.68544201906;5756.8848623027725;5765.4522820332;5772.509336706385;27804.58293125605;27811.721882781116;27918.302042448897;28073.29816754577;28240.152795416172;2267.07050109456;2395.652630368587;2482.3170943867003;2829.278533973844;3234;9814.509274536245;10577.471126443725;15294.847051529454;16343.591410214722;17071.65856682872;1556.813182418117;1832.743415921772;1854.5085552328644;1880.0522678378977;1945.902704864751;77584;98768;122210.38894805558;122517;122951.38524307321;11545.365004924643;11799.997000255074;11812.645620631223;11833.526658933546;11925.940370298093;14789.260536973108;14880.553583392515;14895.329710163069;14897.702045959237;14948.40206391754;8777.648894044294;8779;8819.823603527893;8872.73381798547;8946.239569636653;8895.73312800617;8944.19502244788;8961.417507861968;9018.729438116867;9038.638454461749;4617.515160908123;4617.561331673485;4619.815207391734;4621.19129152397;4680.08738296029;1773.7162054071287;1774.066306209235;1897;1942.339604534465;1943.9611207776047;847.8685803700313;937.4703292639637;966.7438128895848;1009.6769033909226;1039.2725092435326;21750;22629.773702263097;22633;22652;22802.885985570298;904.9140331668481;923.8475651517559;924.3390975452584;945.1493655709779;955.2835373469898;8739.519326437106;9634.325597208268;11830.822537661941;11956.760864782656;11979;564170;570054.1497292487;574407;584037;584768;70167;115165.4241728786;133258.33370833084;139693;140831.29584352218;1011.8279892418307;1016.0753714120116;1056.1709058389129;1077.2028698762858;1080.9675709728563;16418.425355112515;19747.901260493607;20078.096485657887;20120.899395503515;20129.899350503154;57924.710376447845;58551.70724146352;58912.52718681896;58932.705336473045;59752;79628.83340499383;80987.19012810032;82023;83372.46830067664;90338.09661903296;7685.116211635734;8130.390220733587;8303.709370172015;8410.87383689233;8465.026521950054;9504.762380940532;13309.334533273492;13349.799753003907;13537.052406331855;13553.457861685816;11255.943720281346;11401.20191586581;11587.420628968686;11629.418529073682;11666.241694289844;9846.212303015885;11378;11563.884361156617;11625.709357266136;11917.22538035025;11722.70693232676;11750.882491174978;11824.64526064185;11923.880761192277;11944.16390852632;7150.749723759852;7459.962700186681;7485.1017877855;7733.7679869604;8537.146285371662;10517.10604598587;11482.655520334403;11507.942460287644;11578.76842463163;11871.406429678655 12:11:56 INFO - PROCESS | 1668 | |3;mootools.html;1574.8267690554042;1612.8387161283872;1693.5850716574441;1834.6239021000697;1960.0493760526151;8625.353098517613;8934.59794309256;9079.682211122612;9082.455052696834;9107.36248462608;7973.282404583586;8192.508449493034;8211.835763284746;8366.916330836704;8829.337799665018;2159.481724386148;2194.6049711052005;2322.9651555226687;2402.2913240594066;2404.75952404759;2029;2390.103711108334;2397.844140130886;2401.135591187171;2405.398650337416;2167.934961951144;2194.7695491973273;2387.140629373433;2388.8805559721945;2390.2829151254555;2099.496120930981;2178.1614078579782;2178.2920550821045;2181.2583721534597;2181.694562761215;2308.9422764430856;2394.5091256292594;2398.07674045492;2409.8795060246916;2410.987945060281;8982.505962172077;9042.86435703465;9047.638094476213;9057.00372958974;9080.137386948229;5300.257963885059;5479.369872464677;5497.092979658351;5499.450054994469;5529.529989950859;3559.04252661038;3641.2171383152486;3659.323025240339;3664.9816750916343;3679.2641471705497;1024.6106479537802;1089.3605124287976;1115.81031224692;1115.9888401115966;1119.261287550213;12094.879051209464;12118.697032574168;12140.575079872162;12158.817617735742;12227.877721222852;4666.626669866402;4705.317728929286;4887.437944636341;4889.608831293488;4902.166631672553;10012.39925604466;10124.291299609104;10125.291229614004;10144.695659130239;10148.238882083726;5435.266239057677;5441.945580544144;5454.645448045864;5456.781728730885;5460.153676180198;4294.63495602871;4993.80024799004;4997;5003.49965003504;5041.344625198701;3042.6957304269395;3676.209614932749;3770.4155855842387;3895.6883449323986;3902.375619900802;1170.5317872850928;1178.1458442629196;1178.9587364442202;1189.74420499593;1189.7501524679915;852.8720691896141;948.9098535639104;953.3945944325403;973.4862289140314;1016.6187679620142;6008.729607167688;6047.909281360783;6137.631742095488;6144;6147.508199344042;954.160338901762;976.4531862157214;985.7584891701579;988.4069558264983;990.5839547390113;4029.3955906613655;4056.492938382702;4066.512018557792;4151.605597468236;4161.209370219649;9479.383840050376;10424.32241904289;10882.455877206266;10932.016118549209;10957.890421095846;10894.618688346029;10898.073663738513;10898.07366373867;10910.890891091147;10928;6623.30455302196;7108.573485590881;8064.314533264738;8270.917290827014;8277.917220827836;6099.84750381244;6150.323464419;6254.593451425642;6287.497000239879;6309.589876658002;9878.30851840393;10235.641752538626;10365.01532354425;10381.221408394551;10395.58417663285;10170.74573135648;10533.578656853946;10545.894541054491;10550.788984220426;10589.735256618646;7770.417218708732;7808.648610812414;7843.215678432111;7903.723369682263;7913.9604301978125;8441.746747597583;8497.022842373206;8535.10381409943;8535.189157030072;8536.061033286209;7552.584607846621;7667.578283194255;7691.038620172479;7831.608419579112;7847.019122609674;6571.835704107436;7298.525595836254;7453.2546745325035;7686.269804368576;7761.61191940412;7121.857562848818;7177.354038136593;7187.31720486553;7190.352868241884;7194.820129496805;4999.700017998786;5046.1926091825135;5087.847364579068;5111.514406131411;5228.503292187236;5078.238264260391;5110.61670374716;5137.332146821039;5168.224766285088;5320.989012087691 12:11:56 INFO - PROCESS | 1668 | |4;prototype.html;308.134143058007;311.9656837747848;320.84278703435325;321.673501396083;325.2551656706143;4746.549077837604;5322.04203243416;5446.319210098738;5495.532879705225;5534.833954981352;4424.867253982377;4619.643981569398;5011.674241174321;5345.412004679482;5349.7057661828585;3292.917677058069;4039.899002524946;4077.10303733179;4149.46057012588;4183.874483765492;3483.4600636901323;4169.999200191947;4183.247015537202;4190.53904070552;4228.5982831630945;3375.0887260439667;4048.9392659110135;4140.50313962325;4184;4206.852760153395;3957.663598594123;4001.979990100031;4186.1418409226035;4192.832286708528;4209.179210054041;3641.198936234024;3643.981780091083;3644.835982380799;3645.143391303042;3657.305112028706;4377.233984052778;4845.127876982142;5325.653832500875;5326.92009619856;5329.44186790339;4566.429196350457;4743.691660042086;4752.908720163117;4756.762161891892;4784.545468180518;5031.911756620998;5442.394493624391;5648.446677163813;5735.913961290584;5786.942130578683;3965.801709914522;3977.3636218204947;3979.7015223858325;3980.4029395590605;4034.1729945361426;5191.896162076738;5454.236406902997;5751.281089863725;6062.757489700451;6091.269047714302;3116.114750575869;3512.4028915083986;3876.1666241757594;4022.255882661717;4049.7570145791346;3504.842282097312;3637.781733096023;4138.52406973196;4162.376673097494;4165.75005499671;4054.2499637567144;4611.100835337077;4740.407449068866;4777.976110119427;4814.398200224972;5092.694438333712;5392.272043274186;5730.570207234474;5751.613080571599;5768.798092066758;1333.5532596580254;1353.3301015997145;1394.4352537222442;1405.7469655462114;1424.893133015028;896.9551522423852;920.1964150265421;925.319889880937;925.3984909808544;928.9071092890657;4521.615662668644;5117;5387.757550910218;5427.9874669557985;5474.014677358114;5673.404292549306;5873;5887.558433117533;5916.378780228014;6050.183225264621;4638.373819534319;5088;5488.1767734839295;5494.587905907073;5550.361708403505;2574.7985472421183;2795.3228063162346;2848.8604558176894;3118.7097612019083;3168.603924509436;5200.791968321225;5419.403865574784;5801.245838041112;6176.814695559051;6294.748210071637;5251.6061295403;6203.193584833943;6235.906461403082;6240.251169859646;6258.280297765724;5706.830099829525;6013.639181649115;6262.561620686508;6279.748810047547;6381.553291269659;5344.946550534445;5538.47384498472;5977.252843394575;5988.101784732325;6003.489703375262;5868.23712917318;5894.38108998549;5904.704764761659;5926.355291495785;5938.970305148619;5764.827055188351;6207.920346683912;6211.04352129096;6221;6280.9685951569945;3769.1896242308035;4080.673546116363;4109.321961876377;4109.712320137562;4141.689373297075;5143.819966301162;5984.790532331347;6001.6990655138625;6005.969970150121;6006.759729610942;4751.384529260068;5459.617826752089;5595.63628364155;5742.397048309867;5753.597248192586;5685.943140568541;5697.772089116389;5714.342850572238;5732.742026608896;5765.194971319077;4831.782569784438;5331.920021199763;5456;5550.47270509301;5649.3220813501;5351.828549743363;5452.2379558104785;5502.752376143003;5757.971210143923;5784.971075144597;4350.412694286231;4638.002829391697;4947.431045429822;5613;5628 12:11:56 INFO - PROCESS | 1668 | |5;yui.html;757.5416872791957;757.5909009135066;784.0317208247812;805.3235282362814;883.2271762208068;13492.73014539711;14960.326785294661;16010.359585616576;16027.599310017255;16093.356265749357;13437.865621343808;14601.926990365033;14715.632109197275;14747.115173089594;14774.704505909875;707.9858402831941;723.8986541884141;888.866669999502;900.3787386703154;905.0858632780872;891.4873947480193;910.544727636182;912.7854954085784;921.4425272710015;925.5418567808944;950.4582388038787;966.5698764050001;967.9661211857587;968.2883080935507;970.257752819094;750.8385697075128;943.056943056943;966.0436168193473;972.601233494271;980.764616492044;715.0525553641446;717.1788302393782;717.3615482220775;718.1561665043553;720.0315575550876;15036;15066.773998390034;15071.54785356441;15077.849221507755;15081.849181508263;14330.211838348887;14337.283135843167;14369.497067602584;14454.566363009126;14514.637134071627;13173.934130329288;13205.537806176833;13256.20462772227;13322.134061285986;13506.729865402735;4606.239970404879;4625.953740462586;4667.696599721007;4838.903221935541;4848.975755121237;111934.99999999919;114183;115192;115266;115355.84644153535;11489.195756297146;11612.941935290439;11660.18378713482;11732.413379331;11865;22973.081076757077;23004.19485318006;23015.079396824276;23047.423814404774;23053.077876884738;7705.807354816175;8023.318017968421;8038.035435747631;8149.9592502038295;8217.506949583045;3878.650921417086;4553.772311384484;4553.908921821545;4571.1543364477675;4697.929531057037;3594;3710.128119891823;3723.5717892442176;3727.0309719473134;3743.3262012837404;600.4205941266612;613.487737738984;614.5421660862686;626.6803929995643;627.6892937995722;530.7268091162423;558.6759679386028;639.8496353356958;651.3746803069107;692.4633409107881;9230.815383692288;9321.72034838956;9652.27607929408;9684.612615495442;9909.504524773733;612.9478994285447;637.1112298343877;643.7360682120308;693.1647364925253;717.1072015340934;4454.732716037048;4730.526947305242;4735.502772208937;4767.713937163781;4780.1873681474235;149774.25112874366;150626.24686876495;150874.24562877114;152539.47460525474;154888.22555887373;89963;112838.43580782044;114155;114652.28021579683;115588;108087;116221.41889290669;116590.83409165799;118451;120699;8996.55017249122;9017.504037278013;9040.773980650274;9045.864312035194;9179.586918588813;42511.787441062595;43978;61923.69038154781;62503.74992500216;63225;93267.53366233125;97008;97107;97880.51059744656;98332;12047.698807529881;12121.69695757613;12129.575464858688;12145.111191475084;12383.19509231897;11572.826407603725;13196.406161722554;13449.86550134486;13681.247531385869;13714.177149370671;12461.439235234617;12557.93721031389;12767.106302558732;13015.739685206432;13045.608631741063;12043.939780301042;12263.448144833326;12511.186772859735;12808.743825123633;12848.486060557472;11982.041436685218;12128.514859405877;12386.690332741753;12689.8096528554;12798.488060477848;9683.176929960751;10531.89468105309;10635.113833629344;10745.892541074489;11232.66302010941;8891.863944490718;9447.716568502956;10524.052835244865;10582.470876455993;10653.89346106529 12:11:56 INFO - PROCESS | 1668 | __end_tp_report 12:11:56 INFO - PROCESS | 1668 | __start_cc_report 12:11:56 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1646 12:11:56 INFO - PROCESS | 1668 | __end_cc_report 12:11:56 INFO - PROCESS | 1668 | __startTimestamp1467745916511__endTimestamp 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | ------- Summary: start ------- 12:11:56 INFO - PROCESS | 1668 | Number of tests: 6 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5845.17 Median:6140.60 stddev:656.82 (10.7%) stddev-sans-first:629.44 12:11:56 INFO - PROCESS | 1668 | Values: 3368.2 3381.0 3384.6 3414.5 3446.3 5726.7 5741.8 5788.7 5870.4 5924.1 5798.9 5802.8 5853.7 5860.8 5868.1 5256.8 5297.9 5320.6 5348.0 5395.4 5314.8 5318.7 5325.9 5329.7 5335.7 5297.6 5305.8 5319.7 5330.6 5363.0 5416.0 5422.5 5423.1 5441.7 5448.8 5153.2 5242.0 5261.3 5320.8 5330.2 5692.2 5751.7 5832.2 5856.1 5861.1 6321.2 6360.5 6363.6 6367.6 6375.5 6213.1 6234.0 6253.3 6299.0 6314.5 5945.1 5954.2 5965.8 5980.6 5988.8 6431.0 6431.9 6451.1 6454.5 6456.5 6109.4 6154.8 6229.1 6247.9 6278.5 6261.1 6301.1 6305.2 6309.4 6310.3 6253.0 6321.3 6340.9 6350.5 6354.0 6140.6 6214.1 6232.3 6256.5 6289.0 4915.0 4941.4 4954.8 5012.4 5012.7 3681.4 4473.4 4489.2 4512.7 4513.9 5845.4 5846.0 5849.7 5853.0 5864.1 6197.9 6215.2 6274.4 6277.2 6288.2 5697.2 5777.5 5815.0 5818.1 5820.7 5488.7 5500.6 5508.9 5527.4 5542.6 6392.8 6407.7 6415.9 6428.6 6435.9 6222.5 6271.8 6295.3 6302.3 6303.5 6343.3 6375.7 6386.2 6474.5 6487.0 6249.4 6296.7 6312.7 6316.8 6317.1 6148.1 6226.1 6239.3 6282.1 6289.6 6185.2 6266.7 6282.9 6285.4 6302.1 6256.4 6285.3 6290.2 6293.2 6298.9 5957.3 5989.8 6147.7 6150.3 6164.7 6173.6 6190.2 6213.7 6221.7 6223.0 6048.9 6105.2 6134.0 6137.8 6149.9 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:78192.32 Median:21546.48 stddev:173118.52 (803.5%) stddev-sans-first:173505.73 12:11:56 INFO - PROCESS | 1668 | Values: 439.7 475.9 486.6 539.0 546.7 30771.7 31062.4 32404.7 32516.5 32527.0 33039.8 44110.6 44831.0 44880.0 47677.8 9855.3 9993.4 10002.9 10015.4 10026.2 8861.3 9830.5 9968.8 9972.7 9976.7 12036.2 12257.1 12323.4 12502.5 12521.4 12227.0 12306.0 12311.7 12319.3 12331.8 8815.6 8966.4 8967.1 12156.1 12259.0 32895.8 41325.2 41645.6 41664.0 43587.3 3492.3 3503.5 3528.5 3675.8 3892.0 32435.5 33238.5 33593.0 33702.7 33728.0 3371.7 3379.7 3422.1 3436.6 3473.4 546108.0 547699.0 551861.0 551915.0 552161.2 55215.4 66212.7 66676.0 66956.7 67015.0 68145.3 68860.7 69122.3 72061.9 72171.6 16533.5 16720.0 16727.7 16730.2 16741.2 12709.6 12734.3 12836.6 12844.0 12873.9 17286.3 17510.0 19924.9 20002.0 20152.6 6986.6 8009.7 8031.6 8039.6 8049.8 957.7 971.9 992.7 1068.0 1134.7 30873.7 31175.7 31293.8 31305.7 31834.8 1116.6 1131.5 1153.3 1161.8 1201.4 15941.7 15974.5 16021.4 16102.9 16601.7 706894.5 710217.0 715063.0 718896.0 718973.4 61720.0 62314.0 62456.7 62510.4 62695.4 1246.9 1283.0 1375.4 1438.6 1463.0 31855.0 32013.0 32116.0 32859.8 32865.5 60045.7 60810.4 60828.0 64340.7 64344.0 651563.0 668029.0 669112.0 669654.0 670349.0 37278.6 44663.8 45403.8 45495.8 45543.0 48124.0 49203.8 49523.0 50247.7 50407.0 40827.6 40887.6 41281.0 41319.6 42347.7 40218.6 40549.6 41038.2 41805.0 41946.8 38812.0 39201.0 39460.0 39787.0 40099.2 34360.0 35426.8 35462.0 36021.8 36157.6 23216.2 26077.3 26257.3 26335.2 26753.3 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34625.43 Median:28156.73 stddev:96039.97 (341.1%) stddev-sans-first:96274.90 12:11:56 INFO - PROCESS | 1668 | Values: 344.7 396.6 399.2 409.1 418.4 18264.7 22081.0 22604.0 22820.7 23125.8 21371.3 21436.4 22020.0 22194.0 22264.1 5758.3 6091.4 6102.5 6104.1 6104.6 4191.2 4341.6 4357.3 4421.9 5895.9 5801.8 6780.2 6791.4 6807.0 6809.9 6404.1 6550.9 6601.3 6727.8 6772.5 3708.7 4493.7 5756.9 5765.5 5772.5 27804.6 27811.7 27918.3 28073.3 28240.2 2267.1 2395.7 2482.3 2829.3 3234.0 9814.5 10577.5 15294.8 16343.6 17071.7 1556.8 1832.7 1854.5 1880.1 1945.9 77584.0 98768.0 122210.4 122517.0 122951.4 11545.4 11800.0 11812.6 11833.5 11925.9 14789.3 14880.6 14895.3 14897.7 14948.4 8777.6 8779.0 8819.8 8872.7 8946.2 8895.7 8944.2 8961.4 9018.7 9038.6 4617.5 4617.6 4619.8 4621.2 4680.1 1773.7 1774.1 1897.0 1942.3 1944.0 847.9 937.5 966.7 1009.7 1039.3 21750.0 22629.8 22633.0 22652.0 22802.9 904.9 923.8 924.3 945.1 955.3 8739.5 9634.3 11830.8 11956.8 11979.0 564170.0 570054.1 574407.0 584037.0 584768.0 70167.0 115165.4 133258.3 139693.0 140831.3 1011.8 1016.1 1056.2 1077.2 1081.0 16418.4 19747.9 20078.1 20120.9 20129.9 57924.7 58551.7 58912.5 58932.7 59752.0 79628.8 80987.2 82023.0 83372.5 90338.1 7685.1 8130.4 8303.7 8410.9 8465.0 9504.8 13309.3 13349.8 13537.1 13553.5 11255.9 11401.2 11587.4 11629.4 11666.2 9846.2 11378.0 11563.9 11625.7 11917.2 11722.7 11750.9 11824.6 11923.9 11944.2 7150.7 7460.0 7485.1 7733.8 8537.1 10517.1 11482.7 11507.9 11578.8 11871.4 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5805.34 Median:4995.40 stddev:3275.31 (65.6%) stddev-sans-first:3269.07 12:11:56 INFO - PROCESS | 1668 | Values: 1574.8 1612.8 1693.6 1834.6 1960.0 8625.4 8934.6 9079.7 9082.5 9107.4 7973.3 8192.5 8211.8 8366.9 8829.3 2159.5 2194.6 2323.0 2402.3 2404.8 2029.0 2390.1 2397.8 2401.1 2405.4 2167.9 2194.8 2387.1 2388.9 2390.3 2099.5 2178.2 2178.3 2181.3 2181.7 2308.9 2394.5 2398.1 2409.9 2411.0 8982.5 9042.9 9047.6 9057.0 9080.1 5300.3 5479.4 5497.1 5499.5 5529.5 3559.0 3641.2 3659.3 3665.0 3679.3 1024.6 1089.4 1115.8 1116.0 1119.3 12094.9 12118.7 12140.6 12158.8 12227.9 4666.6 4705.3 4887.4 4889.6 4902.2 10012.4 10124.3 10125.3 10144.7 10148.2 5435.3 5441.9 5454.6 5456.8 5460.2 4294.6 4993.8 4997.0 5003.5 5041.3 3042.7 3676.2 3770.4 3895.7 3902.4 1170.5 1178.1 1179.0 1189.7 1189.8 852.9 948.9 953.4 973.5 1016.6 6008.7 6047.9 6137.6 6144.0 6147.5 954.2 976.5 985.8 988.4 990.6 4029.4 4056.5 4066.5 4151.6 4161.2 9479.4 10424.3 10882.5 10932.0 10957.9 10894.6 10898.1 10898.1 10910.9 10928.0 6623.3 7108.6 8064.3 8270.9 8277.9 6099.8 6150.3 6254.6 6287.5 6309.6 9878.3 10235.6 10365.0 10381.2 10395.6 10170.7 10533.6 10545.9 10550.8 10589.7 7770.4 7808.6 7843.2 7903.7 7914.0 8441.7 8497.0 8535.1 8535.2 8536.1 7552.6 7667.6 7691.0 7831.6 7847.0 6571.8 7298.5 7453.3 7686.3 7761.6 7121.9 7177.4 7187.3 7190.4 7194.8 4999.7 5046.2 5087.8 5111.5 5228.5 5078.2 5110.6 5137.3 5168.2 5321.0 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4649.90 Median:5286.82 stddev:1443.47 (27.3%) stddev-sans-first:1410.25 12:11:56 INFO - PROCESS | 1668 | Values: 308.1 312.0 320.8 321.7 325.3 4746.5 5322.0 5446.3 5495.5 5534.8 4424.9 4619.6 5011.7 5345.4 5349.7 3292.9 4039.9 4077.1 4149.5 4183.9 3483.5 4170.0 4183.2 4190.5 4228.6 3375.1 4048.9 4140.5 4184.0 4206.9 3957.7 4002.0 4186.1 4192.8 4209.2 3641.2 3644.0 3644.8 3645.1 3657.3 4377.2 4845.1 5325.7 5326.9 5329.4 4566.4 4743.7 4752.9 4756.8 4784.5 5031.9 5442.4 5648.4 5735.9 5786.9 3965.8 3977.4 3979.7 3980.4 4034.2 5191.9 5454.2 5751.3 6062.8 6091.3 3116.1 3512.4 3876.2 4022.3 4049.8 3504.8 3637.8 4138.5 4162.4 4165.8 4054.2 4611.1 4740.4 4778.0 4814.4 5092.7 5392.3 5730.6 5751.6 5768.8 1333.6 1353.3 1394.4 1405.7 1424.9 897.0 920.2 925.3 925.4 928.9 4521.6 5117.0 5387.8 5428.0 5474.0 5673.4 5873.0 5887.6 5916.4 6050.2 4638.4 5088.0 5488.2 5494.6 5550.4 2574.8 2795.3 2848.9 3118.7 3168.6 5200.8 5419.4 5801.2 6176.8 6294.7 5251.6 6203.2 6235.9 6240.3 6258.3 5706.8 6013.6 6262.6 6279.7 6381.6 5344.9 5538.5 5977.3 5988.1 6003.5 5868.2 5894.4 5904.7 5926.4 5939.0 5764.8 6207.9 6211.0 6221.0 6281.0 3769.2 4080.7 4109.3 4109.7 4141.7 5143.8 5984.8 6001.7 6006.0 6006.8 4751.4 5459.6 5595.6 5742.4 5753.6 5685.9 5697.8 5714.3 5732.7 5765.2 4831.8 5331.9 5456.0 5550.5 5649.3 5351.8 5452.2 5502.8 5758.0 5785.0 4350.4 4638.0 4947.4 5613.0 5628.0 12:11:56 INFO - PROCESS | 1668 | 12:11:56 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:24586.67 Median:3810.99 stddev:39340.04 (1032.3%) stddev-sans-first:39409.71 12:11:56 INFO - PROCESS | 1668 | Values: 757.5 757.6 784.0 805.3 883.2 13492.7 14960.3 16010.4 16027.6 16093.4 13437.9 14601.9 14715.6 14747.1 14774.7 708.0 723.9 888.9 900.4 905.1 891.5 910.5 912.8 921.4 925.5 950.5 966.6 968.0 968.3 970.3 750.8 943.1 966.0 972.6 980.8 715.1 717.2 717.4 718.2 720.0 15036.0 15066.8 15071.5 15077.8 15081.8 14330.2 14337.3 14369.5 14454.6 14514.6 13173.9 13205.5 13256.2 13322.1 13506.7 4606.2 4626.0 4667.7 4838.9 4849.0 111935.0 114183.0 115192.0 115266.0 115355.8 11489.2 11612.9 11660.2 11732.4 11865.0 22973.1 23004.2 23015.1 23047.4 23053.1 7705.8 8023.3 8038.0 8150.0 8217.5 3878.7 4553.8 4553.9 4571.2 4697.9 3594.0 3710.1 3723.6 3727.0 3743.3 600.4 613.5 614.5 626.7 627.7 530.7 558.7 639.8 651.4 692.5 9230.8 9321.7 9652.3 9684.6 9909.5 612.9 637.1 643.7 693.2 717.1 4454.7 4730.5 4735.5 4767.7 4780.2 149774.3 150626.2 150874.2 152539.5 154888.2 89963.0 112838.4 114155.0 114652.3 115588.0 108087.0 116221.4 116590.8 118451.0 120699.0 8996.6 9017.5 9040.8 9045.9 9179.6 42511.8 43978.0 61923.7 62503.7 63225.0 93267.5 97008.0 97107.0 97880.5 98332.0 12047.7 12121.7 12129.6 12145.1 12383.2 11572.8 13196.4 13449.9 13681.2 13714.2 12461.4 12557.9 12767.1 13015.7 13045.6 12043.9 12263.4 12511.2 12808.7 12848.5 11982.0 12128.5 12386.7 12689.8 12798.5 9683.2 10531.9 10635.1 10745.9 11232.7 8891.9 9447.7 10524.1 10582.5 10653.9 12:11:56 INFO - PROCESS | 1668 | -------- Summary: end -------- 12:11:56 INFO - PROCESS | 1668 | 12:11:57 INFO - TEST-INFO | 1668: exit 0 12:11:57 INFO - TEST-OK | dromaeo_css | took 1118394ms 12:11:57 INFO - TEST-START | kraken 12:11:57 INFO - operating with platform_type : mac_ 12:11:57 INFO - Initialising browser for kraken test... 12:11:57 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpZLSfqN/profile http://localhost:49282/getInfo.html) 12:11:59 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 12:11:59 INFO - PROCESS | 1678 | colorDepth:24 12:11:59 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 12:11:59 INFO - PROCESS | 1678 | __metrics 12:11:59 INFO - PROCESS | 1678 | 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" 12:11:59 INFO - PROCESS | 1678 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:00 INFO - TEST-INFO | 1678: exit 0 12:12:00 INFO - Browser initialized. 12:12:00 INFO - Running cycle 1/1 for kraken test... 12:12:00 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpZLSfqN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:12 INFO - PROCESS | 1679 | RSS: Main: 244748288 12:12:12 INFO - PROCESS | 1679 | 12:12:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection) 12:12:15 INFO - PROCESS | 1679 | RSS: Main: 294113280 12:12:15 INFO - PROCESS | 1679 | 12:12:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-dft) 12:12:18 INFO - PROCESS | 1679 | RSS: Main: 304832512 12:12:18 INFO - PROCESS | 1679 | 12:12:22 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-fft) 12:12:23 INFO - PROCESS | 1679 | RSS: Main: 388009984 12:12:23 INFO - PROCESS | 1679 | 12:12:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator) 12:12:25 INFO - PROCESS | 1679 | RSS: Main: 291151872 12:12:25 INFO - PROCESS | 1679 | 12:12:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:12:28 INFO - PROCESS | 1679 | RSS: Main: 295067648 12:12:28 INFO - PROCESS | 1679 | 12:12:31 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom) 12:12:31 INFO - PROCESS | 1679 | RSS: Main: 333688832 12:12:31 INFO - PROCESS | 1679 | 12:12:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate) 12:12:35 INFO - PROCESS | 1679 | RSS: Main: 334413824 12:12:35 INFO - PROCESS | 1679 | 12:12:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial) 12:12:39 INFO - PROCESS | 1679 | RSS: Main: 332636160 12:12:39 INFO - PROCESS | 1679 | 12:12:40 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:12:41 INFO - PROCESS | 1679 | RSS: Main: 311803904 12:12:41 INFO - PROCESS | 1679 | 12:12:43 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:12:43 INFO - PROCESS | 1679 | RSS: Main: 319389696 12:12:43 INFO - PROCESS | 1679 | 12:12:45 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:12:46 INFO - PROCESS | 1679 | RSS: Main: 317116416 12:12:46 INFO - PROCESS | 1679 | 12:12:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:12:50 INFO - PROCESS | 1679 | RSS: Main: 324849664 12:12:50 INFO - PROCESS | 1679 | 12:12:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:12:53 INFO - PROCESS | 1679 | RSS: Main: 327589888 12:12:53 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49282/tests/kraken/driver.html?testName=ai-astar) 12:12:55 INFO - PROCESS | 1679 | RSS: Main: 371257344 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | __start_tp_report 12:12:55 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 12:12:55 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 12:12:55 INFO - PROCESS | 1679 | |i|pagename|runs| 12:12:55 INFO - PROCESS | 1679 | |0;ai-astar;100;112;111;113;113;111;112;112;112;110 12:12:55 INFO - PROCESS | 1679 | |1;audio-beat-detection;139;108;105;157;107;107;125;105;158;134 12:12:55 INFO - PROCESS | 1679 | |2;audio-dft;177;185;188;185;197;262;180;184;182;181 12:12:55 INFO - PROCESS | 1679 | |3;audio-fft;77;73;58;89;73;81;82;77;82;57 12:12:55 INFO - PROCESS | 1679 | |4;audio-oscillator;85;92;94;71;71;66;96;70;67;102 12:12:55 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;89;86;90;88;88;86;87;88;89;86 12:12:55 INFO - PROCESS | 1679 | |6;imaging-darkroom;110;146;87;128;145;94;94;95;94;94 12:12:55 INFO - PROCESS | 1679 | |7;imaging-desaturate;84;89;130;86;84;85;69;84;109;125 12:12:55 INFO - PROCESS | 1679 | |8;json-parse-financial;54;53;53;55;53;53;55;55;53;55 12:12:55 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;64;72;79;76;71;71;69;70;70;70 12:12:55 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;83;80;84;84;85;81;82;86;82;74 12:12:55 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;134;139;136;153;154;147;140;133;222;136 12:12:55 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;160;161;162;162;162;165;164;164;162;162 12:12:55 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;67;67;66;66;65;66;65;66;65 12:12:55 INFO - PROCESS | 1679 | __end_tp_report 12:12:55 INFO - PROCESS | 1679 | __start_cc_report 12:12:55 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1992 12:12:55 INFO - PROCESS | 1679 | __end_cc_report 12:12:55 INFO - PROCESS | 1679 | __startTimestamp1467745975491__endTimestamp 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | ------- Summary: start ------- 12:12:55 INFO - PROCESS | 1679 | Number of tests: 14 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.84 (3.4%) stddev-sans-first:0.97 12:12:55 INFO - PROCESS | 1679 | Values: 100.0 112.0 111.0 113.0 113.0 111.0 112.0 112.0 112.0 110.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:129.50 stddev:21.41 (16.5%) stddev-sans-first:22.05 12:12:55 INFO - PROCESS | 1679 | Values: 139.0 108.0 105.0 157.0 107.0 107.0 125.0 105.0 158.0 134.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:192.10 Median:185.00 stddev:25.15 (13.6%) stddev-sans-first:26.08 12:12:55 INFO - PROCESS | 1679 | Values: 177.0 185.0 188.0 185.0 197.0 262.0 180.0 184.0 182.0 181.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:74.90 Median:79.00 stddev:10.32 (13.1%) stddev-sans-first:10.92 12:12:55 INFO - PROCESS | 1679 | Values: 77.0 73.0 58.0 89.0 73.0 81.0 82.0 77.0 82.0 57.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:78.00 stddev:13.79 (17.7%) stddev-sans-first:14.57 12:12:55 INFO - PROCESS | 1679 | Values: 85.0 92.0 94.0 71.0 71.0 66.0 96.0 70.0 67.0 102.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.00 stddev:1.42 (1.6%) stddev-sans-first:1.42 12:12:55 INFO - PROCESS | 1679 | Values: 89.0 86.0 90.0 88.0 88.0 86.0 87.0 88.0 89.0 86.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:94.00 stddev:22.62 (24.1%) stddev-sans-first:23.99 12:12:55 INFO - PROCESS | 1679 | Values: 110.0 146.0 87.0 128.0 145.0 94.0 94.0 95.0 94.0 94.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:84.00 stddev:19.93 (23.7%) stddev-sans-first:20.77 12:12:55 INFO - PROCESS | 1679 | Values: 84.0 89.0 130.0 86.0 84.0 85.0 69.0 84.0 109.0 125.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.50 stddev:0.99 (1.8%) stddev-sans-first:1.05 12:12:55 INFO - PROCESS | 1679 | Values: 54.0 53.0 53.0 55.0 53.0 53.0 55.0 55.0 53.0 55.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:4.02 (5.7%) stddev-sans-first:3.32 12:12:55 INFO - PROCESS | 1679 | Values: 64.0 72.0 79.0 76.0 71.0 71.0 69.0 70.0 70.0 70.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.50 stddev:3.38 (4.0%) stddev-sans-first:3.57 12:12:55 INFO - PROCESS | 1679 | Values: 83.0 80.0 84.0 84.0 85.0 81.0 82.0 86.0 82.0 74.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:143.50 stddev:26.61 (18.5%) stddev-sans-first:27.63 12:12:55 INFO - PROCESS | 1679 | Values: 134.0 139.0 136.0 153.0 154.0 147.0 140.0 133.0 222.0 136.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:162.00 stddev:1.51 (0.9%) stddev-sans-first:1.32 12:12:55 INFO - PROCESS | 1679 | Values: 160.0 161.0 162.0 162.0 162.0 165.0 164.0 164.0 162.0 162.0 12:12:55 INFO - PROCESS | 1679 | 12:12:55 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 12:12:55 INFO - PROCESS | 1679 | Values: 67.0 67.0 67.0 66.0 66.0 65.0 66.0 65.0 66.0 65.0 12:12:55 INFO - PROCESS | 1679 | -------- Summary: end -------- 12:12:55 INFO - PROCESS | 1679 | 12:12:56 INFO - TEST-INFO | 1679: exit 0 12:12:56 INFO - TEST-OK | kraken | took 59275ms 12:12:56 INFO - SUITE-END | took 1177s 12:12:56 INFO - Completed test suite (00:19:37) 12:12:56 INFO - javascript benchmark 12:12:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.1579605098723, 3380.9830950845244, 3384.6107697614766, 3414.470757032661, 3446.2590543033243, 5726.68503232322, 5741.799037033705, 5788.652680839153, 5870.354261031283, 5924.140999555062, 5798.9130163047585, 5802.767889284434, 5853.678047707374, 5860.824175274738, 5868.090445980869, 5256.8160114396005, 5297.920531192035, 5320.574354051667, 5348.010618035656, 5395.379531353886, 5314.7608357624, 5318.707471089088, 5325.920111198335, 5329.680219186842, 5335.67985920846, 5297.629165958385, 5305.761240744157, 5319.707416092114, 5330.573554115662, 5362.973185134068, 5416.0, 5422.53908417785, 5423.105187644034, 5441.673499589998, 5448.809291674812, 5153.201253805629, 5242.004019236333, 5261.316028916254, 5320.787168513255, 5330.200469929541, 5692.203091567184, 5751.712414379264, 5832.2418085649015, 5856.121581762727, 5861.062230043172, 6321.209848768904, 6360.459360954324, 6363.649999250039, 6367.554271201017, 6375.521835862282, 6213.068039794022, 6233.968830155865, 6253.312135665074, 6298.968505157491, 6314.463270622003, 5945.10823376497, 5954.166416701665, 5965.821025369159, 5980.5514586405325, 5988.820335389945, 6431.0, 6431.903521447182, 6451.064595633607, 6454.515911306671, 6456.515761317826, 6109.356888764571, 6154.784582539679, 6229.127922090911, 6247.875042499125, 6278.466330361879, 6261.060840873814, 6301.117843501914, 6305.211848518935, 6309.432151106331, 6310.337414571496, 6253.030780229072, 6321.273053598895, 6340.904886426707, 6350.4602108820345, 6354.015127655221, 6140.600860944024, 6214.130021796952, 6232.25212974446, 6256.468200203034, 6288.99376099831, 4914.950850491521, 4941.407031156285, 4954.752262386866, 5012.448630650625, 5012.674176178537, 3681.37416639172, 4473.418455600751, 4489.236829738952, 4512.661550383668, 4513.909721805546, 5845.357010728816, 5845.970770146122, 5849.6782676952325, 5853.0, 5864.120381942743, 6197.907031394533, 6215.192025036717, 6274.372562743781, 6277.246730392382, 6288.151099601587, 5697.173909783059, 5777.45114214149, 5815.0, 5818.0981947798155, 5820.70896455184, 5488.725563721798, 5500.614956953055, 5508.944910550923, 5527.419620939743, 5542.63972841764, 6392.808215753535, 6407.679616019181, 6415.903761443582, 6428.582142160838, 6435.903461447989, 6222.502199824094, 6271.78048768291, 6295.307516173126, 6302.3067462578165, 6303.527235457267, 6343.270523889812, 6375.74497020114, 6386.233651961795, 6474.482041436768, 6487.026945958144, 6249.406306400884, 6296.748130074929, 6312.652804095819, 6316.842078948063, 6317.115603815378, 6148.077788331786, 6226.12834203221, 6239.282482514569, 6282.057691346152, 6289.559730818799, 6185.165002724754, 6266.717997690023, 6282.905756413749, 6285.43431091204, 6302.149209856794, 6256.405641464054, 6285.340039295991, 6290.21372328459, 6293.150424692791, 6298.874022519615, 5957.255343082114, 5989.8203053908455, 6147.692615369124, 6150.261968563801, 6164.722587483664, 6173.567850250619, 6190.1952746142715, 6213.65824879636, 6221.657808820558, 6223.0, 6048.879022419615, 6105.186826067751, 6134.04922237054, 6137.7851775187655, 6149.923763341487], "value": 5801.42150974967, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.7010033177439, 475.9457800970851, 486.5791090706539, 538.9595780316478, 546.6938514431919, 30771.69228307719, 31062.37875242494, 32404.67595324046, 32516.512252316206, 32527.0, 33039.83480082602, 44110.55889441105, 44831.0, 44880.0, 47677.7616111919, 9855.310128291023, 9993.350432221918, 10002.899971000306, 10015.44915029673, 10026.248031397638, 8861.335399845006, 9830.508474576278, 9968.75078123049, 9972.6509572165, 9976.700698979006, 12036.157468977175, 12257.14200006, 12323.445444954954, 12502.49990000399, 12521.436535355931, 12227.0, 12306.0, 12311.69220769488, 12319.322437265946, 12331.815022774666, 8815.603297851612, 8966.372353935227, 8967.148120928501, 12156.149069565134, 12259.0, 32895.83552082224, 41325.173496529904, 41645.583544164474, 41664.0, 43587.34618980718, 3492.3015396920464, 3503.4744788281705, 3528.523649307336, 3675.7978311192874, 3891.9610803891883, 32435.51346729801, 33238.50142247868, 33592.99221023373, 33702.6629733702, 33727.98816035498, 3371.679690429405, 3379.6958273755235, 3422.144463884029, 3436.570428696413, 3473.426884564044, 546108.0, 547699.0, 551861.0, 551915.0, 552161.2391938014, 55215.447845521034, 66212.66893665501, 66676.0, 66956.6652166736, 67015.0, 68145.31854681489, 68860.6556967212, 69122.3087769126, 72061.91907121397, 72171.63914180396, 16533.50399488017, 16720.0, 16727.665446690997, 16730.16349182536, 16741.246643901053, 12709.555165569162, 12734.299613521345, 12836.550720724917, 12843.972482201401, 12873.935630321788, 17286.308547657954, 17510.0, 19924.90037549832, 20002.0, 20152.596948060956, 6986.580805151707, 8009.71844504888, 8031.55826429552, 8039.638216280281, 8049.758507244792, 957.6791774755535, 971.9260217459747, 992.6823416506793, 1067.9839802402971, 1134.659602119361, 30873.691263087083, 31175.688243117278, 31293.8435307822, 31305.747770089398, 31834.840825795724, 1116.564539829467, 1131.5191043806349, 1153.3080151908787, 1161.7850697620968, 1201.408134222159, 15941.681166376608, 15974.520764377086, 16021.359145634044, 16102.919485402732, 16601.667966640598, 706894.4655276793, 710217.0, 715063.0, 718896.0, 718973.405132971, 61720.0, 62314.0, 62456.68771656203, 62510.374896251364, 62695.37304626896, 1246.9251844888972, 1282.9807552886714, 1375.3535838156051, 1438.5756201920458, 1463.0490181382186, 31855.0, 32013.0, 32116.03651890447, 32859.83570082134, 32865.50701739428, 60045.69977150086, 60810.39189608047, 60828.0, 64340.67829660822, 64344.0, 651563.0, 668029.0, 669112.0, 669654.0, 670349.0, 37278.627213727515, 44663.77668111639, 45403.77298113488, 45495.77252113718, 45543.0, 48124.0, 49203.753981229864, 49523.0, 50247.748761255956, 50406.991860163325, 40827.59172408238, 40887.56893508713, 41281.0, 41319.58680413158, 42347.729568113005, 40218.59781402149, 40549.594504054585, 41038.1792364157, 41805.0, 41946.790266048476, 38812.0, 39201.0, 39460.01349966159, 39787.0, 40099.198016038936, 34360.0, 35426.82286588551, 35462.0354689888, 36021.81989090038, 36157.638423615426, 23216.187433439747, 26077.348066297734, 26257.27633847184, 26335.20994370172, 26753.33116672099], "value": 21453.35050537561, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.7293874308667, 396.55981860135245, 399.15777709033915, 409.05712333072256, 418.36199795312143, 18264.726029109574, 22081.006354714038, 22604.0, 22820.65769013466, 23125.76874231257, 21371.252006179788, 21436.356909292706, 22020.0, 22194.0, 22264.109435622555, 5758.3090029196555, 6091.360407157251, 6102.450779429851, 6104.08438734189, 6104.572679912408, 4191.2246234446575, 4341.6092551670345, 4357.281048626973, 4421.889452763675, 5895.852603684921, 5801.796937107202, 6780.1524809398825, 6791.3548212919695, 6806.965965170167, 6809.931900680979, 6404.071409645599, 6550.901736473957, 6601.339866013408, 6727.831804204885, 6772.458203343721, 3708.701954315995, 4493.68544201906, 5756.8848623027725, 5765.4522820332, 5772.509336706385, 27804.58293125605, 27811.721882781116, 27918.302042448897, 28073.29816754577, 28240.152795416172, 2267.07050109456, 2395.652630368587, 2482.3170943867003, 2829.278533973844, 3234.0, 9814.509274536245, 10577.471126443725, 15294.847051529454, 16343.591410214722, 17071.65856682872, 1556.813182418117, 1832.743415921772, 1854.5085552328644, 1880.0522678378977, 1945.902704864751, 77584.0, 98768.0, 122210.38894805558, 122517.0, 122951.38524307321, 11545.365004924643, 11799.997000255074, 11812.645620631223, 11833.526658933546, 11925.940370298093, 14789.260536973108, 14880.553583392515, 14895.329710163069, 14897.702045959237, 14948.40206391754, 8777.648894044294, 8779.0, 8819.823603527893, 8872.73381798547, 8946.239569636653, 8895.73312800617, 8944.19502244788, 8961.417507861968, 9018.729438116867, 9038.638454461749, 4617.515160908123, 4617.561331673485, 4619.815207391734, 4621.19129152397, 4680.08738296029, 1773.7162054071287, 1774.066306209235, 1897.0, 1942.339604534465, 1943.9611207776047, 847.8685803700313, 937.4703292639637, 966.7438128895848, 1009.6769033909226, 1039.2725092435326, 21750.0, 22629.773702263097, 22633.0, 22652.0, 22802.885985570298, 904.9140331668481, 923.8475651517559, 924.3390975452584, 945.1493655709779, 955.2835373469898, 8739.519326437106, 9634.325597208268, 11830.822537661941, 11956.760864782656, 11979.0, 564170.0, 570054.1497292487, 574407.0, 584037.0, 584768.0, 70167.0, 115165.4241728786, 133258.33370833084, 139693.0, 140831.29584352218, 1011.8279892418307, 1016.0753714120116, 1056.1709058389129, 1077.2028698762858, 1080.9675709728563, 16418.425355112515, 19747.901260493607, 20078.096485657887, 20120.899395503515, 20129.899350503154, 57924.710376447845, 58551.70724146352, 58912.52718681896, 58932.705336473045, 59752.0, 79628.83340499383, 80987.19012810032, 82023.0, 83372.46830067664, 90338.09661903296, 7685.116211635734, 8130.390220733587, 8303.709370172015, 8410.87383689233, 8465.026521950054, 9504.762380940532, 13309.334533273492, 13349.799753003907, 13537.052406331855, 13553.457861685816, 11255.943720281346, 11401.20191586581, 11587.420628968686, 11629.418529073682, 11666.241694289844, 9846.212303015885, 11378.0, 11563.884361156617, 11625.709357266136, 11917.22538035025, 11722.70693232676, 11750.882491174978, 11824.64526064185, 11923.880761192277, 11944.16390852632, 7150.749723759852, 7459.962700186681, 7485.1017877855, 7733.7679869604, 8537.146285371662, 10517.10604598587, 11482.655520334403, 11507.942460287644, 11578.76842463163, 11871.406429678655], "value": 9853.74749113853, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1574.8267690554042, 1612.8387161283872, 1693.5850716574441, 1834.6239021000697, 1960.0493760526151, 8625.353098517613, 8934.59794309256, 9079.682211122612, 9082.455052696834, 9107.36248462608, 7973.282404583586, 8192.508449493034, 8211.835763284746, 8366.916330836704, 8829.337799665018, 2159.481724386148, 2194.6049711052005, 2322.9651555226687, 2402.2913240594066, 2404.75952404759, 2029.0, 2390.103711108334, 2397.844140130886, 2401.135591187171, 2405.398650337416, 2167.934961951144, 2194.7695491973273, 2387.140629373433, 2388.8805559721945, 2390.2829151254555, 2099.496120930981, 2178.1614078579782, 2178.2920550821045, 2181.2583721534597, 2181.694562761215, 2308.9422764430856, 2394.5091256292594, 2398.07674045492, 2409.8795060246916, 2410.987945060281, 8982.505962172077, 9042.86435703465, 9047.638094476213, 9057.00372958974, 9080.137386948229, 5300.257963885059, 5479.369872464677, 5497.092979658351, 5499.450054994469, 5529.529989950859, 3559.04252661038, 3641.2171383152486, 3659.323025240339, 3664.9816750916343, 3679.2641471705497, 1024.6106479537802, 1089.3605124287976, 1115.81031224692, 1115.9888401115966, 1119.261287550213, 12094.879051209464, 12118.697032574168, 12140.575079872162, 12158.817617735742, 12227.877721222852, 4666.626669866402, 4705.317728929286, 4887.437944636341, 4889.608831293488, 4902.166631672553, 10012.39925604466, 10124.291299609104, 10125.291229614004, 10144.695659130239, 10148.238882083726, 5435.266239057677, 5441.945580544144, 5454.645448045864, 5456.781728730885, 5460.153676180198, 4294.63495602871, 4993.80024799004, 4997.0, 5003.49965003504, 5041.344625198701, 3042.6957304269395, 3676.209614932749, 3770.4155855842387, 3895.6883449323986, 3902.375619900802, 1170.5317872850928, 1178.1458442629196, 1178.9587364442202, 1189.74420499593, 1189.7501524679915, 852.8720691896141, 948.9098535639104, 953.3945944325403, 973.4862289140314, 1016.6187679620142, 6008.729607167688, 6047.909281360783, 6137.631742095488, 6144.0, 6147.508199344042, 954.160338901762, 976.4531862157214, 985.7584891701579, 988.4069558264983, 990.5839547390113, 4029.3955906613655, 4056.492938382702, 4066.512018557792, 4151.605597468236, 4161.209370219649, 9479.383840050376, 10424.32241904289, 10882.455877206266, 10932.016118549209, 10957.890421095846, 10894.618688346029, 10898.073663738513, 10898.07366373867, 10910.890891091147, 10928.0, 6623.30455302196, 7108.573485590881, 8064.314533264738, 8270.917290827014, 8277.917220827836, 6099.84750381244, 6150.323464419, 6254.593451425642, 6287.497000239879, 6309.589876658002, 9878.30851840393, 10235.641752538626, 10365.01532354425, 10381.221408394551, 10395.58417663285, 10170.74573135648, 10533.578656853946, 10545.894541054491, 10550.788984220426, 10589.735256618646, 7770.417218708732, 7808.648610812414, 7843.215678432111, 7903.723369682263, 7913.9604301978125, 8441.746747597583, 8497.022842373206, 8535.10381409943, 8535.189157030072, 8536.061033286209, 7552.584607846621, 7667.578283194255, 7691.038620172479, 7831.608419579112, 7847.019122609674, 6571.835704107436, 7298.525595836254, 7453.2546745325035, 7686.269804368576, 7761.61191940412, 7121.857562848818, 7177.354038136593, 7187.31720486553, 7190.352868241884, 7194.820129496805, 4999.700017998786, 5046.1926091825135, 5087.847364579068, 5111.514406131411, 5228.503292187236, 5078.238264260391, 5110.61670374716, 5137.332146821039, 5168.224766285088, 5320.989012087691], "value": 4650.387689749394, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.134143058007, 311.9656837747848, 320.84278703435325, 321.673501396083, 325.2551656706143, 4746.549077837604, 5322.04203243416, 5446.319210098738, 5495.532879705225, 5534.833954981352, 4424.867253982377, 4619.643981569398, 5011.674241174321, 5345.412004679482, 5349.7057661828585, 3292.917677058069, 4039.899002524946, 4077.10303733179, 4149.46057012588, 4183.874483765492, 3483.4600636901323, 4169.999200191947, 4183.247015537202, 4190.53904070552, 4228.5982831630945, 3375.0887260439667, 4048.9392659110135, 4140.50313962325, 4184.0, 4206.852760153395, 3957.663598594123, 4001.979990100031, 4186.1418409226035, 4192.832286708528, 4209.179210054041, 3641.198936234024, 3643.981780091083, 3644.835982380799, 3645.143391303042, 3657.305112028706, 4377.233984052778, 4845.127876982142, 5325.653832500875, 5326.92009619856, 5329.44186790339, 4566.429196350457, 4743.691660042086, 4752.908720163117, 4756.762161891892, 4784.545468180518, 5031.911756620998, 5442.394493624391, 5648.446677163813, 5735.913961290584, 5786.942130578683, 3965.801709914522, 3977.3636218204947, 3979.7015223858325, 3980.4029395590605, 4034.1729945361426, 5191.896162076738, 5454.236406902997, 5751.281089863725, 6062.757489700451, 6091.269047714302, 3116.114750575869, 3512.4028915083986, 3876.1666241757594, 4022.255882661717, 4049.7570145791346, 3504.842282097312, 3637.781733096023, 4138.52406973196, 4162.376673097494, 4165.75005499671, 4054.2499637567144, 4611.100835337077, 4740.407449068866, 4777.976110119427, 4814.398200224972, 5092.694438333712, 5392.272043274186, 5730.570207234474, 5751.613080571599, 5768.798092066758, 1333.5532596580254, 1353.3301015997145, 1394.4352537222442, 1405.7469655462114, 1424.893133015028, 896.9551522423852, 920.1964150265421, 925.319889880937, 925.3984909808544, 928.9071092890657, 4521.615662668644, 5117.0, 5387.757550910218, 5427.9874669557985, 5474.014677358114, 5673.404292549306, 5873.0, 5887.558433117533, 5916.378780228014, 6050.183225264621, 4638.373819534319, 5088.0, 5488.1767734839295, 5494.587905907073, 5550.361708403505, 2574.7985472421183, 2795.3228063162346, 2848.8604558176894, 3118.7097612019083, 3168.603924509436, 5200.791968321225, 5419.403865574784, 5801.245838041112, 6176.814695559051, 6294.748210071637, 5251.6061295403, 6203.193584833943, 6235.906461403082, 6240.251169859646, 6258.280297765724, 5706.830099829525, 6013.639181649115, 6262.561620686508, 6279.748810047547, 6381.553291269659, 5344.946550534445, 5538.47384498472, 5977.252843394575, 5988.101784732325, 6003.489703375262, 5868.23712917318, 5894.38108998549, 5904.704764761659, 5926.355291495785, 5938.970305148619, 5764.827055188351, 6207.920346683912, 6211.04352129096, 6221.0, 6280.9685951569945, 3769.1896242308035, 4080.673546116363, 4109.321961876377, 4109.712320137562, 4141.689373297075, 5143.819966301162, 5984.790532331347, 6001.6990655138625, 6005.969970150121, 6006.759729610942, 4751.384529260068, 5459.617826752089, 5595.63628364155, 5742.397048309867, 5753.597248192586, 5685.943140568541, 5697.772089116389, 5714.342850572238, 5732.742026608896, 5765.194971319077, 4831.782569784438, 5331.920021199763, 5456.0, 5550.47270509301, 5649.3220813501, 5351.828549743363, 5452.2379558104785, 5502.752376143003, 5757.971210143923, 5784.971075144597, 4350.412694286231, 4638.002829391697, 4947.431045429822, 5613.0, 5628.0], "value": 4194.480142711352, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.5416872791957, 757.5909009135066, 784.0317208247812, 805.3235282362814, 883.2271762208068, 13492.73014539711, 14960.326785294661, 16010.359585616576, 16027.599310017255, 16093.356265749357, 13437.865621343808, 14601.926990365033, 14715.632109197275, 14747.115173089594, 14774.704505909875, 707.9858402831941, 723.8986541884141, 888.866669999502, 900.3787386703154, 905.0858632780872, 891.4873947480193, 910.544727636182, 912.7854954085784, 921.4425272710015, 925.5418567808944, 950.4582388038787, 966.5698764050001, 967.9661211857587, 968.2883080935507, 970.257752819094, 750.8385697075128, 943.056943056943, 966.0436168193473, 972.601233494271, 980.764616492044, 715.0525553641446, 717.1788302393782, 717.3615482220775, 718.1561665043553, 720.0315575550876, 15036.0, 15066.773998390034, 15071.54785356441, 15077.849221507755, 15081.849181508263, 14330.211838348887, 14337.283135843167, 14369.497067602584, 14454.566363009126, 14514.637134071627, 13173.934130329288, 13205.537806176833, 13256.20462772227, 13322.134061285986, 13506.729865402735, 4606.239970404879, 4625.953740462586, 4667.696599721007, 4838.903221935541, 4848.975755121237, 111934.99999999919, 114183.0, 115192.0, 115266.0, 115355.84644153535, 11489.195756297146, 11612.941935290439, 11660.18378713482, 11732.413379331, 11865.0, 22973.081076757077, 23004.19485318006, 23015.079396824276, 23047.423814404774, 23053.077876884738, 7705.807354816175, 8023.318017968421, 8038.035435747631, 8149.9592502038295, 8217.506949583045, 3878.650921417086, 4553.772311384484, 4553.908921821545, 4571.1543364477675, 4697.929531057037, 3594.0, 3710.128119891823, 3723.5717892442176, 3727.0309719473134, 3743.3262012837404, 600.4205941266612, 613.487737738984, 614.5421660862686, 626.6803929995643, 627.6892937995722, 530.7268091162423, 558.6759679386028, 639.8496353356958, 651.3746803069107, 692.4633409107881, 9230.815383692288, 9321.72034838956, 9652.27607929408, 9684.612615495442, 9909.504524773733, 612.9478994285447, 637.1112298343877, 643.7360682120308, 693.1647364925253, 717.1072015340934, 4454.732716037048, 4730.526947305242, 4735.502772208937, 4767.713937163781, 4780.1873681474235, 149774.25112874366, 150626.24686876495, 150874.24562877114, 152539.47460525474, 154888.22555887373, 89963.0, 112838.43580782044, 114155.0, 114652.28021579683, 115588.0, 108087.0, 116221.41889290669, 116590.83409165799, 118451.0, 120699.0, 8996.55017249122, 9017.504037278013, 9040.773980650274, 9045.864312035194, 9179.586918588813, 42511.787441062595, 43978.0, 61923.69038154781, 62503.74992500216, 63225.0, 93267.53366233125, 97008.0, 97107.0, 97880.51059744656, 98332.0, 12047.698807529881, 12121.69695757613, 12129.575464858688, 12145.111191475084, 12383.19509231897, 11572.826407603725, 13196.406161722554, 13449.86550134486, 13681.247531385869, 13714.177149370671, 12461.439235234617, 12557.93721031389, 12767.106302558732, 13015.739685206432, 13045.608631741063, 12043.939780301042, 12263.448144833326, 12511.186772859735, 12808.743825123633, 12848.486060557472, 11982.041436685218, 12128.514859405877, 12386.690332741753, 12689.8096528554, 12798.488060477848, 9683.176929960751, 10531.89468105309, 10635.113833629344, 10745.892541074489, 11232.66302010941, 8891.863944490718, 9447.716568502956, 10524.052835244865, 10582.470876455993, 10653.89346106529], "value": 7850.712446994723, "unit": "score"}], "name": "dromaeo_css", "value": 7567.6157467599, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 111.0, 113.0, 113.0, 111.0, 112.0, 112.0, 112.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 108.0, 105.0, 157.0, 107.0, 107.0, 125.0, 105.0, 158.0, 134.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 185.0, 188.0, 185.0, 197.0, 262.0, 180.0, 184.0, 182.0, 181.0], "value": 192.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 58.0, 89.0, 73.0, 81.0, 82.0, 77.0, 82.0, 57.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 92.0, 94.0, 71.0, 71.0, 66.0, 96.0, 70.0, 67.0, 102.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 88.0, 88.0, 86.0, 87.0, 88.0, 89.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 146.0, 87.0, 128.0, 145.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 130.0, 86.0, 84.0, 85.0, 69.0, 84.0, 109.0, 125.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 55.0, 53.0, 53.0, 55.0, 55.0, 53.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 79.0, 76.0, 71.0, 71.0, 69.0, 70.0, 70.0, 70.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 84.0, 84.0, 85.0, 81.0, 82.0, 86.0, 82.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 139.0, 136.0, 153.0, 154.0, 147.0, 140.0, 133.0, 222.0, 136.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 162.0, 162.0, 165.0, 164.0, 164.0, 162.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1459.4000000000003, "alertThreshold": 2.0}]} 12:12:56 INFO - Return code: 0 12:12:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:12:56 INFO - # TBPL SUCCESS # 12:12:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:56 INFO - 2016-07-05 12:12:56.781 screenresolution[1683:9754] starting screenresolution argv=screenresolution get 12:12:56 INFO - 2016-07-05 12:12:56.784 screenresolution[1683:9754] Display 0: 1600x1200x32@60 12:12:56 INFO - 2016-07-05 12:12:56.790 screenresolution[1684:9757] starting screenresolution argv=screenresolution list 12:12:56 INFO - Available Modes on Display 0 12:12:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:57 INFO - Intel Iris: 12:12:57 INFO - Chipset Model: Intel Iris 12:12:57 INFO - Type: GPU 12:12:57 INFO - Bus: Built-In 12:12:57 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:57 INFO - Vendor: Intel (0x8086) 12:12:57 INFO - Device ID: 0x0a2e 12:12:57 INFO - Revision ID: 0x0009 12:12:57 INFO - Displays: 12:12:57 INFO - Display: 12:12:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:57 INFO - Display Serial Number: 1600x1200 60 12:12:57 INFO - Main Display: Yes 12:12:57 INFO - Mirror: Off 12:12:57 INFO - Online: Yes 12:12:57 INFO - Rotation: Supported 12:12:57 INFO - Return code: 0 12:12:57 INFO - Running post-action listener: _resource_record_post_action 12:12:57 INFO - ##### 12:12:57 INFO - ##### Finished run-tests step (success) 12:12:57 INFO - ##### 12:12:57 INFO - Running post-run listener: _resource_record_post_run 12:12:57 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 36914688; Write bytes: 704774144; Read time: 238; Write time: 1903 12:12:57 INFO - TinderboxPrint: CPU usage
53.4% 12:12:57 INFO - TinderboxPrint: I/O read bytes / time
36,914,688 / 238 12:12:57 INFO - TinderboxPrint: I/O write bytes / time
704,774,144 / 1,903 12:12:57 INFO - TinderboxPrint: CPU idle
2,265.1 (46.6%) 12:12:57 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 12:12:57 INFO - TinderboxPrint: CPU user
1,622.9 (33.4%) 12:12:57 INFO - TinderboxPrint: Swap in / out
443,547,648 / 0 12:12:57 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 396626432; Write bytes: 391079424; Read time: 29936; Write time: 778 12:12:57 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35116544; Write bytes: 293779968; Read time: 222; Write time: 1075 12:12:57 INFO - Running post-run listener: _upload_blobber_files 12:12:57 INFO - Blob upload gear active. 12:12:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:12:57 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 12:12:57 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'] 12:12:57 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 12:12:57 INFO - (blobuploader) - INFO - Open directory for files ... 12:12:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:12:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:12:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:58 INFO - (blobuploader) - INFO - Done attempting. 12:12:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:12:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:12:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:59 INFO - (blobuploader) - INFO - Done attempting. 12:12:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:12:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:13:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:01 INFO - (blobuploader) - INFO - Done attempting. 12:13:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:13:01 INFO - Return code: 0 12:13:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:13:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:13:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c082162b3b67f7853e1d9f8eb829470996b2ed8205e80894cb946cadb9474233c28cae4883c42cd7de07082097b18258d76fbd00ff9362271560a075da88d63e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98eef6ee7afad0222a8597e7de12f9365690de3753757e52b99428b423ad0af6f7f58738b76168205cf249daed3cb1b7c63e541a6e6a032d464a5aef914c2462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a1daf77300341dfcbebb70f3006016ac3ac2b1ff4df293e8894d5bda80b501fb37dbefe23a5d3b458680e19b2e80d55e02d6e7e1fd0ce63711883a412260ba00"} 12:13:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:13:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:13:01 INFO - Contents: 12:13:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c082162b3b67f7853e1d9f8eb829470996b2ed8205e80894cb946cadb9474233c28cae4883c42cd7de07082097b18258d76fbd00ff9362271560a075da88d63e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98eef6ee7afad0222a8597e7de12f9365690de3753757e52b99428b423ad0af6f7f58738b76168205cf249daed3cb1b7c63e541a6e6a032d464a5aef914c2462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a1daf77300341dfcbebb70f3006016ac3ac2b1ff4df293e8894d5bda80b501fb37dbefe23a5d3b458680e19b2e80d55e02d6e7e1fd0ce63711883a412260ba00"} 12:13:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:13:01 INFO - Copying logs to upload dir... 12:13:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:13:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.468676 ========= master_lag: 0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-07-05 12:13:01.920900) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 12:13:01.924474) ========= 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.mincwp86lk/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.3VOEnSez8m/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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/c082162b3b67f7853e1d9f8eb829470996b2ed8205e80894cb946cadb9474233c28cae4883c42cd7de07082097b18258d76fbd00ff9362271560a075da88d63e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98eef6ee7afad0222a8597e7de12f9365690de3753757e52b99428b423ad0af6f7f58738b76168205cf249daed3cb1b7c63e541a6e6a032d464a5aef914c2462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a1daf77300341dfcbebb70f3006016ac3ac2b1ff4df293e8894d5bda80b501fb37dbefe23a5d3b458680e19b2e80d55e02d6e7e1fd0ce63711883a412260ba00"} build_url:https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010940 build_url: 'https://queue.taskcluster.net/v1/task/Qwc3VWPSSLSS1aXzjPWfbw/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/c082162b3b67f7853e1d9f8eb829470996b2ed8205e80894cb946cadb9474233c28cae4883c42cd7de07082097b18258d76fbd00ff9362271560a075da88d63e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/98eef6ee7afad0222a8597e7de12f9365690de3753757e52b99428b423ad0af6f7f58738b76168205cf249daed3cb1b7c63e541a6e6a032d464a5aef914c2462", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a1daf77300341dfcbebb70f3006016ac3ac2b1ff4df293e8894d5bda80b501fb37dbefe23a5d3b458680e19b2e80d55e02d6e7e1fd0ce63711883a412260ba00"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 12:13:01.956942) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:13:01.957284) ========= 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.mincwp86lk/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.3VOEnSez8m/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005022 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:13:02.006189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 12:13:02.006505) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 12:13:02.006853) ========= ========= Total master_lag: 0.66 =========