[17:51:18,073 DEBUG] JetspeedRunDataService: storing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:18,073 DEBUG] SELECT TURBINE_USER.USER_ID, TURBINE_USER.LOGIN_NAME, TURBINE_USER.PASSWORD_VALUE, TURBINE_USER.FIRST_NAME, TURBINE_USER.LAST_NAME, TURBINE_USER.EMAIL, TURBINE_USER.CONFIRM_VALUE, TURBINE_USER.MODIFIED, TURBINE_USER.CREATED, TURBINE_USER.LAST_LOGIN, TURBINE_USER.DISABLED, TURBINE_USER.OBJECTDATA FROM TURBINE_USER WHERE (TURBINE_USER.LOGIN_NAME='anon')
[17:51:19,775 DEBUG] Elapsed time=170 ms
[17:51:19,875 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:19,875 DEBUG] SELECT TURBINE_USER.USER_ID, TURBINE_USER.LOGIN_NAME, TURBINE_USER.PASSWORD_VALUE, TURBINE_USER.FIRST_NAME, TURBINE_USER.LAST_NAME, TURBINE_USER.EMAIL, TURBINE_USER.CONFIRM_VALUE, TURBINE_USER.MODIFIED, TURBINE_USER.CREATED, TURBINE_USER.LAST_LOGIN, TURBINE_USER.DISABLED, TURBINE_USER.OBJECTDATA FROM TURBINE_USER WHERE (TURBINE_USER.LOGIN_NAME='turbine')
[17:51:20,025 DEBUG] Elapsed time=150 ms
[17:51:20,266 DEBUG] Profiler fallback called with: BaseProfile[user/turbine/en/US/html/default.psml]
[17:51:20,266 INFO ] Returning locator string: User:turbine$Page:default.psml$MediaType:html$Country:US$Language:en
[17:51:20,266 INFO ] Returning locator string: User:turbine$Page:default.psml$MediaType:html$Language:en
[17:51:20,266 INFO ] Returning locator string: User:turbine$Page:default.psml$MediaType:html
[17:51:20,266 DEBUG] JetspeedVelocityPage: Setting type to: text/html; charset=UTF-8
[17:51:20,266 DEBUG] Action: building action context
[17:51:20,266 DEBUG] Action: try executing events
[17:51:20,276 DEBUG] JetspeedTemplatePage: requested template = Home.vm
[17:51:20,276 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\screens\html\Home.vm returning /html/Home.vm
[17:51:20,276 DEBUG] JetspeedTemplatePage: calculated template = /html/Home.vm
[17:51:20,286 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\layouts\html\default.vm returning /html/default.vm
[17:51:20,286 DEBUG] JetspeedTemplatePage: layoutTemplate is finally /html/default.vm
[17:51:20,957 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\navigations\html\top.vm returning /html/top.vm
[17:51:21,197 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\navigations\html\left.vm returning /html/left.vm
[17:51:21,457 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,457 INFO ] cache miss, object expired: -1194991042-425089013|_showtitlebar-false
[17:51:21,457 DEBUG] PortletFactory.getPortlet(): constructed in 0 ms - handle: -1194991042-425089013|_showtitlebar-false
[17:51:21,457 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,457 INFO ] cache miss, object expired: -1653569551user/turbine/html/default.psml/04
[17:51:21,457 DEBUG] PortletFactory.getPortlet(): constructed in 0 ms - handle: -1653569551user/turbine/html/default.psml/04
[17:51:21,457 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,457 INFO ] cache miss, object expired: 207822763352265325|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showdescription-true|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showtextinput-true|_display-normal|showtitle-true|openinpopup-false|itemdisplayed-15
[17:51:21,467 DEBUG] PortletFactory.getPortlet(): constructed in 10 ms - handle: 207822763352265325|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showdescription-true|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showtextinput-true|_display-normal|showtitle-true|openinpopup-false|itemdisplayed-15
[17:51:21,467 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,467 INFO ] cache miss, object expired: 207822763-712972069|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showdescription-true|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showtextinput-true|_display-normal|showtitle-true|openinpopup-false|itemdisplayed-15
[17:51:21,537 DEBUG] PortletFactory.getPortlet(): constructed in 70 ms - handle: 207822763-712972069|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showdescription-true|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showtextinput-true|_display-normal|showtitle-true|openinpopup-false|itemdisplayed-15
[17:51:21,537 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,537 INFO ] cache miss, object expired: 207822763-1393484904|showtitle-true|openinpopup-false|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showtextinput-true|itemdisplayed-15|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showdescription-true
[17:51:21,547 DEBUG] PortletFactory.getPortlet(): constructed in 10 ms - handle: 207822763-1393484904|showtitle-true|openinpopup-false|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showtextinput-true|itemdisplayed-15|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showdescription-true
[17:51:21,547 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,547 INFO ] cache miss, object expired: 2078227631908198891|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showdescription-true|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showtextinput-true|_display-minimized|showtitle-true|openinpopup-false|itemdisplayed-15
[17:51:21,547 DEBUG] PortletFactory.getPortlet(): constructed in 0 ms - handle: 2078227631908198891|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showdescription-true|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showtextinput-true|_display-minimized|showtitle-true|openinpopup-false|itemdisplayed-15
[17:51:21,547 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,547 INFO ] cache miss, object expired: 669557072user/turbine/html/default.psml/P-ef1547a162-10018
[17:51:21,547 DEBUG] PortletFactory.getPortlet(): constructed in 0 ms - handle: 669557072user/turbine/html/default.psml/P-ef1547a162-10018
[17:51:21,547 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,547 INFO ] cache miss, object expired: 2078227632107704176|showtitle-true|openinpopup-false|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showtextinput-true|itemdisplayed-15|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showdescription-true
[17:51:21,578 DEBUG] PortletFactory.getPortlet(): constructed in 31 ms - handle: 2078227632107704176|showtitle-true|openinpopup-false|stylesheet-/WEB-INF/xsl/rss.xsl|stylesheet.text/html-/WEB-INF/xsl/rss.xsl|showtextinput-true|itemdisplayed-15|stylesheet.text/vnd.wap.wml-/WEB-INF/xsl/rss-wml.xsl|showdescription-true
[17:51:21,578 DEBUG] JetspeedRunDataService: accessing rundata org.apache.jetspeed.services.rundata.DefaultJetspeedRunData@2f for thread: Thread[Thread-12,5,main]
[17:51:21,578 INFO ] cache miss, object expired: -1683332112|template-hello.jsp
[17:51:21,578 DEBUG] PortletFactory.getPortlet(): constructed in 0 ms - handle: -1683332112|template-hello.jsp
[17:51:21,578 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed-tab.vm returning /html/jetspeed-tab.vm
[17:51:21,608 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controllers\html\card.vm returning /html/card.vm
[17:51:21,608 DEBUG] Controller calculated setSize 9 row 3 colNum: 3 rowNum: 3
[17:51:21,608 DEBUG] Column 0
[17:51:21,608 DEBUG] Row 0 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@1678
[17:51:21,608 DEBUG] Row 1 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@1679
[17:51:21,608 DEBUG] Row 2 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@167a
[17:51:21,608 DEBUG] Column 1
[17:51:21,608 DEBUG] Row 0 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@167b
[17:51:21,608 DEBUG] Row 1 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@167c
[17:51:21,608 DEBUG] Row 2 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@167d
[17:51:21,608 DEBUG] Column 2
[17:51:21,608 DEBUG] Row 0 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@167e
[17:51:21,608 DEBUG] Row 1 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@167f
[17:51:21,608 DEBUG] Row 2 object: org.apache.jetspeed.portal.controls.VelocityPortletControl@1680
[17:51:21,608 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controllers\html\multicolumn.vm returning /html/multicolumn.vm
[17:51:21,638 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:21,678 DEBUG] checking for Portlet permission: view for portlet: LoggedInWelcome
[17:51:21,678 DEBUG] checking for Portlet permission: view for portlet: LoggedInWelcome
[17:51:21,678 DEBUG] checking for Portlet permission: view for portlet: LoggedInWelcome
[17:51:21,678 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:21,688 DEBUG] checking for Portlet permission: view for portlet: http://www.xmlhack.com/rsscat.php
[17:51:21,688 DEBUG] checking for Portlet permission: view for portlet: http://www.xmlhack.com/rsscat.php
[17:51:21,688 DEBUG] checking for Portlet permission: view for portlet: http://www.xmlhack.com/rsscat.php
[17:51:21,698 WARN ] Cache getEntry Called with /WEB-INF/xsl/rss.xsl
[17:51:21,698 INFO ] Returning local cached URL
[17:51:21,768 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:21,778 DEBUG] checking for Portlet permission: view for portlet: http://www.mozilla.org/news.rdf
[17:51:21,778 DEBUG] checking for Portlet permission: view for portlet: http://www.mozilla.org/news.rdf
[17:51:21,778 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:21,788 DEBUG] checking for Portlet permission: view for portlet: HelloVelocity
[17:51:21,788 DEBUG] checking for Portlet permission: view for portlet: HelloVelocity
[17:51:21,788 DEBUG] checking for Portlet permission: view for portlet: HelloVelocity
[17:51:21,788 DEBUG] VelocityPortlet::getVelocityContent
[17:51:21,788 DEBUG] VelocityPortlet found action portlets.HelloAction context org.apache.velocity.VelocityContext@16eb
[17:51:21,788 DEBUG] Action detected with action + context
[17:51:21,788 DEBUG] VelocityAction: retrieved context: org.apache.velocity.VelocityContext@16eb
[17:51:21,788 DEBUG] VelocityAction: retrieved portlet: org.apache.jetspeed.portal.portlets.CustomizerVelocityPortlet@16ec
[17:51:21,898 DEBUG] VelocityAction: building normal
[17:51:21,898 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\portlets\html\hello.vm returning /html/hello.vm
[17:51:21,898 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:21,938 DEBUG] checking for Portlet permission: view for portlet: http://jakarta.apache.org/jetspeed/channels/turbine.rss
[17:51:21,938 DEBUG] checking for Portlet permission: view for portlet: http://jakarta.apache.org/jetspeed/channels/turbine.rss
[17:51:21,938 DEBUG] checking for Portlet permission: view for portlet: http://jakarta.apache.org/jetspeed/channels/turbine.rss
[17:51:21,938 WARN ] Cache getEntry Called with /WEB-INF/xsl/rss.xsl
[17:51:21,938 INFO ] Returning local cached URL
[17:51:21,978 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:21,988 DEBUG] checking for Portlet permission: view for portlet: http://jakarta.apache.org/jetspeed/channels/jetspeed.rss
[17:51:21,988 DEBUG] checking for Portlet permission: view for portlet: http://jakarta.apache.org/jetspeed/channels/jetspeed.rss
[17:51:21,988 DEBUG] checking for Portlet permission: view for portlet: http://jakarta.apache.org/jetspeed/channels/jetspeed.rss
[17:51:21,988 WARN ] Cache getEntry Called with /WEB-INF/xsl/rss.xsl
[17:51:21,988 INFO ] Returning local cached URL
[17:51:22,098 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:22,128 DEBUG] checking for Portlet permission: view for portlet: DatabaseBrowserTest
[17:51:22,128 DEBUG] checking for Portlet permission: view for portlet: DatabaseBrowserTest
[17:51:22,128 DEBUG] checking for Portlet permission: view for portlet: DatabaseBrowserTest
[17:51:22,128 DEBUG] VelocityPortlet::getVelocityContent
[17:51:22,128 DEBUG] VelocityPortlet found action portlets.browser.DatabaseBrowserAction context org.apache.velocity.VelocityContext@173d
[17:51:22,128 DEBUG] Action detected with action + context
[17:51:22,128 DEBUG] VelocityAction: retrieved context: org.apache.velocity.VelocityContext@173d
[17:51:22,128 DEBUG] VelocityAction: retrieved portlet: org.apache.jetspeed.portal.portlets.browser.DatabaseBrowserPortlet@173e
[17:51:22,128 DEBUG] VelocityAction: building normal
[17:51:22,309 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\portlets\html\database-browser-portlet.vm returning /html/database-browser-portlet.vm
[17:51:22,389 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:22,399 DEBUG] checking for Portlet permission: view for portlet: http://barrapunto.com/barrapunto.rdf
[17:51:22,399 DEBUG] checking for Portlet permission: view for portlet: http://barrapunto.com/barrapunto.rdf
[17:51:22,399 DEBUG] checking for Portlet permission: view for portlet: http://barrapunto.com/barrapunto.rdf
[17:51:22,399 WARN ] Cache getEntry Called with /WEB-INF/xsl/rss.xsl
[17:51:22,399 INFO ] Returning local cached URL
[17:51:22,499 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\controls\html\jetspeed.vm returning /html/jetspeed.vm
[17:51:22,509 DEBUG] checking for Portlet permission: view for portlet: HelloJSP
[17:51:22,509 DEBUG] checking for Portlet permission: view for portlet: HelloJSP
[17:51:22,509 DEBUG] checking for Portlet permission: view for portlet: HelloJSP
[17:51:22,509 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\jsp\portlets\html\hello.jsp returning /html/hello.jsp
[17:51:22,619 DEBUG] template exists: D:\jetspeed-1.4b1\WEB-INF\templates\vm\navigations\html\bottom.vm returning /html/bottom.vm
[17:51:22,869 DEBUG] JetspeedRunDataService: releasing rundata for thread: Thread[Thread-12,5,main]