builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0190 starttime: 1467827798.46 results: success (0) buildid: 20160706092823 builduid: 04f742194ed94f7c93c434d3f4d93ed3 revision: e6e8c3df9af39c8771f795f33646a43ca022496c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.462963) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.463404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.463689) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.487162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.487587) ========= 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.rb13UVpdNM/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.zrjpqDyTvU/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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-06 10:56:38-- 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% 57.9M=0s 2016-07-06 10:56:38 (57.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079154 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.589142) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.589597) ========= 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.rb13UVpdNM/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.zrjpqDyTvU/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.020920 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:38.638581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 10:56:38.638939) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e6e8c3df9af39c8771f795f33646a43ca022496c --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 e6e8c3df9af39c8771f795f33646a43ca022496c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rb13UVpdNM/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.zrjpqDyTvU/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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-06 10:56:38,712 truncating revision to first 12 chars 2016-07-06 10:56:38,712 Setting DEBUG logging. 2016-07-06 10:56:38,712 attempt 1/10 2016-07-06 10:56:38,712 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e6e8c3df9af3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 10:56:40,062 unpacking tar archive at: autoland-e6e8c3df9af3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.601149 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 10:56:40.263953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:40.264531) ========= script_repo_revision: e6e8c3df9af39c8771f795f33646a43ca022496c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:40.265227) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:40.265730) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 10:56:40.287276) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-06 10:56:40.287903) ========= /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.rb13UVpdNM/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.zrjpqDyTvU/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:56:40 INFO - MultiFileLogger online at 20160706 10:56:40 in /builds/slave/test 10:56:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:56:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:56:40 INFO - {'append_to_log': False, 10:56:40 INFO - 'base_work_dir': '/builds/slave/test', 10:56:40 INFO - 'blob_upload_branch': 'Autoland', 10:56:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:56:40 INFO - 'branch': 'Autoland', 10:56:40 INFO - 'buildbot_json_path': 'buildprops.json', 10:56:40 INFO - 'config_files': ('talos/mac_config.py',), 10:56:40 INFO - 'default_actions': ('clobber', 10:56:40 INFO - 'read-buildbot-config', 10:56:40 INFO - 'download-and-extract', 10:56:40 INFO - 'populate-webroot', 10:56:40 INFO - 'create-virtualenv', 10:56:40 INFO - 'install', 10:56:40 INFO - 'run-tests'), 10:56:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:56:40 INFO - 'download_minidump_stackwalk': True, 10:56:40 INFO - 'download_symbols': 'ondemand', 10:56:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:56:40 INFO - 'tooltool.py': '/tools/tooltool.py', 10:56:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:56:40 INFO - '/tools/misc-python/virtualenv.py')}, 10:56:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:56:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:56:40 INFO - 'installer_path': 'installer.exe', 10:56:40 INFO - 'log_level': 'info', 10:56:40 INFO - 'log_name': 'talos', 10:56:40 INFO - 'log_to_console': True, 10:56:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:56:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:56:40 INFO - 'opt_config_files': (), 10:56:40 INFO - 'pip_index': False, 10:56:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:56:40 INFO - 'cmd': ('bash', 10:56:40 INFO - '-c', 10:56:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:56:40 INFO - 'enabled': True, 10:56:40 INFO - 'halt_on_failure': False, 10:56:40 INFO - 'name': 'check_screen_resolution'},), 10:56:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:56:40 INFO - 'cmd': ('bash', 10:56:40 INFO - '-c', 10:56:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:56:40 INFO - 'enabled': True, 10:56:40 INFO - 'halt_on_failure': False, 10:56:40 INFO - 'name': 'check_screen_resolution'},), 10:56:40 INFO - 'run_cmd_checks_enabled': True, 10:56:40 INFO - 'sps_profile': False, 10:56:40 INFO - 'sps_profile_interval': 0, 10:56:40 INFO - 'suite': 'dromaeojs', 10:56:40 INFO - 'system_bits': '32', 10:56:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:56:40 INFO - 'title': 't-yosemite-r7-0190', 10:56:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:56:40 INFO - 'use_talos_json': True, 10:56:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:56:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:56:40 INFO - 'work_dir': 'build'} 10:56:40 INFO - ##### 10:56:40 INFO - ##### Running clobber step. 10:56:40 INFO - ##### 10:56:40 INFO - Running pre-action listener: _resource_record_pre_action 10:56:40 INFO - Running main action method: clobber 10:56:40 INFO - rmtree: /builds/slave/test/build 10:56:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:56:41 INFO - Running post-action listener: _resource_record_post_action 10:56:41 INFO - ##### 10:56:41 INFO - ##### Finished clobber step (success) 10:56:41 INFO - ##### 10:56:41 INFO - ##### 10:56:41 INFO - ##### Running read-buildbot-config step. 10:56:41 INFO - ##### 10:56:41 INFO - Running pre-action listener: _resource_record_pre_action 10:56:41 INFO - Running main action method: read_buildbot_config 10:56:41 INFO - Using buildbot properties: 10:56:41 INFO - { 10:56:41 INFO - "project": "", 10:56:41 INFO - "product": "firefox", 10:56:41 INFO - "script_repo_revision": "production", 10:56:41 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:56:41 INFO - "repository": "", 10:56:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:56:41 INFO - "buildid": "20160706092823", 10:56:41 INFO - "pgo_build": "False", 10:56:41 INFO - "basedir": "/builds/slave/test", 10:56:41 INFO - "buildnumber": 22, 10:56:41 INFO - "slavename": "t-yosemite-r7-0190", 10:56:41 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:56:41 INFO - "platform": "yosemite_r7", 10:56:41 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:56:41 INFO - "branch": "autoland", 10:56:41 INFO - "repo_path": "integration/autoland", 10:56:41 INFO - "revision": "e6e8c3df9af39c8771f795f33646a43ca022496c", 10:56:41 INFO - "stage_platform": "macosx64", 10:56:41 INFO - "builduid": "04f742194ed94f7c93c434d3f4d93ed3", 10:56:41 INFO - "slavebuilddir": "test" 10:56:41 INFO - } 10:56:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:56:41 INFO - Running post-action listener: _resource_record_post_action 10:56:41 INFO - ##### 10:56:41 INFO - ##### Finished read-buildbot-config step (success) 10:56:41 INFO - ##### 10:56:41 INFO - ##### 10:56:41 INFO - ##### Running download-and-extract step. 10:56:41 INFO - ##### 10:56:41 INFO - Running pre-action listener: _resource_record_pre_action 10:56:41 INFO - Running main action method: download_and_extract 10:56:41 INFO - mkdir: /builds/slave/test/build/tests 10:56: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')]} 10:56:41 INFO - https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:56:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:56:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:56:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:42 INFO - Downloaded 1199 bytes. 10:56:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:56:42 INFO - Using the following test package requirements: 10:56:42 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:56:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:56:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:56:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'jsshell-mac.zip'], 10:56:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:56:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:56:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:56:42 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:56:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:56:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:56:42 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:56:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:56: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')]} 10:56:42 INFO - https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:56:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:56:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:56:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:44 INFO - Downloaded 18804725 bytes. 10:56:44 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'] 10:56:44 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 10:56:47 INFO - Return code: 0 10:56:47 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 10:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:56:47 INFO - https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:56:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:56:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:56:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:49 INFO - Downloaded 18804725 bytes. 10:56:49 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'] 10:56:49 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 10:56:53 INFO - Return code: 0 10:56:53 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')]} 10:56:53 INFO - https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:56:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:56:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:56:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:55 INFO - Downloaded 14403333 bytes. 10:56:55 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'] 10:56:55 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 10:56:55 INFO - Return code: 0 10:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:56:55 INFO - https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:56:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:56:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:56:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:56:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frx5enZeQsqjwgI1kPyLzw/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 10:56:59 INFO - Downloaded 112408995 bytes. 10:56:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:56:59 INFO - mkdir: /builds/slave/test/properties 10:56:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:56:59 INFO - Writing to file /builds/slave/test/properties/build_url 10:56:59 INFO - Contents: 10:56:59 INFO - build_url:https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:57:00 INFO - Running post-action listener: _resource_record_post_action 10:57:00 INFO - Running post-action listener: set_extra_try_arguments 10:57:00 INFO - ##### 10:57:00 INFO - ##### Finished download-and-extract step (success) 10:57:00 INFO - ##### 10:57:00 INFO - ##### 10:57:00 INFO - ##### Running populate-webroot step. 10:57:00 INFO - ##### 10:57:00 INFO - Running pre-action listener: _resource_record_pre_action 10:57:00 INFO - Running main action method: populate_webroot 10:57:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:57:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:57:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:57:00 INFO - '--tppagecycles', 10:57:00 INFO - '7'], 10:57:00 INFO - 'tests': ['tsvgm']}}, 10:57:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:57:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:57:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:57:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:57:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:57:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:57:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['damp', 'tps']}, 10:57:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'tests': ['damp', 'tps']}, 10:57:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['dromaeo_dom']}, 10:57:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:57:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['basic_compositor_video']}, 10:57:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:57:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['a11yr', 10:57:00 INFO - 'ts_paint', 10:57:00 INFO - 'tpaint', 10:57:00 INFO - 'sessionrestore', 10:57:00 INFO - 'sessionrestore_no_auto_restore', 10:57:00 INFO - 'tabpaint']}, 10:57:00 INFO - 'other-e10s': {'tests': ['a11yr', 10:57:00 INFO - 'ts_paint', 10:57:00 INFO - 'tpaint', 10:57:00 INFO - 'sessionrestore', 10:57:00 INFO - 'sessionrestore_no_auto_restore', 10:57:00 INFO - 'tabpaint']}, 10:57:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:57:00 INFO - 'ts_paint', 10:57:00 INFO - 'tpaint', 10:57:00 INFO - 'sessionrestore', 10:57:00 INFO - 'sessionrestore_no_auto_restore', 10:57:00 INFO - 'tabpaint']}, 10:57:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:57:00 INFO - 'ts_paint', 10:57:00 INFO - 'tpaint', 10:57:00 INFO - 'sessionrestore', 10:57:00 INFO - 'sessionrestore_no_auto_restore', 10:57:00 INFO - 'tabpaint']}, 10:57:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['a11yr', 10:57:00 INFO - 'ts_paint', 10:57:00 INFO - 'tpaint', 10:57:00 INFO - 'sessionrestore', 10:57:00 INFO - 'sessionrestore_no_auto_restore', 10:57:00 INFO - 'tabpaint']}, 10:57:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['a11yr', 10:57:00 INFO - 'ts_paint', 10:57:00 INFO - 'tpaint', 10:57:00 INFO - 'sessionrestore', 10:57:00 INFO - 'sessionrestore_no_auto_restore', 10:57:00 INFO - 'tabpaint']}, 10:57:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['tsvgx', 10:57:00 INFO - 'tsvgr_opacity', 10:57:00 INFO - 'tart', 10:57:00 INFO - 'tscrollx', 10:57:00 INFO - 'cart']}, 10:57:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:57:00 INFO - 'tsvgr_opacity', 10:57:00 INFO - 'tart', 10:57:00 INFO - 'tscrollx', 10:57:00 INFO - 'cart']}, 10:57:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'talos_options': ['--disable-e10s'], 10:57:00 INFO - 'tests': ['tp5o']}, 10:57:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'tests': ['tp5o']}, 10:57:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'talos_options': ['--disable-e10s', 10:57:00 INFO - '--xperf_path', 10:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:57:00 INFO - 'tests': ['tp5n']}, 10:57:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:57:00 INFO - 'talos_options': ['--xperf_path', 10:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:57:01 INFO - 'tests': ['tp5n']}}, 10:57:01 INFO - 'talos.zip': {'path': '', 10:57:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:57:01 INFO - Running post-action listener: _resource_record_post_action 10:57:01 INFO - ##### 10:57:01 INFO - ##### Finished populate-webroot step (success) 10:57:01 INFO - ##### 10:57:01 INFO - ##### 10:57:01 INFO - ##### Running create-virtualenv step. 10:57:01 INFO - ##### 10:57:01 INFO - Running pre-action listener: _resource_record_pre_action 10:57:01 INFO - Running main action method: create_virtualenv 10:57:01 INFO - Creating virtualenv /builds/slave/test/build/venv 10:57:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:57:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:57:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:57:01 INFO - Using real prefix '/tools/python27' 10:57:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:57:01 INFO - Installing distribute.............................................................................................................................................................................................done. 10:57:04 INFO - Installing pip.................done. 10:57:04 INFO - Return code: 0 10:57:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:04 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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:04 INFO - 'HOME': '/Users/cltbld', 10:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:04 INFO - 'LOGNAME': 'cltbld', 10:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:04 INFO - 'MOZ_NO_REMOTE': '1', 10:57:04 INFO - 'NO_EM_RESTART': '1', 10:57:04 INFO - 'PAGER': '/bin/cat', 10:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:04 INFO - 'PWD': '/builds/slave/test', 10:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:04 INFO - 'SHELL': '/bin/bash', 10:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:04 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:04 INFO - 'USER': 'cltbld', 10:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:04 INFO - 'XPC_FLAGS': '0x0', 10:57:04 INFO - 'XPC_SERVICE_NAME': '0', 10:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:57:04 INFO - Cleaning up... 10:57:04 INFO - Return code: 0 10:57:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:04 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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:04 INFO - 'HOME': '/Users/cltbld', 10:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:04 INFO - 'LOGNAME': 'cltbld', 10:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:04 INFO - 'MOZ_NO_REMOTE': '1', 10:57:04 INFO - 'NO_EM_RESTART': '1', 10:57:04 INFO - 'PAGER': '/bin/cat', 10:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:04 INFO - 'PWD': '/builds/slave/test', 10:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:04 INFO - 'SHELL': '/bin/bash', 10:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:04 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:04 INFO - 'USER': 'cltbld', 10:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:04 INFO - 'XPC_FLAGS': '0x0', 10:57:04 INFO - 'XPC_SERVICE_NAME': '0', 10:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:05 INFO - Downloading/unpacking psutil>=3.1.1 10:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:57:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:08 INFO - Installing collected packages: psutil 10:57:08 INFO - Running setup.py install for psutil 10:57:08 INFO - building 'psutil._psutil_osx' extension 10:57:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:57:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:57:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:57:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:57:09 INFO - building 'psutil._psutil_posix' extension 10:57:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:57:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:57:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:57:09 INFO - ^ 10:57:09 INFO - 1 warning generated. 10:57:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:57:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:09 INFO - Successfully installed psutil 10:57:09 INFO - Cleaning up... 10:57:09 INFO - Return code: 0 10:57:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:09 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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:09 INFO - 'HOME': '/Users/cltbld', 10:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:09 INFO - 'LOGNAME': 'cltbld', 10:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:09 INFO - 'MOZ_NO_REMOTE': '1', 10:57:09 INFO - 'NO_EM_RESTART': '1', 10:57:09 INFO - 'PAGER': '/bin/cat', 10:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:09 INFO - 'PWD': '/builds/slave/test', 10:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:09 INFO - 'SHELL': '/bin/bash', 10:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:09 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:09 INFO - 'USER': 'cltbld', 10:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:09 INFO - 'XPC_FLAGS': '0x0', 10:57:09 INFO - 'XPC_SERVICE_NAME': '0', 10:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:57:12 INFO - Installing collected packages: mozsystemmonitor 10:57:12 INFO - Running setup.py install for mozsystemmonitor 10:57:12 INFO - Successfully installed mozsystemmonitor 10:57:12 INFO - Cleaning up... 10:57:12 INFO - Return code: 0 10:57:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:12 INFO - 'HOME': '/Users/cltbld', 10:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:12 INFO - 'LOGNAME': 'cltbld', 10:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:12 INFO - 'MOZ_NO_REMOTE': '1', 10:57:12 INFO - 'NO_EM_RESTART': '1', 10:57:12 INFO - 'PAGER': '/bin/cat', 10:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:12 INFO - 'PWD': '/builds/slave/test', 10:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:12 INFO - 'SHELL': '/bin/bash', 10:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:12 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:12 INFO - 'USER': 'cltbld', 10:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:12 INFO - 'XPC_FLAGS': '0x0', 10:57:12 INFO - 'XPC_SERVICE_NAME': '0', 10:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:12 INFO - Downloading/unpacking jsonschema==2.5.1 10:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:57:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:15 INFO - Downloading functools32-3.2.3-2.tar.gz 10:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:57:15 INFO - warning: no files found matching '*.txt' 10:57:15 INFO - no previously-included directories found matching 'build' 10:57:15 INFO - no previously-included directories found matching 'dist' 10:57:15 INFO - no previously-included directories found matching '.git*' 10:57:15 INFO - Installing collected packages: jsonschema, functools32 10:57:15 INFO - Running setup.py install for functools32 10:57:15 INFO - warning: no files found matching '*.txt' 10:57:15 INFO - no previously-included directories found matching 'build' 10:57:15 INFO - no previously-included directories found matching 'dist' 10:57:15 INFO - no previously-included directories found matching '.git*' 10:57:15 INFO - Successfully installed jsonschema functools32 10:57:15 INFO - Cleaning up... 10:57:16 INFO - Return code: 0 10:57:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:57: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')]} 10:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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')]} 10:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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', '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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:57: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 blobuploader==1.2.4 10:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:16 INFO - 'HOME': '/Users/cltbld', 10:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:16 INFO - 'LOGNAME': 'cltbld', 10:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:16 INFO - 'MOZ_NO_REMOTE': '1', 10:57:16 INFO - 'NO_EM_RESTART': '1', 10:57:16 INFO - 'PAGER': '/bin/cat', 10:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:16 INFO - 'PWD': '/builds/slave/test', 10:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:16 INFO - 'SHELL': '/bin/bash', 10:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:16 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:16 INFO - 'USER': 'cltbld', 10:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:16 INFO - 'XPC_FLAGS': '0x0', 10:57:16 INFO - 'XPC_SERVICE_NAME': '0', 10:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:16 INFO - Downloading/unpacking blobuploader==1.2.4 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:18 INFO - Downloading blobuploader-1.2.4.tar.gz 10:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:57:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:57:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:19 INFO - Downloading docopt-0.6.1.tar.gz 10:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:57:20 INFO - Installing collected packages: blobuploader, requests, docopt 10:57:20 INFO - Running setup.py install for blobuploader 10:57:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:57:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:57:20 INFO - Running setup.py install for requests 10:57:20 INFO - Running setup.py install for docopt 10:57:20 INFO - Successfully installed blobuploader requests docopt 10:57:20 INFO - Cleaning up... 10:57:20 INFO - Return code: 0 10:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57: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')]} 10:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57: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')]} 10:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:20 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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:20 INFO - 'HOME': '/Users/cltbld', 10:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:20 INFO - 'LOGNAME': 'cltbld', 10:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:20 INFO - 'MOZ_NO_REMOTE': '1', 10:57:20 INFO - 'NO_EM_RESTART': '1', 10:57:20 INFO - 'PAGER': '/bin/cat', 10:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:20 INFO - 'PWD': '/builds/slave/test', 10:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:20 INFO - 'SHELL': '/bin/bash', 10:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:20 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:20 INFO - 'USER': 'cltbld', 10:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:20 INFO - 'XPC_FLAGS': '0x0', 10:57:20 INFO - 'XPC_SERVICE_NAME': '0', 10:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-rBLSy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-LKdbpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-dhqecP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-fesFgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-wBnT4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-s9p_zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-hONcAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-DGc8A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-x3Hhpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-U4NCFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ngEJE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-_I7LlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-EK3v6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-SKDpjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-YkolUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-c4JYJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:22 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Go8Rmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:57:22 INFO - Running setup.py install for manifestparser 10:57:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozcrash 10:57:23 INFO - Running setup.py install for mozdebug 10:57:23 INFO - Running setup.py install for mozdevice 10:57:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozfile 10:57:23 INFO - Running setup.py install for mozhttpd 10:57:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozinfo 10:57:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozInstall 10:57:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Running setup.py install for mozleak 10:57:24 INFO - Running setup.py install for mozlog 10:57:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Running setup.py install for moznetwork 10:57:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Running setup.py install for mozprocess 10:57:24 INFO - Running setup.py install for mozprofile 10:57:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Running setup.py install for mozrunner 10:57:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:57:25 INFO - Running setup.py install for mozscreenshot 10:57:25 INFO - Running setup.py install for moztest 10:57:25 INFO - Running setup.py install for mozversion 10:57:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:57:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:57:25 INFO - Cleaning up... 10:57:25 INFO - Return code: 0 10:57:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:25 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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:57:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:25 INFO - 'HOME': '/Users/cltbld', 10:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:25 INFO - 'LOGNAME': 'cltbld', 10:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:25 INFO - 'MOZ_NO_REMOTE': '1', 10:57:25 INFO - 'NO_EM_RESTART': '1', 10:57:25 INFO - 'PAGER': '/bin/cat', 10:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:25 INFO - 'PWD': '/builds/slave/test', 10:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:25 INFO - 'SHELL': '/bin/bash', 10:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:25 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:25 INFO - 'USER': 'cltbld', 10:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:25 INFO - 'XPC_FLAGS': '0x0', 10:57:25 INFO - 'XPC_SERVICE_NAME': '0', 10:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-N9gm9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-RiKKsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-OSxwO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-MgIMMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-8nnqdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-eOdWus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-wSeOOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Wfq3UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-3cy4UU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-duEz45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:26 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)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-t2R_lY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:26 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-RKmuhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:27 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-nlJnbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:27 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-SK876a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:27 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-vT94i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:27 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-JZPyJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:27 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-uohQDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:57:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:29 INFO - Downloading blessings-1.6.tar.gz 10:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:57:30 INFO - Installing collected packages: blessings 10:57:30 INFO - Running setup.py install for blessings 10:57:30 INFO - Successfully installed blessings 10:57:30 INFO - Cleaning up... 10:57:30 INFO - Return code: 0 10:57:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:57:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:30 INFO - Reading from file tmpfile_stdout 10:57:30 INFO - Current package versions: 10:57:30 INFO - blessings == 1.6 10:57:30 INFO - blobuploader == 1.2.4 10:57:30 INFO - docopt == 0.6.1 10:57:30 INFO - functools32 == 3.2.3-2 10:57:30 INFO - jsonschema == 2.5.1 10:57:30 INFO - manifestparser == 1.1 10:57:30 INFO - mozInstall == 1.12 10:57:30 INFO - mozcrash == 0.17 10:57:30 INFO - mozdebug == 0.1 10:57:30 INFO - mozdevice == 0.48 10:57:30 INFO - mozfile == 1.2 10:57:30 INFO - mozhttpd == 0.7 10:57:30 INFO - mozinfo == 0.9 10:57:30 INFO - mozleak == 0.1 10:57:30 INFO - mozlog == 3.2 10:57:30 INFO - moznetwork == 0.27 10:57:30 INFO - mozprocess == 0.23 10:57:30 INFO - mozprofile == 0.28 10:57:30 INFO - mozrunner == 6.12 10:57:30 INFO - mozscreenshot == 0.1 10:57:30 INFO - mozsystemmonitor == 0.3 10:57:30 INFO - moztest == 0.7 10:57:30 INFO - mozversion == 1.4 10:57:30 INFO - psutil == 3.1.1 10:57:30 INFO - requests == 1.2.3 10:57:30 INFO - wsgiref == 0.1.2 10:57:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:30 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 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:30 INFO - 'HOME': '/Users/cltbld', 10:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:30 INFO - 'LOGNAME': 'cltbld', 10:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:30 INFO - 'MOZ_NO_REMOTE': '1', 10:57:30 INFO - 'NO_EM_RESTART': '1', 10:57:30 INFO - 'PAGER': '/bin/cat', 10:57:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:30 INFO - 'PWD': '/builds/slave/test', 10:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:30 INFO - 'SHELL': '/bin/bash', 10:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:30 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:30 INFO - 'USER': 'cltbld', 10:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:30 INFO - 'XPC_FLAGS': '0x0', 10:57:30 INFO - 'XPC_SERVICE_NAME': '0', 10:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:57:30 INFO - Cleaning up... 10:57:30 INFO - Return code: 0 10:57:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fecc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97abc896e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, '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.rb13UVpdNM/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.zrjpqDyTvU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:57:30 INFO - 'HOME': '/Users/cltbld', 10:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:57:30 INFO - 'LOGNAME': 'cltbld', 10:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:30 INFO - 'MOZ_NO_REMOTE': '1', 10:57:30 INFO - 'NO_EM_RESTART': '1', 10:57:30 INFO - 'PAGER': '/bin/cat', 10:57:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:30 INFO - 'PWD': '/builds/slave/test', 10:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:57:30 INFO - 'SHELL': '/bin/bash', 10:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:57:30 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:57:30 INFO - 'USER': 'cltbld', 10:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:30 INFO - 'XPC_FLAGS': '0x0', 10:57:30 INFO - 'XPC_SERVICE_NAME': '0', 10:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:57:31 INFO - Cleaning up... 10:57:31 INFO - Return code: 0 10:57:31 INFO - Running post-action listener: _resource_record_post_action 10:57:31 INFO - Running post-action listener: _start_resource_monitoring 10:57:31 INFO - Starting resource monitoring. 10:57:31 INFO - ##### 10:57:31 INFO - ##### Finished create-virtualenv step (success) 10:57:31 INFO - ##### 10:57:31 INFO - ##### 10:57:31 INFO - ##### Running install step. 10:57:31 INFO - ##### 10:57:31 INFO - Running pre-action listener: _resource_record_pre_action 10:57:31 INFO - Running main action method: install 10:57:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:31 INFO - Reading from file tmpfile_stdout 10:57:31 INFO - Detecting whether we're running mozinstall >=1.0... 10:57:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:57:31 INFO - Reading from file tmpfile_stdout 10:57:31 INFO - Output received: 10:57:31 INFO - Usage: mozinstall [options] installer 10:57:31 INFO - Options: 10:57:31 INFO - -h, --help show this help message and exit 10:57:31 INFO - -d DEST, --destination=DEST 10:57:31 INFO - Directory to install application into. [default: 10:57:31 INFO - "/builds/slave/test"] 10:57:31 INFO - --app=APP Application being installed. [default: firefox] 10:57:31 INFO - mkdir: /builds/slave/test/build/application 10:57:31 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'] 10:57:31 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 10:58:06 INFO - Reading from file tmpfile_stdout 10:58:06 INFO - Output received: 10:58:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:58:06 INFO - Running post-action listener: _resource_record_post_action 10:58:06 INFO - ##### 10:58:06 INFO - ##### Finished install step (success) 10:58:06 INFO - ##### 10:58:06 INFO - ##### 10:58:06 INFO - ##### Running run-tests step. 10:58:06 INFO - ##### 10:58:06 INFO - Running pre-action listener: _resource_record_pre_action 10:58:06 INFO - Running main action method: run_tests 10:58:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:58:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:58:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:58:06 INFO - 2016-07-06 10:58:06.105 screenresolution[2195:15783] starting screenresolution argv=screenresolution get 10:58:06 INFO - 2016-07-06 10:58:06.108 screenresolution[2195:15783] Display 0: 1600x1200x32@60 10:58:06 INFO - 2016-07-06 10:58:06.114 screenresolution[2196:15785] starting screenresolution argv=screenresolution list 10:58:06 INFO - Available Modes on Display 0 10:58:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:58:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:58:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:58:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:58:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:58:06 INFO - Intel Iris: 10:58:06 INFO - Chipset Model: Intel Iris 10:58:06 INFO - Type: GPU 10:58:06 INFO - Bus: Built-In 10:58:06 INFO - VRAM (Dynamic, Max): 1536 MB 10:58:06 INFO - Vendor: Intel (0x8086) 10:58:06 INFO - Device ID: 0x0a2e 10:58:06 INFO - Revision ID: 0x0009 10:58:06 INFO - Displays: 10:58:06 INFO - Display: 10:58:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:58:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:58:06 INFO - Display Serial Number: 1600x1200 60 10:58:06 INFO - Main Display: Yes 10:58:06 INFO - Mirror: Off 10:58:06 INFO - Online: Yes 10:58:06 INFO - Rotation: Supported 10:58:06 INFO - Return code: 0 10:58:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:58:06 INFO - Python 2.7.3 10:58:06 INFO - Return code: 0 10:58:06 INFO - grabbing minidump binary from tooltool 10:58:06 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')]} 10:58:06 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 0x10fd9bd30>, '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 0x10fb00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd93b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:58:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:58:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:58:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:58:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:58:06 INFO - Return code: 0 10:58:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:58:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:58:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:58:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:58:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:58:06 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/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:58:06 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/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0190 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:58:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rb13UVpdNM/Render', 10:58:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:58:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:58:06 INFO - 'HOME': '/Users/cltbld', 10:58:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:58:06 INFO - 'LOGNAME': 'cltbld', 10:58:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:58:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:58:06 INFO - 'MOZ_NO_REMOTE': '1', 10:58:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:58:06 INFO - 'NO_EM_RESTART': '1', 10:58:06 INFO - 'PAGER': '/bin/cat', 10:58:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:58:06 INFO - 'PWD': '/builds/slave/test', 10:58:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:58:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:58:06 INFO - 'SHELL': '/bin/bash', 10:58:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrjpqDyTvU/Listeners', 10:58:06 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 10:58:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:58:06 INFO - 'USER': 'cltbld', 10:58:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:58:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:58:06 INFO - 'XPC_FLAGS': '0x0', 10:58:06 INFO - 'XPC_SERVICE_NAME': '0', 10:58:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:58:06 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/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:58:06 INFO - mozversion application_buildid: 20160706092823 10:58:06 INFO - mozversion application_changeset: e6e8c3df9af39c8771f795f33646a43ca022496c 10:58:06 INFO - mozversion application_display_name: Nightly 10:58:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:58:06 INFO - mozversion application_name: Firefox 10:58:06 INFO - mozversion application_remotingname: firefox 10:58:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:58:06 INFO - mozversion application_vendor: Mozilla 10:58:06 INFO - mozversion application_version: 50.0a1 10:58:06 INFO - mozversion platform_buildid: 20160706092823 10:58:06 INFO - mozversion platform_changeset: e6e8c3df9af39c8771f795f33646a43ca022496c 10:58:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:58:06 INFO - mozversion platform_version: 50.0a1 10:58:06 INFO - using testdate: 1467827886 10:58:06 INFO - actual date: 1467827886 10:58:06 INFO - starting webserver on 'localhost:51903' 10:58:06 INFO - SUITE-START | Running 2 tests 10:58:06 INFO - TEST-START | dromaeo_css 10:58:06 INFO - operating with platform_type : mac_ 10:58:06 INFO - Initialising browser for dromaeo_css test... 10:58:06 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpjiLO6d/profile http://localhost:51903/getInfo.html) 10:58:09 INFO - PROCESS | 2206 | __metrics Screen width/height:1600/1200 10:58:09 INFO - PROCESS | 2206 | colorDepth:24 10:58:09 INFO - PROCESS | 2206 | Browser inner width/height: 1024/689 10:58:09 INFO - PROCESS | 2206 | __metrics 10:58:09 INFO - PROCESS | 2206 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:58:09 INFO - PROCESS | 2206 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:58:09 INFO - TEST-INFO | 2206: exit 0 10:58:09 INFO - Browser initialized. 10:58:09 INFO - Running cycle 1/1 for dromaeo_css test... 10:58:09 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpjiLO6d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:58:22 INFO - PROCESS | 2208 | RSS: Main: 243863552 10:58:22 INFO - PROCESS | 2208 | 10:59:13 INFO - PROCESS | 2208 | 1467827953668 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:01:23 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51903/tests/dromaeo/cssquery-ext.html) 11:01:25 INFO - PROCESS | 2208 | RSS: Main: 334118912 11:01:25 INFO - PROCESS | 2208 | 11:04:28 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-ext.html (next: http://localhost:51903/tests/dromaeo/cssquery-jquery.html) 11:04:29 INFO - PROCESS | 2208 | RSS: Main: 376778752 11:04:29 INFO - PROCESS | 2208 | 11:07:31 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51903/tests/dromaeo/cssquery-mootools.html) 11:07:32 INFO - PROCESS | 2208 | RSS: Main: 371589120 11:07:32 INFO - PROCESS | 2208 | 11:10:34 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51903/tests/dromaeo/cssquery-prototype.html) 11:10:35 INFO - PROCESS | 2208 | RSS: Main: 369287168 11:10:35 INFO - PROCESS | 2208 | 11:13:38 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51903/tests/dromaeo/cssquery-yui.html) 11:13:39 INFO - PROCESS | 2208 | RSS: Main: 337977344 11:13:39 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-yui.html (next: http://localhost:51903/tests/dromaeo/cssquery-dojo.html) 11:16:42 INFO - PROCESS | 2208 | RSS: Main: 426672128 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | __start_tp_report 11:16:42 INFO - PROCESS | 2208 | _x_x_mozilla_page_load 11:16:42 INFO - PROCESS | 2208 | _x_x_mozilla_page_load_details 11:16:42 INFO - PROCESS | 2208 | |i|pagename|runs| 11:16:42 INFO - PROCESS | 2208 | |0;dojo.html;2858.9285267868313;3275.737940964723;3309.6193937697158;3322.750793690473;3384.5261663367132;5575.080111781562;5700.087985922253;5715.057015592432;5764.971175144124;5780.306363236407;5714.028615135427;5768.942310576904;5790.36306006339;5844.912326315109;5912.911306330419;5144.0740666679985;5185.24813901984;5237.3715154181555;5270.156774916014;5279.128943724281;5171.7672704728375;5285.973570132144;5298.947010529903;5358.196270559428;5367.1680889462;5269.156934890438;5302.893942121155;5303.284056652341;5318.494742999409;5347.171188465774;5111.488851114858;5138.254953031789;5153.510416510426;5176.715280659562;5214.895702085975;5143.3570803649545;5151.7681704323395;5163.328767260232;5166.819161329373;5171.482851714836;5722.456366645204;5777.653340799566;5830.242068531106;5830.5044071254;5844.766209351621;6119.694015299262;6148.200733904564;6182.752689892399;6199;6290;5578.748956296936;6246.718897649616;6294.181756371642;6323.399277068671;6342.175517182783;5829.533637309005;5855.473007429352;5875.030619947746;5878.470937615592;5901.380355062743;6247.468965137923;6317.178766760292;6333.841653958596;6334.493240540745;6334.714937827809;6059.606125601822;6089.821881683172;6093.254324534637;6147.538934579924;6162.198914141222;6188.566800324024;6246.593971391936;6270.31026587075;6277.058441233851;6293.905591416133;6052.061930400795;6131.448169664752;6233.719482623293;6241.21984751906;6242.563020588515;6099.76379046824;6167.383261673796;6241.126242326078;6245.6252624842655;6277.058441233851;4924;4940.530649588355;4943.233798761198;4944.1100601891285;4993.80024799004;4438.245498265303;4444.488883778342;4462.553744625577;4477.507474177838;4483.574060464251;5606.6636001840025;5677.943220567824;5693.259876216065;5741;5752.453516915886;6154.261488621394;6245;6287.371262873768;6302.8424289392215;6322.778702745381;5762.078067509178;5766.048601980626;5774.624649397776;5782.624129431658;5841.065429531254;4840.080384726891;5379.731013449312;5430.56555475561;5457.699826509501;5459.972700136554;6268.811935641938;6360.363963603602;6361.491080713625;6384.904226436607;6386.680665966683;6174.66039367839;6284.057391391328;6309.211348581427;6318.08387783778;6348.079528468254;6249.843753906189;6257.843553911189;6272.059191121369;6393.552451328363;6419.390157934989;6205.906911396423;6233.189685340877;6238.937610623836;6243.843903902439;6253.656048917172;6132.938670613236;6285.685715714105;6311.084892690443;6334.968325158345;6336.651484168231;5921.496672782861;5921.6743079131065;6022.036474164112;6074.848128796815;6098.664573448503;5925.703714814328;6032.638041717511;6080.0575910733905;6095.664738439428;6127.387261273837;5936.436038576328;5962.463378295801;5968.283805943314;5968.671723055274;6023.27720673522;5839.854003649943;6042.395760423922;6065.7573697053385;6106.175666284995;6136.907946380898;5924.407559244041;5935.703214839327;5941.287045554561;5946.07835785454;5976 11:16:42 INFO - PROCESS | 2208 | |1;ext.html;462.21654295968347;508.8727818045489;527.9546497934087;530.0194639916156;556.3574071946902;27867.860660696693;42789;43753.343699844525;43777.12445751091;46178;45503.77248113763;45980;46021.30968035482;46094.0781184377;46193;8890.155435233642;9207.26341892647;9401.777768890035;9836.262280328969;10056.19550435967;8848.646054157825;10177.84733229002;12082.33547154906;12275.079369047313;12457.563985260518;12288.5699000535;12356.196847204948;12358;12361.752764944697;12393.814092788616;8865.423747456394;9071.63713451461;12163.817542736866;12329.445174967148;12353.135280530412;9051.094890510962;9117.863232051524;11817.054635629129;12142.696432589168;12149.878501214964;43013.56986430127;43407.13185736268;43434.34848477275;43453.34819977703;43511.78244108791;3575.249197668493;3693.981530092359;3698.2418604186078;3835.846566137351;3982.522097348337;32228.677713223038;32949.01152965415;33289.500657490156;33344.33311333784;33692.98921032373;3256.544083828266;3299.2307082242805;3310.817905015223;3350.2461946062185;3407.16524451511;536453;536578;538236;538242;539376;60134.69932650309;60814.99999999911;60825;60947;61194.694026529585;68832.6558367215;68844.96732549016;69030.65484672645;69045;69108.30891691019;16877.91561042187;17119;17147.228374723167;17151.970881747136;17183.914080429517;11941.820872686916;12190.81713774294;12844.550440734529;13046.282454465096;13106.80339794904;18142.727859082126;18218.271269149085;18249.543761406072;18251;18434.17046232896;5441.830006548611;6527.771527996498;6542.738290468423;6554.442872355808;6561.606303621798;777.793884620576;920.5535315372039;1005.3013155856755;1067.524951396621;1131.3801546583752;26067.869660651577;30438.69561304403;30456;31187.688123118478;31882.88409905642;1015.6851376073441;1040.9687709368732;1049.2812423489934;1072.9785404292027;1104.2877344113;13362.465501380031;16219.351225951066;16259.918700406422;16261.837381626268;16316.67366652684;678804;685113;689803;690101;690880;63299;63329.36670633327;63457.73084538284;63465.365346346865;63507.047394289126;1258.5028913579085;1393.776995680666;1411.7529432349297;1430.5851303121979;1446.8697817196503;32281.35437291288;32293.51559726653;32334.029979100666;32610.67389326171;33003.17492062718;53386.73306633442;53453;54187.45812541824;54312.72843635757;54319.72840135774;456313;458338;459234.40765591915;460030;462575;36392.45411318779;43177.35233971556;43993.560064400226;44054.11891762083;44463.77768111139;49493.01013979772;49675;50299.74850125726;50677.49322506728;50742.98514029773;34555.65444345524;38908.832735018;41506.58493415027;41839.05058038963;41943.370849437895;40929.18141637091;40949;41049;41804.79097604614;42095.36856947087;39305.41041884317;39813.80093099516;40045.39931900966;40369.59630403658;42453;32970.835145824116;35553.644463555036;35725;36179.27641447104;36325.72859949889;21288.574228515034;23110.653340199573;25823.741762582886;26596;26723.866380667972 11:16:42 INFO - PROCESS | 2208 | |2;jquery.html;361.16750889200415;378.9488419063426;388.7598560591713;395.46019683132516;415.81288420210893;15598.298076586552;17101.82898171018;21878.90605469726;23284.76715232847;23290.951907164177;21228.469288267803;21428.678569821466;22275.554488910213;22425.439364015907;22431.663525047097;5860.560457965648;5913.113033045056;6123.632582045069;6169.136320915076;6169.2596888373455;5974.4921681657115;6061.757529698807;6090.847728806793;6093.695315234243;6113.663748493831;5730.4269573042575;6019.909701354483;6037.366076561963;6038.849028774272;6040.426159514839;5979.521638268928;6048.939510604926;6063.272407311151;6069.787557435513;6082.118092876531;5056.519630635084;5249.2388603652635;5252.71110088945;5253.973730131325;5273.34083239595;26994.865025674942;27169.864150679317;27185;27222.31944201391;27227.45545089087;2416.093964763214;2436.1351720139273;2477.5664258754646;2920.9707902920914;3231.725303349218;15523.456679016293;15933.362665493367;16142.515724528283;16263.918680406641;16836.83163168377;1802.927882884683;1886.3209244671907;1887.962240755191;1906.8569857260761;1948.7430607258382;126527.36736316352;128066.07900881587;128994.3550282252;129322.3533882334;129609;9844.50777461124;10461.320014199053;10704;11093.223474356879;11249.268797528135;12968.610941671765;13056.281904495345;13075.869241307466;13099.345032748515;13102;7422.888656670154;8912.777180570409;8932.598033088527;8979.865302020475;8994.550272486349;7759.922400776033;8187.83624327522;8788.91211087881;8967.578753736454;9267;7029.507934444539;7070.399016083587;7100.218975912646;7105.218425973138;7115.438442160818;1739.1825841854309;1808.032702504154;1885.9057047147587;1892.5302480263113;1892.5741708115838;828.6022709099545;843.9915600844035;870.3341943413294;936.9672061477952;1031.5254982707888;22654.773452265596;23876.522469550513;24385.512289754104;24397.634035489482;24594;952.3714348530076;976.345848281653;989.851522271665;1002.2132625888788;1032.0866033560353;12386.81419778704;12506.999440044774;12685.93657031709;12770.425330860133;12787.296698681661;609321;620417.7958220451;622281.8885905632;630967;630992.365648925;140227;141709.29145354207;147606.5239347593;148144;152604;982.3761911186306;1002.8245057115122;1005.3415013166242;1018.8522664213648;1020.3724709303725;18771.15529801135;20565.97170141517;20581.58836823285;20625;20634.96825158766;57001.429985701274;59747.40252597418;60397;60494.79010419679;61597.38402616096;88048.5597572008;89442.55278723825;90304.09695902957;90492.64261035959;91837.54081229551;8082.353411727166;8170.264676179172;8220.095789463274;8366.08086812555;8419.873701894354;13100;13250.00624953113;13372.398242079324;13592.252426116658;13637.795433068313;9671.274654400808;10856.620018299322;11539.653810385702;11606.12954028468;11718.296902185895;9548.952255238679;11170.994610485095;11352.716182095513;11417.60038398652;11572.421378931189;11476.598319059127;11535.192536522363;11581.420928953687;11822.290662560274;11960.401979901144;8143.389245806469;8236.001331432253;8264.297534709616;8467.788305292417;8472.279856212292;10674.8932510677;11658.53365865356;11763.764724705628;11773.941130294294;11814.94092529532 11:16:42 INFO - PROCESS | 2208 | |3;mootools.html;1516.8558986896242;1568.6392129810142;1734.4969958711974;1855.7401963725072;1899.559066599419;8348.624311905964;8538.658453661854;8976.820463590733;8980.955095224532;8989.190972812445;7884.960575197116;8166.6325015374305;8184.181581841813;8240.629171687273;8758.956205218965;1873.681474149398;2147.5919575280686;2178.814800741939;2397.5444665513496;2403.170906037414;2369.834111612188;2383.833131680783;2396.3290278721897;2398.4603464220586;2401.5076909233567;2105.5999360121527;2388.4625959159225;2389.9880500597474;2395.6646069550275;2397.6763136976547;2309;2391.928242152738;2394.8682822444757;2398.3524448399007;2408.1330720940446;2360.8583484990954;2378.369732021016;2378.4767351182713;2383.07060246504;2395.2814155753204;8987.505687187202;9064.365494415393;9066.093390660948;9076.773080673036;9080.682176123804;5301.946980530184;5391.81128660499;5432.6468779529205;5434.3207099112615;5445.047116754592;3063.5557844112477;3595.6404359563835;3663.725220608465;3714.257148570297;3729.4219395993664;1051.4900273367412;1091.8908109189097;1094.2176343914139;1102.200904344352;1104.282216559235;11927.821082683766;11944.761104777856;11952.820707689392;11959.820602690967;11973.161878668498;4687.1328804171335;4703.647226457961;4890.217565189552;4898.926516102261;4903.313536104948;10156.136728378171;10276.023777741102;10277.126444252173;10296.382217067;10327.173826093895;5448.83653490396;5481.725913704299;5481.75332110048;5486.0125177468435;5495.642783219078;4216.536181020086;4886.267059941038;4995.425526064477;5009.323741294878;5014.774335154853;3419.3245309798517;3966.186931678999;4060.3706425504092;4111.1366613011605;4124.13393187434;1281.5258354408743;1292.2182079841748;1294.0618051912477;1303.00320255005;1306.1314226039576;903.9276857851356;906.6146887572755;916.8441364967971;925.0519161789623;939.1876027236527;5884.411558844081;5942.6731529766275;5975.52195824333;6090.725917333731;6125.540584456184;835.1606635331574;878.0122362342365;878.486085639895;941.449252187478;945.1871390604075;3991.7804520751056;4020.9396859047138;4034.3545032794614;4065.329220678614;4117.814698338582;8630.834837296885;9362.06379362072;10806.08148307387;10981.286216395909;10986.230963832455;10979.341239525655;10987.61543345995;10995.615153469751;11000.174986876016;11062.612808551663;7873.448858579844;8309.542975136426;8373.874391884126;8403.621837017348;8426.957865210634;6054.424829641177;6128.693565321805;6226.439620434183;6262.68686565679;6264.404881536247;8395.538245396561;9972.401655900669;10098.747531311776;10357.689269321932;10357.792844143225;9665.806683866143;10297.227707921786;10551.103156231806;10558.736031599272;10583;5790.826275211751;7705.42209334291;7798;7882.921170788219;7895.960520197362;6144.508439324933;7979.281864632211;7983.72056978003;8492.023417306842;8592.398532102943;7586.696532138652;7656.923430765621;7665.003549538525;7695.6536955836;7798.220177982384;7564.130125035471;7604.353629941517;7605.809854753676;7724.420668450001;7740.883886741816;6970.745265851992;7195.532290401107;7200.063991681258;7201.603911784692;7202.135743710788;5015.423226328873;5094.337736094284;5117.8976420470635;5127.128388174094;5223.634345595924;4256.743925719374;4318.41701370311;5164;5207.609429292743;5307.548858347083 11:16:42 INFO - PROCESS | 2208 | |4;prototype.html;307.7660977656981;309.330299900715;315.2025375799227;318.3426224845695;320.70975766930906;4536.7051141675765;5157.561607263382;5404.810831620894;5485.4788795064505;5520.779168833242;4426.44669416323;4542.228530872962;5378.677279363231;5417.566594672427;5461.371942226645;3470.3406352793013;3892.552356479013;3932.370820668694;4091.734037287567;4121.083696081321;3366.9158271043298;4033.8503526494956;4109.73964319623;4129.359949207863;4172.624463798257;3732.682721968636;4087.816048277835;4126.216018956388;4130.938035929464;4140.937885931713;3352.580927384077;3547.1486843157713;3881.204353107606;4062.512498500184;4087.5299340575916;2849.9145025649054;3545.6099829018785;3549.7337699672626;3550.8757193498363;3563.251717139404;5126.026055049529;5138.922916156261;5193.428722840485;5234.555062819627;5282.603804714623;4236.152769446123;4586.7477288749105;4753.26324419712;4760;4774.069056533977;4435.090806384684;5254.320296648699;5321.973390133025;5616.0171969905105;5706.887157004386;3968.325384684582;3999;4004.699647526418;4011.458453108822;4019.196160767858;5020.573251273646;5361.919571206435;5623.606347555632;5927.8221653350465;5984.521238300926;3848.0956975110826;3863.2852922208917;3886.7084968627464;3889.9027524311555;3893.3575959966856;3300.1124016485574;3654.250878569887;3966.4446977423186;3987.7009224308295;4007.975972134054;4122.56713045132;4341.674374421931;4394.846180383671;4650.697704649187;4683.99294151759;4501.704130893268;5685.573581981368;5723.055695810228;5774.354309021908;5820.76716931318;1269.7079671675567;1338.4980632262902;1369.5822774054009;1386.861313868605;1424.1170474305789;798.5248776977689;826.0175578611381;841.2638940926689;841.9536925469158;845.5476320168681;4280.828766849353;5181.875431073242;5189.4032186299055;5235.188545775411;5369.3556773187465;4809.879753006133;5016.949830501721;5333.733313334318;5521.861953451196;5654.558087687696;5164.406093299243;5343.305370301913;5356.143017117242;5357.946420535823;5386.461353864661;2483.341914392687;2490.8007359411204;3040.863161157753;3062.1627023785995;3077.753779697619;4696.694714843524;5431.6740995540395;6014.578979471483;6019.518438524908;6021.969890150609;4364.476262848479;5203.687778733212;5485.177223416519;6004.609700369462;6010.459058684739;5296.602754793406;5667.348254950651;6058.303674970927;6063.757449702139;6079.574429789872;5343.572514198932;5745.224394706828;5811.796587119599;5835.503982161564;5869.266341707286;5079.390473143247;5231.476852314738;5701.315842098975;5769.720960220773;5784.351459834095;5253.894922101613;5476.972615136899;5703.315602127771;6060.181875446759;6063.696815159136;3185.2833112549492;3623;3835.600005997735;3864.0146762576487;3932.5870783568457;5771.307443106854;5822.214001109796;5834.299884013945;5838.4453476919625;5843.94156058451;4997.325361076353;5188.09208388523;5319.867003324948;5669.291338582677;5674.77300907959;5381.13901775714;5476.946145195551;5583.330000399978;5676.205331253545;5698.772049118154;4892.608591312757;5321.935612877517;5516.227728117987;5532.197831314356;5622.072358060874;4609.631229501565;4806.110869489016;5522.217281162417;5699.34457537372;5762.4525670061275;4401.66987475947;4836.95163048365;5594.972025140011;5603.075492543685;5617.7191140442 11:16:42 INFO - PROCESS | 2208 | |5;yui.html;746.3096635612059;764.9158592554821;769.9961500192499;801.2908575910319;826.8057006603448;15224.923875380622;15783.210839458025;15795.84204157958;15798.921005394972;15838;14385.856141438582;14642.41430342785;14740.484083057096;14775.335109920052;14838.629034274149;711.9003339532456;730.5689643110563;732.4177279063158;732.5201992694767;739.8557281330143;732.5860888597925;754.1365136918253;891.3493150000503;899.2221728205121;907.2560500389683;929.9674511392103;939.178219058324;942.0297093993198;945.3618807305076;946.9195118414908;940.8635747816528;941.0636416765294;941.7410212191634;943.7216021273706;946.5551190940316;561.3824792728008;685.0888318536385;708.3837061756253;710.5417006031132;712.6828561290228;15145.469908553148;15159.545213643498;15194.316255768517;15199.316030778642;15210.695786084327;14105.36525856339;14429.49496767608;14430.639234019234;14493.492727754583;14757.114573125647;12990.610281691565;13029.609111726664;13208.009399295091;13292.667683307995;13549.254790986492;4596.057808149321;4604;4633.212353899811;4705.435347758291;4784.832530861438;107023.46488267509;107153.92846071518;109735.45132274367;111130.88869111367;112350.43824780824;11432.428378581038;11444.084473242121;11492.540298388138;11610.477528511237;11618.88381116195;18537.35119270819;22988.65517017246;23048.07807687707;23060.538789224123;23439.41401464977;7875.251851074138;7922.643481043367;7935.841283174304;8150.470219435836;8174.918250817415;4384.145091707151;4436.578525040116;4536.251518499476;4663.580277775017;4747.525247475225;3617.94573081404;3772.905677358088;3783.8648405478248;3796.7152463565344;3801.82891769871;674.4772801079203;688.7830333444949;693.0262980512388;700.3486757315643;705.9611721355272;606.4269265544107;674.544682339419;693.5665209244222;701.5089437393844;710.6127160697432;8892.466452012899;9480.24158067353;9489.857652135224;9497.857532137023;9548.283878708986;584.2790254741677;619.4982064527746;654.8461111638761;667.5827607745159;702.455596912397;4530.546945305443;4577.908441831145;4585.312203169551;4656.580907718254;4692.554207350297;154487;154650;156469.217653911;156714;157396.21301893645;101985;104628.90742185267;106432;132362;134562;103109.96890031153;103497.48251258695;107907;109751;110867;9575.90424095778;9839.803203936024;9961.39648345306;10046.849297260693;10081.747956301151;48572;58178.7091064542;60595.39404605897;60744.48138796566;61743.765124698155;77529;93465.06534934563;94058;94153;98034.03931921464;13099.738005239651;13288.468461261828;13371.899429957279;13414.12808167466;13794.172349659053;14812.851871481578;14933.253337333308;15016;15188.772168417261;15449.5365139046;13968.860311396757;14048.367823448174;14061.156330620195;14081.577552673436;14530.128192308495;12800.423980921067;13787.862121378657;13873;13969.51106711301;14080.295985200904;13557.728845423233;13600.115992460458;13718.38267277955;14190.077644953046;14317.498887538886;11380.260283081574;11444.713882153013;11446.713832154262;11819.349935753273;11847.229930054518;11329.263597866113;11395.829062564235;11435.313881167156;11550.537978480766;11617.767644647229 11:16:42 INFO - PROCESS | 2208 | __end_tp_report 11:16:42 INFO - PROCESS | 2208 | __start_cc_report 11:16:42 INFO - PROCESS | 2208 | _x_x_mozilla_cycle_collect,1297 11:16:42 INFO - PROCESS | 2208 | __end_cc_report 11:16:42 INFO - PROCESS | 2208 | __startTimestamp1467829002481__endTimestamp 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | ------- Summary: start ------- 11:16:42 INFO - PROCESS | 2208 | Number of tests: 6 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | [#0] dojo.html Cycles:165 Average:5755.26 Median:5936.44 stddev:654.30 (11.0%) stddev-sans-first:615.59 11:16:42 INFO - PROCESS | 2208 | Values: 2858.9 3275.7 3309.6 3322.8 3384.5 5575.1 5700.1 5715.1 5765.0 5780.3 5714.0 5768.9 5790.4 5844.9 5912.9 5144.1 5185.2 5237.4 5270.2 5279.1 5171.8 5286.0 5298.9 5358.2 5367.2 5269.2 5302.9 5303.3 5318.5 5347.2 5111.5 5138.3 5153.5 5176.7 5214.9 5143.4 5151.8 5163.3 5166.8 5171.5 5722.5 5777.7 5830.2 5830.5 5844.8 6119.7 6148.2 6182.8 6199.0 6290.0 5578.7 6246.7 6294.2 6323.4 6342.2 5829.5 5855.5 5875.0 5878.5 5901.4 6247.5 6317.2 6333.8 6334.5 6334.7 6059.6 6089.8 6093.3 6147.5 6162.2 6188.6 6246.6 6270.3 6277.1 6293.9 6052.1 6131.4 6233.7 6241.2 6242.6 6099.8 6167.4 6241.1 6245.6 6277.1 4924.0 4940.5 4943.2 4944.1 4993.8 4438.2 4444.5 4462.6 4477.5 4483.6 5606.7 5677.9 5693.3 5741.0 5752.5 6154.3 6245.0 6287.4 6302.8 6322.8 5762.1 5766.0 5774.6 5782.6 5841.1 4840.1 5379.7 5430.6 5457.7 5460.0 6268.8 6360.4 6361.5 6384.9 6386.7 6174.7 6284.1 6309.2 6318.1 6348.1 6249.8 6257.8 6272.1 6393.6 6419.4 6205.9 6233.2 6238.9 6243.8 6253.7 6132.9 6285.7 6311.1 6335.0 6336.7 5921.5 5921.7 6022.0 6074.8 6098.7 5925.7 6032.6 6080.1 6095.7 6127.4 5936.4 5962.5 5968.3 5968.7 6023.3 5839.9 6042.4 6065.8 6106.2 6136.9 5924.4 5935.7 5941.3 5946.1 5976.0 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | [#1] ext.html Cycles:180 Average:71303.20 Median:40207.50 stddev:151899.33 (377.8%) stddev-sans-first:152232.33 11:16:42 INFO - PROCESS | 2208 | Values: 462.2 508.9 528.0 530.0 556.4 27867.9 42789.0 43753.3 43777.1 46178.0 45503.8 45980.0 46021.3 46094.1 46193.0 8890.2 9207.3 9401.8 9836.3 10056.2 8848.6 10177.8 12082.3 12275.1 12457.6 12288.6 12356.2 12358.0 12361.8 12393.8 8865.4 9071.6 12163.8 12329.4 12353.1 9051.1 9117.9 11817.1 12142.7 12149.9 43013.6 43407.1 43434.3 43453.3 43511.8 3575.2 3694.0 3698.2 3835.8 3982.5 32228.7 32949.0 33289.5 33344.3 33693.0 3256.5 3299.2 3310.8 3350.2 3407.2 536453.0 536578.0 538236.0 538242.0 539376.0 60134.7 60815.0 60825.0 60947.0 61194.7 68832.7 68845.0 69030.7 69045.0 69108.3 16877.9 17119.0 17147.2 17152.0 17183.9 11941.8 12190.8 12844.6 13046.3 13106.8 18142.7 18218.3 18249.5 18251.0 18434.2 5441.8 6527.8 6542.7 6554.4 6561.6 777.8 920.6 1005.3 1067.5 1131.4 26067.9 30438.7 30456.0 31187.7 31882.9 1015.7 1041.0 1049.3 1073.0 1104.3 13362.5 16219.4 16259.9 16261.8 16316.7 678804.0 685113.0 689803.0 690101.0 690880.0 63299.0 63329.4 63457.7 63465.4 63507.0 1258.5 1393.8 1411.8 1430.6 1446.9 32281.4 32293.5 32334.0 32610.7 33003.2 53386.7 53453.0 54187.5 54312.7 54319.7 456313.0 458338.0 459234.4 460030.0 462575.0 36392.5 43177.4 43993.6 44054.1 44463.8 49493.0 49675.0 50299.7 50677.5 50743.0 34555.7 38908.8 41506.6 41839.1 41943.4 40929.2 40949.0 41049.0 41804.8 42095.4 39305.4 39813.8 40045.4 40369.6 42453.0 32970.8 35553.6 35725.0 36179.3 36325.7 21288.6 23110.7 25823.7 26596.0 26723.9 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | [#2] jquery.html Cycles:180 Average:37554.21 Median:13535.78 stddev:104544.42 (772.4%) stddev-sans-first:104800.40 11:16:42 INFO - PROCESS | 2208 | Values: 361.2 378.9 388.8 395.5 415.8 15598.3 17101.8 21878.9 23284.8 23291.0 21228.5 21428.7 22275.6 22425.4 22431.7 5860.6 5913.1 6123.6 6169.1 6169.3 5974.5 6061.8 6090.8 6093.7 6113.7 5730.4 6019.9 6037.4 6038.8 6040.4 5979.5 6048.9 6063.3 6069.8 6082.1 5056.5 5249.2 5252.7 5254.0 5273.3 26994.9 27169.9 27185.0 27222.3 27227.5 2416.1 2436.1 2477.6 2921.0 3231.7 15523.5 15933.4 16142.5 16263.9 16836.8 1802.9 1886.3 1888.0 1906.9 1948.7 126527.4 128066.1 128994.4 129322.4 129609.0 9844.5 10461.3 10704.0 11093.2 11249.3 12968.6 13056.3 13075.9 13099.3 13102.0 7422.9 8912.8 8932.6 8979.9 8994.6 7759.9 8187.8 8788.9 8967.6 9267.0 7029.5 7070.4 7100.2 7105.2 7115.4 1739.2 1808.0 1885.9 1892.5 1892.6 828.6 844.0 870.3 937.0 1031.5 22654.8 23876.5 24385.5 24397.6 24594.0 952.4 976.3 989.9 1002.2 1032.1 12386.8 12507.0 12685.9 12770.4 12787.3 609321.0 620417.8 622281.9 630967.0 630992.4 140227.0 141709.3 147606.5 148144.0 152604.0 982.4 1002.8 1005.3 1018.9 1020.4 18771.2 20566.0 20581.6 20625.0 20635.0 57001.4 59747.4 60397.0 60494.8 61597.4 88048.6 89442.6 90304.1 90492.6 91837.5 8082.4 8170.3 8220.1 8366.1 8419.9 13100.0 13250.0 13372.4 13592.3 13637.8 9671.3 10856.6 11539.7 11606.1 11718.3 9549.0 11171.0 11352.7 11417.6 11572.4 11476.6 11535.2 11581.4 11822.3 11960.4 8143.4 8236.0 8264.3 8467.8 8472.3 10674.9 11658.5 11763.8 11773.9 11814.9 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | [#3] mootools.html Cycles:180 Average:5762.20 Median:5012.05 stddev:3231.71 (64.5%) stddev-sans-first:3225.03 11:16:42 INFO - PROCESS | 2208 | Values: 1516.9 1568.6 1734.5 1855.7 1899.6 8348.6 8538.7 8976.8 8981.0 8989.2 7885.0 8166.6 8184.2 8240.6 8759.0 1873.7 2147.6 2178.8 2397.5 2403.2 2369.8 2383.8 2396.3 2398.5 2401.5 2105.6 2388.5 2390.0 2395.7 2397.7 2309.0 2391.9 2394.9 2398.4 2408.1 2360.9 2378.4 2378.5 2383.1 2395.3 8987.5 9064.4 9066.1 9076.8 9080.7 5301.9 5391.8 5432.6 5434.3 5445.0 3063.6 3595.6 3663.7 3714.3 3729.4 1051.5 1091.9 1094.2 1102.2 1104.3 11927.8 11944.8 11952.8 11959.8 11973.2 4687.1 4703.6 4890.2 4898.9 4903.3 10156.1 10276.0 10277.1 10296.4 10327.2 5448.8 5481.7 5481.8 5486.0 5495.6 4216.5 4886.3 4995.4 5009.3 5014.8 3419.3 3966.2 4060.4 4111.1 4124.1 1281.5 1292.2 1294.1 1303.0 1306.1 903.9 906.6 916.8 925.1 939.2 5884.4 5942.7 5975.5 6090.7 6125.5 835.2 878.0 878.5 941.4 945.2 3991.8 4020.9 4034.4 4065.3 4117.8 8630.8 9362.1 10806.1 10981.3 10986.2 10979.3 10987.6 10995.6 11000.2 11062.6 7873.4 8309.5 8373.9 8403.6 8427.0 6054.4 6128.7 6226.4 6262.7 6264.4 8395.5 9972.4 10098.7 10357.7 10357.8 9665.8 10297.2 10551.1 10558.7 10583.0 5790.8 7705.4 7798.0 7882.9 7896.0 6144.5 7979.3 7983.7 8492.0 8592.4 7586.7 7656.9 7665.0 7695.7 7798.2 7564.1 7604.4 7605.8 7724.4 7740.9 6970.7 7195.5 7200.1 7201.6 7202.1 5015.4 5094.3 5117.9 5127.1 5223.6 4256.7 4318.4 5164.0 5207.6 5307.5 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | [#4] prototype.html Cycles:180 Average:4520.96 Median:5160.98 stddev:1403.20 (27.2%) stddev-sans-first:1371.04 11:16:42 INFO - PROCESS | 2208 | Values: 307.8 309.3 315.2 318.3 320.7 4536.7 5157.6 5404.8 5485.5 5520.8 4426.4 4542.2 5378.7 5417.6 5461.4 3470.3 3892.6 3932.4 4091.7 4121.1 3366.9 4033.9 4109.7 4129.4 4172.6 3732.7 4087.8 4126.2 4130.9 4140.9 3352.6 3547.1 3881.2 4062.5 4087.5 2849.9 3545.6 3549.7 3550.9 3563.3 5126.0 5138.9 5193.4 5234.6 5282.6 4236.2 4586.7 4753.3 4760.0 4774.1 4435.1 5254.3 5322.0 5616.0 5706.9 3968.3 3999.0 4004.7 4011.5 4019.2 5020.6 5361.9 5623.6 5927.8 5984.5 3848.1 3863.3 3886.7 3889.9 3893.4 3300.1 3654.3 3966.4 3987.7 4008.0 4122.6 4341.7 4394.8 4650.7 4684.0 4501.7 5685.6 5723.1 5774.4 5820.8 1269.7 1338.5 1369.6 1386.9 1424.1 798.5 826.0 841.3 842.0 845.5 4280.8 5181.9 5189.4 5235.2 5369.4 4809.9 5016.9 5333.7 5521.9 5654.6 5164.4 5343.3 5356.1 5357.9 5386.5 2483.3 2490.8 3040.9 3062.2 3077.8 4696.7 5431.7 6014.6 6019.5 6022.0 4364.5 5203.7 5485.2 6004.6 6010.5 5296.6 5667.3 6058.3 6063.8 6079.6 5343.6 5745.2 5811.8 5835.5 5869.3 5079.4 5231.5 5701.3 5769.7 5784.4 5253.9 5477.0 5703.3 6060.2 6063.7 3185.3 3623.0 3835.6 3864.0 3932.6 5771.3 5822.2 5834.3 5838.4 5843.9 4997.3 5188.1 5319.9 5669.3 5674.8 5381.1 5476.9 5583.3 5676.2 5698.8 4892.6 5321.9 5516.2 5532.2 5622.1 4609.6 4806.1 5522.2 5699.3 5762.5 4401.7 4837.0 5595.0 5603.1 5617.7 11:16:42 INFO - PROCESS | 2208 | 11:16:42 INFO - PROCESS | 2208 | [#5] yui.html Cycles:180 Average:24717.75 Median:23249.98 stddev:39027.83 (167.9%) stddev-sans-first:39095.81 11:16:42 INFO - PROCESS | 2208 | Values: 746.3 764.9 770.0 801.3 826.8 15224.9 15783.2 15795.8 15798.9 15838.0 14385.9 14642.4 14740.5 14775.3 14838.6 711.9 730.6 732.4 732.5 739.9 732.6 754.1 891.3 899.2 907.3 930.0 939.2 942.0 945.4 946.9 940.9 941.1 941.7 943.7 946.6 561.4 685.1 708.4 710.5 712.7 15145.5 15159.5 15194.3 15199.3 15210.7 14105.4 14429.5 14430.6 14493.5 14757.1 12990.6 13029.6 13208.0 13292.7 13549.3 4596.1 4604.0 4633.2 4705.4 4784.8 107023.5 107153.9 109735.5 111130.9 112350.4 11432.4 11444.1 11492.5 11610.5 11618.9 18537.4 22988.7 23048.1 23060.5 23439.4 7875.3 7922.6 7935.8 8150.5 8174.9 4384.1 4436.6 4536.3 4663.6 4747.5 3617.9 3772.9 3783.9 3796.7 3801.8 674.5 688.8 693.0 700.3 706.0 606.4 674.5 693.6 701.5 710.6 8892.5 9480.2 9489.9 9497.9 9548.3 584.3 619.5 654.8 667.6 702.5 4530.5 4577.9 4585.3 4656.6 4692.6 154487.0 154650.0 156469.2 156714.0 157396.2 101985.0 104628.9 106432.0 132362.0 134562.0 103110.0 103497.5 107907.0 109751.0 110867.0 9575.9 9839.8 9961.4 10046.8 10081.7 48572.0 58178.7 60595.4 60744.5 61743.8 77529.0 93465.1 94058.0 94153.0 98034.0 13099.7 13288.5 13371.9 13414.1 13794.2 14812.9 14933.3 15016.0 15188.8 15449.5 13968.9 14048.4 14061.2 14081.6 14530.1 12800.4 13787.9 13873.0 13969.5 14080.3 13557.7 13600.1 13718.4 14190.1 14317.5 11380.3 11444.7 11446.7 11819.3 11847.2 11329.3 11395.8 11435.3 11550.5 11617.8 11:16:42 INFO - PROCESS | 2208 | -------- Summary: end -------- 11:16:42 INFO - PROCESS | 2208 | 11:16:43 INFO - TEST-INFO | 2208: exit 0 11:16:43 INFO - TEST-OK | dromaeo_css | took 1116391ms 11:16:43 INFO - TEST-START | kraken 11:16:43 INFO - operating with platform_type : mac_ 11:16:43 INFO - Initialising browser for kraken test... 11:16:43 INFO - TEST-INFO | started process 2211 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpVgSpUL/profile http://localhost:51903/getInfo.html) 11:16:45 INFO - PROCESS | 2211 | __metrics Screen width/height:1600/1200 11:16:45 INFO - PROCESS | 2211 | colorDepth:24 11:16:45 INFO - PROCESS | 2211 | Browser inner width/height: 1024/689 11:16:45 INFO - PROCESS | 2211 | __metrics 11:16:45 INFO - PROCESS | 2211 | 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:16:45 INFO - PROCESS | 2211 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:16:46 INFO - TEST-INFO | 2211: exit 0 11:16:46 INFO - Browser initialized. 11:16:46 INFO - Running cycle 1/1 for kraken test... 11:16:46 INFO - TEST-INFO | started process 2212 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpVgSpUL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:58 INFO - PROCESS | 2212 | RSS: Main: 243761152 11:16:58 INFO - PROCESS | 2212 | 11:17:00 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-beat-detection) 11:17:01 INFO - PROCESS | 2212 | RSS: Main: 294707200 11:17:01 INFO - PROCESS | 2212 | 11:17:04 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-dft) 11:17:05 INFO - PROCESS | 2212 | RSS: Main: 304726016 11:17:05 INFO - PROCESS | 2212 | 11:17:08 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-fft) 11:17:09 INFO - PROCESS | 2212 | RSS: Main: 390352896 11:17:09 INFO - PROCESS | 2212 | 11:17:11 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-oscillator) 11:17:11 INFO - PROCESS | 2212 | RSS: Main: 290562048 11:17:11 INFO - PROCESS | 2212 | 11:17:13 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51903/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:17:14 INFO - PROCESS | 2212 | RSS: Main: 294641664 11:17:14 INFO - PROCESS | 2212 | 11:17:17 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51903/tests/kraken/driver.html?testName=imaging-darkroom) 11:17:18 INFO - PROCESS | 2212 | RSS: Main: 330887168 11:17:18 INFO - PROCESS | 2212 | 11:17:20 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51903/tests/kraken/driver.html?testName=imaging-desaturate) 11:17:21 INFO - PROCESS | 2212 | RSS: Main: 335364096 11:17:21 INFO - PROCESS | 2212 | 11:17:24 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51903/tests/kraken/driver.html?testName=json-parse-financial) 11:17:25 INFO - PROCESS | 2212 | RSS: Main: 333910016 11:17:25 INFO - PROCESS | 2212 | 11:17:26 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51903/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:17:27 INFO - PROCESS | 2212 | RSS: Main: 313741312 11:17:27 INFO - PROCESS | 2212 | 11:17:29 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:17:30 INFO - PROCESS | 2212 | RSS: Main: 321036288 11:17:30 INFO - PROCESS | 2212 | 11:17:32 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:17:32 INFO - PROCESS | 2212 | RSS: Main: 318062592 11:17:32 INFO - PROCESS | 2212 | 11:17:35 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:17:36 INFO - PROCESS | 2212 | RSS: Main: 328527872 11:17:36 INFO - PROCESS | 2212 | 11:17:39 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:17:39 INFO - PROCESS | 2212 | RSS: Main: 329781248 11:17:39 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51903/tests/kraken/driver.html?testName=ai-astar) 11:17:41 INFO - PROCESS | 2212 | RSS: Main: 372948992 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | __start_tp_report 11:17:41 INFO - PROCESS | 2212 | _x_x_mozilla_page_load 11:17:41 INFO - PROCESS | 2212 | _x_x_mozilla_page_load_details 11:17:41 INFO - PROCESS | 2212 | |i|pagename|runs| 11:17:41 INFO - PROCESS | 2212 | |0;ai-astar;101;114;112;112;114;111;114;111;112;112 11:17:41 INFO - PROCESS | 2212 | |1;audio-beat-detection;144;108;109;199;141;121;125;110;177;137 11:17:41 INFO - PROCESS | 2212 | |2;audio-dft;182;184;183;186;185;184;187;224;231;181 11:17:41 INFO - PROCESS | 2212 | |3;audio-fft;80;75;58;111;57;58;60;69;83;61 11:17:41 INFO - PROCESS | 2212 | |4;audio-oscillator;85;83;94;70;70;68;96;70;67;98 11:17:41 INFO - PROCESS | 2212 | |5;imaging-gaussian-blur;87;84;87;85;91;87;87;84;87;84 11:17:41 INFO - PROCESS | 2212 | |6;imaging-darkroom;98;94;94;93;93;93;93;93;93;93 11:17:41 INFO - PROCESS | 2212 | |7;imaging-desaturate;85;108;131;134;85;85;108;133;146;83 11:17:41 INFO - PROCESS | 2212 | |8;json-parse-financial;53;54;54;54;53;54;53;54;54;53 11:17:41 INFO - PROCESS | 2212 | |9;json-stringify-tinderbox;66;69;81;75;72;71;69;69;70;71 11:17:41 INFO - PROCESS | 2212 | |10;stanford-crypto-aes;84;85;82;83;85;82;85;84;86;81 11:17:41 INFO - PROCESS | 2212 | |11;stanford-crypto-ccm;133;150;149;148;128;137;127;117;184;160 11:17:41 INFO - PROCESS | 2212 | |12;stanford-crypto-pbkdf2;163;165;164;164;163;162;164;163;162;159 11:17:41 INFO - PROCESS | 2212 | |13;stanford-crypto-sha256-iterative;67;67;67;68;76;86;89;88;71;65 11:17:41 INFO - PROCESS | 2212 | __end_tp_report 11:17:41 INFO - PROCESS | 2212 | __start_cc_report 11:17:41 INFO - PROCESS | 2212 | _x_x_mozilla_cycle_collect,2066 11:17:41 INFO - PROCESS | 2212 | __end_cc_report 11:17:41 INFO - PROCESS | 2212 | __startTimestamp1467829061945__endTimestamp 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | ------- Summary: start ------- 11:17:41 INFO - PROCESS | 2212 | Number of tests: 14 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:3.80 (3.4%) stddev-sans-first:1.24 11:17:41 INFO - PROCESS | 2212 | Values: 101.0 114.0 112.0 112.0 114.0 111.0 114.0 111.0 112.0 112.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:139.00 stddev:30.30 (21.8%) stddev-sans-first:32.04 11:17:41 INFO - PROCESS | 2212 | Values: 144.0 108.0 109.0 199.0 141.0 121.0 125.0 110.0 177.0 137.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#2] audio-dft Cycles:10 Average:192.70 Median:185.50 stddev:18.50 (10.0%) stddev-sans-first:19.21 11:17:41 INFO - PROCESS | 2212 | Values: 182.0 184.0 183.0 186.0 185.0 184.0 187.0 224.0 231.0 181.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#3] audio-fft Cycles:10 Average:71.20 Median:65.00 stddev:17.00 (26.1%) stddev-sans-first:17.73 11:17:41 INFO - PROCESS | 2212 | Values: 80.0 75.0 58.0 111.0 57.0 58.0 60.0 69.0 83.0 61.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:84.00 stddev:12.57 (15.0%) stddev-sans-first:13.21 11:17:41 INFO - PROCESS | 2212 | Values: 85.0 83.0 94.0 70.0 70.0 68.0 96.0 70.0 67.0 98.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.16 (2.5%) stddev-sans-first:2.28 11:17:41 INFO - PROCESS | 2212 | Values: 87.0 84.0 87.0 85.0 91.0 87.0 87.0 84.0 87.0 84.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.00 stddev:1.57 (1.7%) stddev-sans-first:0.44 11:17:41 INFO - PROCESS | 2212 | Values: 98.0 94.0 94.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#7] imaging-desaturate Cycles:10 Average:109.80 Median:114.50 stddev:24.62 (21.5%) stddev-sans-first:24.42 11:17:41 INFO - PROCESS | 2212 | Values: 85.0 108.0 131.0 134.0 85.0 85.0 108.0 133.0 146.0 83.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.52 (1.0%) stddev-sans-first:0.50 11:17:41 INFO - PROCESS | 2212 | Values: 53.0 54.0 54.0 54.0 53.0 54.0 53.0 54.0 54.0 53.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:4.14 (5.8%) stddev-sans-first:3.92 11:17:41 INFO - PROCESS | 2212 | Values: 66.0 69.0 81.0 75.0 72.0 71.0 69.0 69.0 70.0 71.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.50 stddev:1.64 (1.9%) stddev-sans-first:1.73 11:17:41 INFO - PROCESS | 2212 | Values: 84.0 85.0 82.0 83.0 85.0 82.0 85.0 84.0 86.0 81.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:148.50 stddev:19.36 (13.0%) stddev-sans-first:20.17 11:17:41 INFO - PROCESS | 2212 | Values: 133.0 150.0 149.0 148.0 128.0 137.0 127.0 117.0 184.0 160.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:163.50 stddev:1.66 (1.0%) stddev-sans-first:1.76 11:17:41 INFO - PROCESS | 2212 | Values: 163.0 165.0 164.0 164.0 163.0 162.0 164.0 163.0 162.0 159.0 11:17:41 INFO - PROCESS | 2212 | 11:17:41 INFO - PROCESS | 2212 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:73.50 stddev:9.66 (13.1%) stddev-sans-first:9.87 11:17:41 INFO - PROCESS | 2212 | Values: 67.0 67.0 67.0 68.0 76.0 86.0 89.0 88.0 71.0 65.0 11:17:41 INFO - PROCESS | 2212 | -------- Summary: end -------- 11:17:41 INFO - PROCESS | 2212 | 11:17:42 INFO - TEST-INFO | 2212: exit 0 11:17:42 INFO - TEST-OK | kraken | took 59721ms 11:17:42 INFO - SUITE-END | took 1176s 11:17:43 INFO - Completed test suite (00:19:36) 11:17:43 INFO - javascript benchmark 11:17:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2858.9285267868313, 3275.737940964723, 3309.6193937697158, 3322.750793690473, 3384.5261663367132, 5575.080111781562, 5700.087985922253, 5715.057015592432, 5764.971175144124, 5780.306363236407, 5714.028615135427, 5768.942310576904, 5790.36306006339, 5844.912326315109, 5912.911306330419, 5144.0740666679985, 5185.24813901984, 5237.3715154181555, 5270.156774916014, 5279.128943724281, 5171.7672704728375, 5285.973570132144, 5298.947010529903, 5358.196270559428, 5367.1680889462, 5269.156934890438, 5302.893942121155, 5303.284056652341, 5318.494742999409, 5347.171188465774, 5111.488851114858, 5138.254953031789, 5153.510416510426, 5176.715280659562, 5214.895702085975, 5143.3570803649545, 5151.7681704323395, 5163.328767260232, 5166.819161329373, 5171.482851714836, 5722.456366645204, 5777.653340799566, 5830.242068531106, 5830.5044071254, 5844.766209351621, 6119.694015299262, 6148.200733904564, 6182.752689892399, 6199.0, 6290.0, 5578.748956296936, 6246.718897649616, 6294.181756371642, 6323.399277068671, 6342.175517182783, 5829.533637309005, 5855.473007429352, 5875.030619947746, 5878.470937615592, 5901.380355062743, 6247.468965137923, 6317.178766760292, 6333.841653958596, 6334.493240540745, 6334.714937827809, 6059.606125601822, 6089.821881683172, 6093.254324534637, 6147.538934579924, 6162.198914141222, 6188.566800324024, 6246.593971391936, 6270.31026587075, 6277.058441233851, 6293.905591416133, 6052.061930400795, 6131.448169664752, 6233.719482623293, 6241.21984751906, 6242.563020588515, 6099.76379046824, 6167.383261673796, 6241.126242326078, 6245.6252624842655, 6277.058441233851, 4924.0, 4940.530649588355, 4943.233798761198, 4944.1100601891285, 4993.80024799004, 4438.245498265303, 4444.488883778342, 4462.553744625577, 4477.507474177838, 4483.574060464251, 5606.6636001840025, 5677.943220567824, 5693.259876216065, 5741.0, 5752.453516915886, 6154.261488621394, 6245.0, 6287.371262873768, 6302.8424289392215, 6322.778702745381, 5762.078067509178, 5766.048601980626, 5774.624649397776, 5782.624129431658, 5841.065429531254, 4840.080384726891, 5379.731013449312, 5430.56555475561, 5457.699826509501, 5459.972700136554, 6268.811935641938, 6360.363963603602, 6361.491080713625, 6384.904226436607, 6386.680665966683, 6174.66039367839, 6284.057391391328, 6309.211348581427, 6318.08387783778, 6348.079528468254, 6249.843753906189, 6257.843553911189, 6272.059191121369, 6393.552451328363, 6419.390157934989, 6205.906911396423, 6233.189685340877, 6238.937610623836, 6243.843903902439, 6253.656048917172, 6132.938670613236, 6285.685715714105, 6311.084892690443, 6334.968325158345, 6336.651484168231, 5921.496672782861, 5921.6743079131065, 6022.036474164112, 6074.848128796815, 6098.664573448503, 5925.703714814328, 6032.638041717511, 6080.0575910733905, 6095.664738439428, 6127.387261273837, 5936.436038576328, 5962.463378295801, 5968.283805943314, 5968.671723055274, 6023.27720673522, 5839.854003649943, 6042.395760423922, 6065.7573697053385, 6106.175666284995, 6136.907946380898, 5924.407559244041, 5935.703214839327, 5941.287045554561, 5946.07835785454, 5976.0], "value": 5710.966299978052, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.21654295968347, 508.8727818045489, 527.9546497934087, 530.0194639916156, 556.3574071946902, 27867.860660696693, 42789.0, 43753.343699844525, 43777.12445751091, 46178.0, 45503.77248113763, 45980.0, 46021.30968035482, 46094.0781184377, 46193.0, 8890.155435233642, 9207.26341892647, 9401.777768890035, 9836.262280328969, 10056.19550435967, 8848.646054157825, 10177.84733229002, 12082.33547154906, 12275.079369047313, 12457.563985260518, 12288.5699000535, 12356.196847204948, 12358.0, 12361.752764944697, 12393.814092788616, 8865.423747456394, 9071.63713451461, 12163.817542736866, 12329.445174967148, 12353.135280530412, 9051.094890510962, 9117.863232051524, 11817.054635629129, 12142.696432589168, 12149.878501214964, 43013.56986430127, 43407.13185736268, 43434.34848477275, 43453.34819977703, 43511.78244108791, 3575.249197668493, 3693.981530092359, 3698.2418604186078, 3835.846566137351, 3982.522097348337, 32228.677713223038, 32949.01152965415, 33289.500657490156, 33344.33311333784, 33692.98921032373, 3256.544083828266, 3299.2307082242805, 3310.817905015223, 3350.2461946062185, 3407.16524451511, 536453.0, 536578.0, 538236.0, 538242.0, 539376.0, 60134.69932650309, 60814.99999999911, 60825.0, 60947.0, 61194.694026529585, 68832.6558367215, 68844.96732549016, 69030.65484672645, 69045.0, 69108.30891691019, 16877.91561042187, 17119.0, 17147.228374723167, 17151.970881747136, 17183.914080429517, 11941.820872686916, 12190.81713774294, 12844.550440734529, 13046.282454465096, 13106.80339794904, 18142.727859082126, 18218.271269149085, 18249.543761406072, 18251.0, 18434.17046232896, 5441.830006548611, 6527.771527996498, 6542.738290468423, 6554.442872355808, 6561.606303621798, 777.793884620576, 920.5535315372039, 1005.3013155856755, 1067.524951396621, 1131.3801546583752, 26067.869660651577, 30438.69561304403, 30456.0, 31187.688123118478, 31882.88409905642, 1015.6851376073441, 1040.9687709368732, 1049.2812423489934, 1072.9785404292027, 1104.2877344113, 13362.465501380031, 16219.351225951066, 16259.918700406422, 16261.837381626268, 16316.67366652684, 678804.0, 685113.0, 689803.0, 690101.0, 690880.0, 63299.0, 63329.36670633327, 63457.73084538284, 63465.365346346865, 63507.047394289126, 1258.5028913579085, 1393.776995680666, 1411.7529432349297, 1430.5851303121979, 1446.8697817196503, 32281.35437291288, 32293.51559726653, 32334.029979100666, 32610.67389326171, 33003.17492062718, 53386.73306633442, 53453.0, 54187.45812541824, 54312.72843635757, 54319.72840135774, 456313.0, 458338.0, 459234.40765591915, 460030.0, 462575.0, 36392.45411318779, 43177.35233971556, 43993.560064400226, 44054.11891762083, 44463.77768111139, 49493.01013979772, 49675.0, 50299.74850125726, 50677.49322506728, 50742.98514029773, 34555.65444345524, 38908.832735018, 41506.58493415027, 41839.05058038963, 41943.370849437895, 40929.18141637091, 40949.0, 41049.0, 41804.79097604614, 42095.36856947087, 39305.41041884317, 39813.80093099516, 40045.39931900966, 40369.59630403658, 42453.0, 32970.835145824116, 35553.644463555036, 35725.0, 36179.27641447104, 36325.72859949889, 21288.574228515034, 23110.653340199573, 25823.741762582886, 26596.0, 26723.866380667972], "value": 21069.78264304451, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.16750889200415, 378.9488419063426, 388.7598560591713, 395.46019683132516, 415.81288420210893, 15598.298076586552, 17101.82898171018, 21878.90605469726, 23284.76715232847, 23290.951907164177, 21228.469288267803, 21428.678569821466, 22275.554488910213, 22425.439364015907, 22431.663525047097, 5860.560457965648, 5913.113033045056, 6123.632582045069, 6169.136320915076, 6169.2596888373455, 5974.4921681657115, 6061.757529698807, 6090.847728806793, 6093.695315234243, 6113.663748493831, 5730.4269573042575, 6019.909701354483, 6037.366076561963, 6038.849028774272, 6040.426159514839, 5979.521638268928, 6048.939510604926, 6063.272407311151, 6069.787557435513, 6082.118092876531, 5056.519630635084, 5249.2388603652635, 5252.71110088945, 5253.973730131325, 5273.34083239595, 26994.865025674942, 27169.864150679317, 27185.0, 27222.31944201391, 27227.45545089087, 2416.093964763214, 2436.1351720139273, 2477.5664258754646, 2920.9707902920914, 3231.725303349218, 15523.456679016293, 15933.362665493367, 16142.515724528283, 16263.918680406641, 16836.83163168377, 1802.927882884683, 1886.3209244671907, 1887.962240755191, 1906.8569857260761, 1948.7430607258382, 126527.36736316352, 128066.07900881587, 128994.3550282252, 129322.3533882334, 129609.0, 9844.50777461124, 10461.320014199053, 10704.0, 11093.223474356879, 11249.268797528135, 12968.610941671765, 13056.281904495345, 13075.869241307466, 13099.345032748515, 13102.0, 7422.888656670154, 8912.777180570409, 8932.598033088527, 8979.865302020475, 8994.550272486349, 7759.922400776033, 8187.83624327522, 8788.91211087881, 8967.578753736454, 9267.0, 7029.507934444539, 7070.399016083587, 7100.218975912646, 7105.218425973138, 7115.438442160818, 1739.1825841854309, 1808.032702504154, 1885.9057047147587, 1892.5302480263113, 1892.5741708115838, 828.6022709099545, 843.9915600844035, 870.3341943413294, 936.9672061477952, 1031.5254982707888, 22654.773452265596, 23876.522469550513, 24385.512289754104, 24397.634035489482, 24594.0, 952.3714348530076, 976.345848281653, 989.851522271665, 1002.2132625888788, 1032.0866033560353, 12386.81419778704, 12506.999440044774, 12685.93657031709, 12770.425330860133, 12787.296698681661, 609321.0, 620417.7958220451, 622281.8885905632, 630967.0, 630992.365648925, 140227.0, 141709.29145354207, 147606.5239347593, 148144.0, 152604.0, 982.3761911186306, 1002.8245057115122, 1005.3415013166242, 1018.8522664213648, 1020.3724709303725, 18771.15529801135, 20565.97170141517, 20581.58836823285, 20625.0, 20634.96825158766, 57001.429985701274, 59747.40252597418, 60397.0, 60494.79010419679, 61597.38402616096, 88048.5597572008, 89442.55278723825, 90304.09695902957, 90492.64261035959, 91837.54081229551, 8082.353411727166, 8170.264676179172, 8220.095789463274, 8366.08086812555, 8419.873701894354, 13100.0, 13250.00624953113, 13372.398242079324, 13592.252426116658, 13637.795433068313, 9671.274654400808, 10856.620018299322, 11539.653810385702, 11606.12954028468, 11718.296902185895, 9548.952255238679, 11170.994610485095, 11352.716182095513, 11417.60038398652, 11572.421378931189, 11476.598319059127, 11535.192536522363, 11581.420928953687, 11822.290662560274, 11960.401979901144, 8143.389245806469, 8236.001331432253, 8264.297534709616, 8467.788305292417, 8472.279856212292, 10674.8932510677, 11658.53365865356, 11763.764724705628, 11773.941130294294, 11814.94092529532], "value": 10179.303036835494, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.8558986896242, 1568.6392129810142, 1734.4969958711974, 1855.7401963725072, 1899.559066599419, 8348.624311905964, 8538.658453661854, 8976.820463590733, 8980.955095224532, 8989.190972812445, 7884.960575197116, 8166.6325015374305, 8184.181581841813, 8240.629171687273, 8758.956205218965, 1873.681474149398, 2147.5919575280686, 2178.814800741939, 2397.5444665513496, 2403.170906037414, 2369.834111612188, 2383.833131680783, 2396.3290278721897, 2398.4603464220586, 2401.5076909233567, 2105.5999360121527, 2388.4625959159225, 2389.9880500597474, 2395.6646069550275, 2397.6763136976547, 2309.0, 2391.928242152738, 2394.8682822444757, 2398.3524448399007, 2408.1330720940446, 2360.8583484990954, 2378.369732021016, 2378.4767351182713, 2383.07060246504, 2395.2814155753204, 8987.505687187202, 9064.365494415393, 9066.093390660948, 9076.773080673036, 9080.682176123804, 5301.946980530184, 5391.81128660499, 5432.6468779529205, 5434.3207099112615, 5445.047116754592, 3063.5557844112477, 3595.6404359563835, 3663.725220608465, 3714.257148570297, 3729.4219395993664, 1051.4900273367412, 1091.8908109189097, 1094.2176343914139, 1102.200904344352, 1104.282216559235, 11927.821082683766, 11944.761104777856, 11952.820707689392, 11959.820602690967, 11973.161878668498, 4687.1328804171335, 4703.647226457961, 4890.217565189552, 4898.926516102261, 4903.313536104948, 10156.136728378171, 10276.023777741102, 10277.126444252173, 10296.382217067, 10327.173826093895, 5448.83653490396, 5481.725913704299, 5481.75332110048, 5486.0125177468435, 5495.642783219078, 4216.536181020086, 4886.267059941038, 4995.425526064477, 5009.323741294878, 5014.774335154853, 3419.3245309798517, 3966.186931678999, 4060.3706425504092, 4111.1366613011605, 4124.13393187434, 1281.5258354408743, 1292.2182079841748, 1294.0618051912477, 1303.00320255005, 1306.1314226039576, 903.9276857851356, 906.6146887572755, 916.8441364967971, 925.0519161789623, 939.1876027236527, 5884.411558844081, 5942.6731529766275, 5975.52195824333, 6090.725917333731, 6125.540584456184, 835.1606635331574, 878.0122362342365, 878.486085639895, 941.449252187478, 945.1871390604075, 3991.7804520751056, 4020.9396859047138, 4034.3545032794614, 4065.329220678614, 4117.814698338582, 8630.834837296885, 9362.06379362072, 10806.08148307387, 10981.286216395909, 10986.230963832455, 10979.341239525655, 10987.61543345995, 10995.615153469751, 11000.174986876016, 11062.612808551663, 7873.448858579844, 8309.542975136426, 8373.874391884126, 8403.621837017348, 8426.957865210634, 6054.424829641177, 6128.693565321805, 6226.439620434183, 6262.68686565679, 6264.404881536247, 8395.538245396561, 9972.401655900669, 10098.747531311776, 10357.689269321932, 10357.792844143225, 9665.806683866143, 10297.227707921786, 10551.103156231806, 10558.736031599272, 10583.0, 5790.826275211751, 7705.42209334291, 7798.0, 7882.921170788219, 7895.960520197362, 6144.508439324933, 7979.281864632211, 7983.72056978003, 8492.023417306842, 8592.398532102943, 7586.696532138652, 7656.923430765621, 7665.003549538525, 7695.6536955836, 7798.220177982384, 7564.130125035471, 7604.353629941517, 7605.809854753676, 7724.420668450001, 7740.883886741816, 6970.745265851992, 7195.532290401107, 7200.063991681258, 7201.603911784692, 7202.135743710788, 5015.423226328873, 5094.337736094284, 5117.8976420470635, 5127.128388174094, 5223.634345595924, 4256.743925719374, 4318.41701370311, 5164.0, 5207.609429292743, 5307.548858347083], "value": 4630.576227102914, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.7660977656981, 309.330299900715, 315.2025375799227, 318.3426224845695, 320.70975766930906, 4536.7051141675765, 5157.561607263382, 5404.810831620894, 5485.4788795064505, 5520.779168833242, 4426.44669416323, 4542.228530872962, 5378.677279363231, 5417.566594672427, 5461.371942226645, 3470.3406352793013, 3892.552356479013, 3932.370820668694, 4091.734037287567, 4121.083696081321, 3366.9158271043298, 4033.8503526494956, 4109.73964319623, 4129.359949207863, 4172.624463798257, 3732.682721968636, 4087.816048277835, 4126.216018956388, 4130.938035929464, 4140.937885931713, 3352.580927384077, 3547.1486843157713, 3881.204353107606, 4062.512498500184, 4087.5299340575916, 2849.9145025649054, 3545.6099829018785, 3549.7337699672626, 3550.8757193498363, 3563.251717139404, 5126.026055049529, 5138.922916156261, 5193.428722840485, 5234.555062819627, 5282.603804714623, 4236.152769446123, 4586.7477288749105, 4753.26324419712, 4760.0, 4774.069056533977, 4435.090806384684, 5254.320296648699, 5321.973390133025, 5616.0171969905105, 5706.887157004386, 3968.325384684582, 3999.0, 4004.699647526418, 4011.458453108822, 4019.196160767858, 5020.573251273646, 5361.919571206435, 5623.606347555632, 5927.8221653350465, 5984.521238300926, 3848.0956975110826, 3863.2852922208917, 3886.7084968627464, 3889.9027524311555, 3893.3575959966856, 3300.1124016485574, 3654.250878569887, 3966.4446977423186, 3987.7009224308295, 4007.975972134054, 4122.56713045132, 4341.674374421931, 4394.846180383671, 4650.697704649187, 4683.99294151759, 4501.704130893268, 5685.573581981368, 5723.055695810228, 5774.354309021908, 5820.76716931318, 1269.7079671675567, 1338.4980632262902, 1369.5822774054009, 1386.861313868605, 1424.1170474305789, 798.5248776977689, 826.0175578611381, 841.2638940926689, 841.9536925469158, 845.5476320168681, 4280.828766849353, 5181.875431073242, 5189.4032186299055, 5235.188545775411, 5369.3556773187465, 4809.879753006133, 5016.949830501721, 5333.733313334318, 5521.861953451196, 5654.558087687696, 5164.406093299243, 5343.305370301913, 5356.143017117242, 5357.946420535823, 5386.461353864661, 2483.341914392687, 2490.8007359411204, 3040.863161157753, 3062.1627023785995, 3077.753779697619, 4696.694714843524, 5431.6740995540395, 6014.578979471483, 6019.518438524908, 6021.969890150609, 4364.476262848479, 5203.687778733212, 5485.177223416519, 6004.609700369462, 6010.459058684739, 5296.602754793406, 5667.348254950651, 6058.303674970927, 6063.757449702139, 6079.574429789872, 5343.572514198932, 5745.224394706828, 5811.796587119599, 5835.503982161564, 5869.266341707286, 5079.390473143247, 5231.476852314738, 5701.315842098975, 5769.720960220773, 5784.351459834095, 5253.894922101613, 5476.972615136899, 5703.315602127771, 6060.181875446759, 6063.696815159136, 3185.2833112549492, 3623.0, 3835.600005997735, 3864.0146762576487, 3932.5870783568457, 5771.307443106854, 5822.214001109796, 5834.299884013945, 5838.4453476919625, 5843.94156058451, 4997.325361076353, 5188.09208388523, 5319.867003324948, 5669.291338582677, 5674.77300907959, 5381.13901775714, 5476.946145195551, 5583.330000399978, 5676.205331253545, 5698.772049118154, 4892.608591312757, 5321.935612877517, 5516.227728117987, 5532.197831314356, 5622.072358060874, 4609.631229501565, 4806.110869489016, 5522.217281162417, 5699.34457537372, 5762.4525670061275, 4401.66987475947, 4836.95163048365, 5594.972025140011, 5603.075492543685, 5617.7191140442], "value": 4076.4489372684943, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.3096635612059, 764.9158592554821, 769.9961500192499, 801.2908575910319, 826.8057006603448, 15224.923875380622, 15783.210839458025, 15795.84204157958, 15798.921005394972, 15838.0, 14385.856141438582, 14642.41430342785, 14740.484083057096, 14775.335109920052, 14838.629034274149, 711.9003339532456, 730.5689643110563, 732.4177279063158, 732.5201992694767, 739.8557281330143, 732.5860888597925, 754.1365136918253, 891.3493150000503, 899.2221728205121, 907.2560500389683, 929.9674511392103, 939.178219058324, 942.0297093993198, 945.3618807305076, 946.9195118414908, 940.8635747816528, 941.0636416765294, 941.7410212191634, 943.7216021273706, 946.5551190940316, 561.3824792728008, 685.0888318536385, 708.3837061756253, 710.5417006031132, 712.6828561290228, 15145.469908553148, 15159.545213643498, 15194.316255768517, 15199.316030778642, 15210.695786084327, 14105.36525856339, 14429.49496767608, 14430.639234019234, 14493.492727754583, 14757.114573125647, 12990.610281691565, 13029.609111726664, 13208.009399295091, 13292.667683307995, 13549.254790986492, 4596.057808149321, 4604.0, 4633.212353899811, 4705.435347758291, 4784.832530861438, 107023.46488267509, 107153.92846071518, 109735.45132274367, 111130.88869111367, 112350.43824780824, 11432.428378581038, 11444.084473242121, 11492.540298388138, 11610.477528511237, 11618.88381116195, 18537.35119270819, 22988.65517017246, 23048.07807687707, 23060.538789224123, 23439.41401464977, 7875.251851074138, 7922.643481043367, 7935.841283174304, 8150.470219435836, 8174.918250817415, 4384.145091707151, 4436.578525040116, 4536.251518499476, 4663.580277775017, 4747.525247475225, 3617.94573081404, 3772.905677358088, 3783.8648405478248, 3796.7152463565344, 3801.82891769871, 674.4772801079203, 688.7830333444949, 693.0262980512388, 700.3486757315643, 705.9611721355272, 606.4269265544107, 674.544682339419, 693.5665209244222, 701.5089437393844, 710.6127160697432, 8892.466452012899, 9480.24158067353, 9489.857652135224, 9497.857532137023, 9548.283878708986, 584.2790254741677, 619.4982064527746, 654.8461111638761, 667.5827607745159, 702.455596912397, 4530.546945305443, 4577.908441831145, 4585.312203169551, 4656.580907718254, 4692.554207350297, 154487.0, 154650.0, 156469.217653911, 156714.0, 157396.21301893645, 101985.0, 104628.90742185267, 106432.0, 132362.0, 134562.0, 103109.96890031153, 103497.48251258695, 107907.0, 109751.0, 110867.0, 9575.90424095778, 9839.803203936024, 9961.39648345306, 10046.849297260693, 10081.747956301151, 48572.0, 58178.7091064542, 60595.39404605897, 60744.48138796566, 61743.765124698155, 77529.0, 93465.06534934563, 94058.0, 94153.0, 98034.03931921464, 13099.738005239651, 13288.468461261828, 13371.899429957279, 13414.12808167466, 13794.172349659053, 14812.851871481578, 14933.253337333308, 15016.0, 15188.772168417261, 15449.5365139046, 13968.860311396757, 14048.367823448174, 14061.156330620195, 14081.577552673436, 14530.128192308495, 12800.423980921067, 13787.862121378657, 13873.0, 13969.51106711301, 14080.295985200904, 13557.728845423233, 13600.115992460458, 13718.38267277955, 14190.077644953046, 14317.498887538886, 11380.260283081574, 11444.713882153013, 11446.713832154262, 11819.349935753273, 11847.229930054518, 11329.263597866113, 11395.829062564235, 11435.313881167156, 11550.537978480766, 11617.767644647229], "value": 8004.456685402085, "unit": "score"}], "name": "dromaeo_css", "value": 7549.141276123854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 112.0, 112.0, 114.0, 111.0, 114.0, 111.0, 112.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 109.0, 199.0, 141.0, 121.0, 125.0, 110.0, 177.0, 137.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 186.0, 185.0, 184.0, 187.0, 224.0, 231.0, 181.0], "value": 192.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 58.0, 111.0, 57.0, 58.0, 60.0, 69.0, 83.0, 61.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 94.0, 70.0, 70.0, 68.0, 96.0, 70.0, 67.0, 98.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 91.0, 87.0, 87.0, 84.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 108.0, 131.0, 134.0, 85.0, 85.0, 108.0, 133.0, 146.0, 83.0], "value": 109.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 53.0, 54.0, 53.0, 54.0, 54.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 81.0, 75.0, 72.0, 71.0, 69.0, 69.0, 70.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 82.0, 83.0, 85.0, 82.0, 85.0, 84.0, 86.0, 81.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 150.0, 149.0, 148.0, 128.0, 137.0, 127.0, 117.0, 184.0, 160.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 164.0, 163.0, 162.0, 164.0, 163.0, 162.0, 159.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 76.0, 86.0, 89.0, 88.0, 71.0, 65.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1471.4, "alertThreshold": 2.0}]} 11:17:43 INFO - Return code: 0 11:17:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:17:43 INFO - # TBPL SUCCESS # 11:17:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:17:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:17:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:17:43 INFO - 2016-07-06 11:17:43.251 screenresolution[2216:17570] starting screenresolution argv=screenresolution get 11:17:43 INFO - 2016-07-06 11:17:43.254 screenresolution[2216:17570] Display 0: 1600x1200x32@60 11:17:43 INFO - 2016-07-06 11:17:43.260 screenresolution[2217:17573] starting screenresolution argv=screenresolution list 11:17:43 INFO - Available Modes on Display 0 11:17:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:17:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:17:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:17:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:17:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:17:43 INFO - Intel Iris: 11:17:43 INFO - Chipset Model: Intel Iris 11:17:43 INFO - Type: GPU 11:17:43 INFO - Bus: Built-In 11:17:43 INFO - VRAM (Dynamic, Max): 1536 MB 11:17:43 INFO - Vendor: Intel (0x8086) 11:17:43 INFO - Device ID: 0x0a2e 11:17:43 INFO - Revision ID: 0x0009 11:17:43 INFO - Displays: 11:17:43 INFO - Display: 11:17:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:17:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:17:43 INFO - Display Serial Number: 1600x1200 60 11:17:43 INFO - Main Display: Yes 11:17:43 INFO - Mirror: Off 11:17:43 INFO - Online: Yes 11:17:43 INFO - Rotation: Supported 11:17:43 INFO - Return code: 0 11:17:43 INFO - Running post-action listener: _resource_record_post_action 11:17:43 INFO - ##### 11:17:43 INFO - ##### Finished run-tests step (success) 11:17:43 INFO - ##### 11:17:43 INFO - Running post-run listener: _resource_record_post_run 11:17:43 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 204800; Write bytes: 700482560; Read time: 5; Write time: 2235 11:17:43 INFO - TinderboxPrint: CPU usage
53.2% 11:17:43 INFO - TinderboxPrint: I/O read bytes / time
204,800 / 5 11:17:43 INFO - TinderboxPrint: I/O write bytes / time
700,482,560 / 2,235 11:17:43 INFO - TinderboxPrint: CPU idle
2,265.9 (46.8%) 11:17:43 INFO - TinderboxPrint: CPU system
963.7 (19.9%) 11:17:43 INFO - TinderboxPrint: CPU user
1,616.0 (33.4%) 11:17:43 INFO - TinderboxPrint: Swap in / out
406,011,904 / 0 11:17:43 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387725824; Write bytes: 381351424; Read time: 28560; Write time: 911 11:17:43 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 204800; Write bytes: 291905024; Read time: 5; Write time: 1268 11:17:43 INFO - Running post-run listener: _upload_blobber_files 11:17:43 INFO - Blob upload gear active. 11:17:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:17:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:17:44 INFO - (blobuploader) - INFO - Open directory for files ... 11:17:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:17:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:17:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:45 INFO - (blobuploader) - INFO - Done attempting. 11:17:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:17:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:17:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:46 INFO - (blobuploader) - INFO - Done attempting. 11:17:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:17:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:17:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:48 INFO - (blobuploader) - INFO - Done attempting. 11:17:48 INFO - (blobuploader) - INFO - Iteration through files over. 11:17:48 INFO - Return code: 0 11:17:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:17:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:17:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d704aeeb46e71d4b31240007243a96ec52be726483a6d8a22121f1d39a3ad9459b5398ef168c33fafabfe4fbe31834bfe2b7bd0d6a03c86e8a1ccbaf2df0bd69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/957e55986576dafc908577b73f884904dce4fe9b745914cc029e2091490a9b6d8812e96d02f76c5fafa41467d7f71b401ef321659f1cca1546bc5c72f47a3771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d8f65bc2a85622f3cebe4cf2a572c03e8b3f84694bbf38e7bee80d68db58774b40a5077f3cb163b0b57a5a2ed18234e8459077f7d4a6950b806e140ad312f5e"} 11:17:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:17:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:17:48 INFO - Contents: 11:17:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d704aeeb46e71d4b31240007243a96ec52be726483a6d8a22121f1d39a3ad9459b5398ef168c33fafabfe4fbe31834bfe2b7bd0d6a03c86e8a1ccbaf2df0bd69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/957e55986576dafc908577b73f884904dce4fe9b745914cc029e2091490a9b6d8812e96d02f76c5fafa41467d7f71b401ef321659f1cca1546bc5c72f47a3771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d8f65bc2a85622f3cebe4cf2a572c03e8b3f84694bbf38e7bee80d68db58774b40a5077f3cb163b0b57a5a2ed18234e8459077f7d4a6950b806e140ad312f5e"} 11:17:48 INFO - Running post-run listener: copy_logs_to_upload_dir 11:17:48 INFO - Copying logs to upload dir... 11:17:48 INFO - mkdir: /builds/slave/test/build/upload/logs 11:17:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.463606 ========= master_lag: 4.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-06 11:17:52.764828) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 11:17:52.768171) ========= 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.rb13UVpdNM/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.zrjpqDyTvU/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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/d704aeeb46e71d4b31240007243a96ec52be726483a6d8a22121f1d39a3ad9459b5398ef168c33fafabfe4fbe31834bfe2b7bd0d6a03c86e8a1ccbaf2df0bd69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/957e55986576dafc908577b73f884904dce4fe9b745914cc029e2091490a9b6d8812e96d02f76c5fafa41467d7f71b401ef321659f1cca1546bc5c72f47a3771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d8f65bc2a85622f3cebe4cf2a572c03e8b3f84694bbf38e7bee80d68db58774b40a5077f3cb163b0b57a5a2ed18234e8459077f7d4a6950b806e140ad312f5e"} build_url:https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011127 build_url: 'https://queue.taskcluster.net/v1/task/frx5enZeQsqjwgI1kPyLzw/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/d704aeeb46e71d4b31240007243a96ec52be726483a6d8a22121f1d39a3ad9459b5398ef168c33fafabfe4fbe31834bfe2b7bd0d6a03c86e8a1ccbaf2df0bd69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/957e55986576dafc908577b73f884904dce4fe9b745914cc029e2091490a9b6d8812e96d02f76c5fafa41467d7f71b401ef321659f1cca1546bc5c72f47a3771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d8f65bc2a85622f3cebe4cf2a572c03e8b3f84694bbf38e7bee80d68db58774b40a5077f3cb163b0b57a5a2ed18234e8459077f7d4a6950b806e140ad312f5e"}' ========= master_lag: 0.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 11:17:53.432988) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:17:53.433302) ========= 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.rb13UVpdNM/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.zrjpqDyTvU/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.004490 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:17:53.509325) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 11:17:53.509640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 11:17:53.510027) ========= ========= Total master_lag: 4.81 =========