Sign up for news about OCLC’s interlibrary loan services.
(500)
The requested URL /content/mainoclc/canada/en_us/illiad/ordering.html resulted in an error in /libs/wcm/msm/components/ghost/ghost.jsp.
Exception:
org.apache.sling.api.SlingException: at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspExceptionInternal(JspServletWrapper.java:560) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspException(JspServletWrapper.java:496) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:448) at org.apache.sling.scripting.jsp.JspServletWrapperAdapter.service(JspServletWrapperAdapter.java:59) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.callJsp(JspScriptEngineFactory.java:173) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.access$100(JspScriptEngineFactory.java:84) at org.apache.sling.scripting.jsp.JspScriptEngineFactory$JspScriptEngine.eval(JspScriptEngineFactory.java:388) at org.apache.sling.scripting.core.impl.DefaultSlingScript.call(DefaultSlingScript.java:358) at org.apache.sling.scripting.core.impl.DefaultSlingScript.eval(DefaultSlingScript.java:170) at org.apache.sling.scripting.core.impl.DefaultSlingScript.service(DefaultSlingScript.java:456) at org.apache.sling.engine.impl.request.RequestData.service(RequestData.java:491) at org.apache.sling.engine.impl.filter.SlingComponentFilterChain.render(SlingComponentFilterChain.java:45) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:64) at com.day.cq.wcm.core.impl.WCMDebugFilter.doFilter(WCMDebugFilter.java:133) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.wcm.core.impl.WCMComponentFilter.filterRootInclude(WCMComponentFilter.java:308) at com.day.cq.wcm.core.impl.WCMComponentFilter.doFilter(WCMComponentFilter.java:141) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.processComponent(SlingRequestProcessorImpl.java:280) at org.apache.sling.engine.impl.filter.RequestSlingFilterChain.render(RequestSlingFilterChain.java:49) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:64) at com.day.cq.wcm.mobile.core.impl.redirect.RedirectFilter.doFilter(RedirectFilter.java:185) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.engine.impl.debug.RequestProgressTrackerLogFilter.doFilter(RequestProgressTrackerLogFilter.java:59) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.wcm.foundation.forms.impl.FormsHandlingServlet.doFilter(FormsHandlingServlet.java:220) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.theme.impl.ThemeResolverFilter.doFilter(ThemeResolverFilter.java:67) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.i18n.impl.I18NFilter.doFilter(I18NFilter.java:101) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.wcm.core.impl.WCMRequestFilter.doFilter(WCMRequestFilter.java:119) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.rewriter.impl.RewriterFilter.doFilter(RewriterFilter.java:84) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.portal.container.internal.request.PortalFilter.doFilter(PortalFilter.java:76) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.bgservlets.impl.BackgroundServletStarterFilter.doFilter(BackgroundServletStarterFilter.java:135) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.processRequest(SlingRequestProcessorImpl.java:171) at org.apache.sling.engine.impl.SlingMainServlet.service(SlingMainServlet.java:199) at org.apache.felix.http.base.internal.handler.ServletHandler.doHandle(ServletHandler.java:96) at org.apache.felix.http.base.internal.handler.ServletHandler.handle(ServletHandler.java:79) at org.apache.felix.http.base.internal.dispatch.ServletPipeline.handle(ServletPipeline.java:42) at org.apache.felix.http.base.internal.dispatch.InvocationFilterChain.doFilter(InvocationFilterChain.java:49) at org.apache.felix.http.base.internal.dispatch.HttpFilterChain.doFilter(HttpFilterChain.java:33) at org.apache.sling.security.impl.ReferrerFilter.doFilter(ReferrerFilter.java:249) at org.apache.felix.http.base.internal.handler.FilterHandler.doHandle(FilterHandler.java:88) at org.apache.felix.http.base.internal.handler.FilterHandler.handle(FilterHandler.java:76) at org.apache.felix.http.base.internal.dispatch.InvocationFilterChain.doFilter(InvocationFilterChain.java:47) at org.apache.felix.http.base.internal.dispatch.HttpFilterChain.doFilter(HttpFilterChain.java:33) at org.apache.felix.http.base.internal.dispatch.FilterPipeline.dispatch(FilterPipeline.java:48) at org.apache.felix.http.base.internal.dispatch.Dispatcher.dispatch(Dispatcher.java:39) at org.apache.felix.http.base.internal.DispatcherServlet.service(DispatcherServlet.java:67) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.felix.http.proxy.ProxyServlet.service(ProxyServlet.java:60) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.launchpad.base.webapp.SlingServletDelegate.service(SlingServletDelegate.java:277) at org.apache.sling.launchpad.webapp.SlingServlet.service(SlingServlet.java:150) at com.day.j2ee.servletengine.ServletRuntimeEnvironment.service(ServletRuntimeEnvironment.java:228) at com.day.j2ee.servletengine.RequestDispatcherImpl.doFilter(RequestDispatcherImpl.java:315) at com.day.j2ee.servletengine.FilterChainImpl.doFilter(FilterChainImpl.java:74) at com.day.crx.launchpad.filters.CRXLaunchpadLicenseFilter.doFilter(CRXLaunchpadLicenseFilter.java:96) at com.day.j2ee.servletengine.FilterChainImpl.doFilter(FilterChainImpl.java:72) at com.day.j2ee.servletengine.RequestDispatcherImpl.service(RequestDispatcherImpl.java:334) at com.day.j2ee.servletengine.RequestDispatcherImpl.service(RequestDispatcherImpl.java:378) at com.day.j2ee.servletengine.ServletHandlerImpl.execute(ServletHandlerImpl.java:315) at com.day.j2ee.servletengine.DefaultThreadPool$DequeueThread.run(DefaultThreadPool.java:134) at java.lang.Thread.run(Thread.java:619) Caused by: org.apache.sling.api.SlingException: javax.servlet.ServletException: javax.servlet.jsp.JspException: Error while executing script body.jsp at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspExceptionInternal(JspServletWrapper.java:560) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspException(JspServletWrapper.java:496) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:442) at org.apache.sling.scripting.jsp.JspServletWrapperAdapter.service(JspServletWrapperAdapter.java:59) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.callJsp(JspScriptEngineFactory.java:173) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.access$100(JspScriptEngineFactory.java:84) at org.apache.sling.scripting.jsp.JspScriptEngineFactory$JspScriptEngine.eval(JspScriptEngineFactory.java:388) at org.apache.sling.scripting.core.impl.DefaultSlingScript.call(DefaultSlingScript.java:358) at org.apache.sling.scripting.core.impl.DefaultSlingScript.eval(DefaultSlingScript.java:170) at org.apache.sling.scripting.core.impl.DefaultSlingScript.service(DefaultSlingScript.java:456) at org.apache.sling.engine.impl.request.RequestData.service(RequestData.java:491) at org.apache.sling.engine.impl.filter.SlingComponentFilterChain.render(SlingComponentFilterChain.java:45) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:64) at com.day.cq.wcm.core.impl.WCMDebugFilter.doFilter(WCMDebugFilter.java:133) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.wcm.core.impl.WCMComponentFilter.doFilter(WCMComponentFilter.java:219) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.processComponent(SlingRequestProcessorImpl.java:280) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.dispatchRequest(SlingRequestProcessorImpl.java:320) at org.apache.sling.engine.impl.request.SlingRequestDispatcher.dispatch(SlingRequestDispatcher.java:208) at org.apache.sling.engine.impl.request.SlingRequestDispatcher.include(SlingRequestDispatcher.java:102) at com.day.cq.wcm.core.impl.WCMComponentFilter$ForwardRequestDispatcher.include(WCMComponentFilter.java:381) at org.apache.jsp.libs.foundation.components.primary.cq.Page.Page_jsp._jspService(Page_jsp.java:106) at org.apache.sling.scripting.jsp.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:419) ... 66 more Caused by: org.apache.sling.api.SlingException: javax.servlet.ServletException: javax.servlet.jsp.JspException: Error while executing script content.jsp at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspExceptionInternal(JspServletWrapper.java:560) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspException(JspServletWrapper.java:496) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:442) at org.apache.sling.scripting.jsp.JspServletWrapperAdapter.service(JspServletWrapperAdapter.java:59) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.callJsp(JspScriptEngineFactory.java:173) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.access$100(JspScriptEngineFactory.java:84) at org.apache.sling.scripting.jsp.JspScriptEngineFactory$JspScriptEngine.eval(JspScriptEngineFactory.java:388) at org.apache.sling.scripting.core.impl.DefaultSlingScript.call(DefaultSlingScript.java:358) at org.apache.sling.scripting.core.impl.DefaultSlingScript.eval(DefaultSlingScript.java:170) at org.apache.sling.scripting.core.impl.DefaultSlingScript.service(DefaultSlingScript.java:456) at com.day.cq.wcm.tags.IncludeTag.includeScript(IncludeTag.java:149) at com.day.cq.wcm.tags.IncludeTag.doEndTag(IncludeTag.java:85) at org.apache.jsp.apps.oclc.components.page.common.common_jsp._jspx_meth_cq_005finclude_005f1(common_jsp.java:220) at org.apache.jsp.apps.oclc.components.page.common.common_jsp._jspService(common_jsp.java:174) at org.apache.sling.scripting.jsp.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:419) ... 89 more Caused by: org.apache.sling.api.SlingException: at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspExceptionInternal(JspServletWrapper.java:560) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspException(JspServletWrapper.java:496) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:448) at org.apache.sling.scripting.jsp.JspServletWrapperAdapter.service(JspServletWrapperAdapter.java:59) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.callJsp(JspScriptEngineFactory.java:173) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.access$100(JspScriptEngineFactory.java:84) at org.apache.sling.scripting.jsp.JspScriptEngineFactory$JspScriptEngine.eval(JspScriptEngineFactory.java:388) at org.apache.sling.scripting.core.impl.DefaultSlingScript.call(DefaultSlingScript.java:358) at org.apache.sling.scripting.core.impl.DefaultSlingScript.eval(DefaultSlingScript.java:170) at org.apache.sling.scripting.core.impl.DefaultSlingScript.service(DefaultSlingScript.java:456) at com.day.cq.wcm.tags.IncludeTag.includeScript(IncludeTag.java:149) at com.day.cq.wcm.tags.IncludeTag.doEndTag(IncludeTag.java:85) at org.apache.jsp.apps.oclc.components.page.oclcbase.body_jsp._jspx_meth_cq_005finclude_005f5(body_jsp.java:416) at org.apache.jsp.apps.oclc.components.page.oclcbase.body_jsp._jspService(body_jsp.java:216) at org.apache.sling.scripting.jsp.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:419) ... 103 more Caused by: org.apache.sling.api.SlingException: at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspExceptionInternal(JspServletWrapper.java:560) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspException(JspServletWrapper.java:496) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:448) at org.apache.sling.scripting.jsp.JspServletWrapperAdapter.service(JspServletWrapperAdapter.java:59) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.callJsp(JspScriptEngineFactory.java:173) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.access$100(JspScriptEngineFactory.java:84) at org.apache.sling.scripting.jsp.JspScriptEngineFactory$JspScriptEngine.eval(JspScriptEngineFactory.java:388) at org.apache.sling.scripting.core.impl.DefaultSlingScript.call(DefaultSlingScript.java:358) at org.apache.sling.scripting.core.impl.DefaultSlingScript.eval(DefaultSlingScript.java:170) at org.apache.sling.scripting.core.impl.DefaultSlingScript.service(DefaultSlingScript.java:456) at org.apache.sling.engine.impl.request.RequestData.service(RequestData.java:491) at org.apache.sling.engine.impl.filter.SlingComponentFilterChain.render(SlingComponentFilterChain.java:45) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:64) at com.day.cq.wcm.core.impl.WCMDebugFilter.doFilter(WCMDebugFilter.java:133) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.wcm.core.impl.WCMComponentFilter.doFilter(WCMComponentFilter.java:219) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.processComponent(SlingRequestProcessorImpl.java:280) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.dispatchRequest(SlingRequestProcessorImpl.java:320) at org.apache.sling.engine.impl.request.SlingRequestDispatcher.dispatch(SlingRequestDispatcher.java:208) at org.apache.sling.engine.impl.request.SlingRequestDispatcher.include(SlingRequestDispatcher.java:102) at com.day.cq.wcm.core.impl.WCMComponentFilter$ForwardRequestDispatcher.include(WCMComponentFilter.java:381) at com.day.cq.wcm.tags.IncludeTag.includeResource(IncludeTag.java:204) at com.day.cq.wcm.tags.IncludeTag.doEndTag(IncludeTag.java:89) at org.apache.jsp.apps.oclc.components.page.oclcinterior.content_jsp._jspx_meth_cq_005finclude_005f9(content_jsp.java:901) at org.apache.jsp.apps.oclc.components.page.oclcinterior.content_jsp._jspService(content_jsp.java:303) at org.apache.sling.scripting.jsp.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:419) ... 117 more Caused by: org.apache.sling.api.SlingException: at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspExceptionInternal(JspServletWrapper.java:560) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.handleJspException(JspServletWrapper.java:496) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:448) at org.apache.sling.scripting.jsp.JspServletWrapperAdapter.service(JspServletWrapperAdapter.java:59) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.callJsp(JspScriptEngineFactory.java:173) at org.apache.sling.scripting.jsp.JspScriptEngineFactory.access$100(JspScriptEngineFactory.java:84) at org.apache.sling.scripting.jsp.JspScriptEngineFactory$JspScriptEngine.eval(JspScriptEngineFactory.java:388) at org.apache.sling.scripting.core.impl.DefaultSlingScript.call(DefaultSlingScript.java:358) at org.apache.sling.scripting.core.impl.DefaultSlingScript.eval(DefaultSlingScript.java:170) at org.apache.sling.scripting.core.impl.DefaultSlingScript.service(DefaultSlingScript.java:456) at org.apache.sling.engine.impl.request.RequestData.service(RequestData.java:491) at org.apache.sling.engine.impl.filter.SlingComponentFilterChain.render(SlingComponentFilterChain.java:45) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:64) at com.day.cq.wcm.core.impl.WCMDebugFilter.doFilter(WCMDebugFilter.java:133) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at com.day.cq.wcm.core.impl.WCMComponentFilter.doFilter(WCMComponentFilter.java:219) at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.processComponent(SlingRequestProcessorImpl.java:280) at org.apache.sling.engine.impl.SlingRequestProcessorImpl.dispatchRequest(SlingRequestProcessorImpl.java:320) at org.apache.sling.engine.impl.request.SlingRequestDispatcher.dispatch(SlingRequestDispatcher.java:208) at org.apache.sling.engine.impl.request.SlingRequestDispatcher.include(SlingRequestDispatcher.java:102) at com.day.cq.wcm.core.impl.WCMComponentFilter$ForwardRequestDispatcher.include(WCMComponentFilter.java:381) at org.apache.sling.scripting.jsp.taglib.IncludeTagHandler.dispatch(IncludeTagHandler.java:49) at org.apache.sling.scripting.jsp.taglib.AbstractDispatcherTagHandler.doEndTag(AbstractDispatcherTagHandler.java:129) at org.apache.jsp.libs.foundation.components.parsys.parsys_jsp._jspService(parsys_jsp.java:311) at org.apache.sling.scripting.jsp.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:419) ... 143 more Caused by: java.lang.NullPointerException at org.apache.jsp.libs.wcm.msm.components.ghost.ghost_jsp._jspService(ghost_jsp.java:146) at org.apache.sling.scripting.jsp.jasper.runtime.HttpJspBase.service(HttpJspBase.java:70) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.sling.scripting.jsp.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:419) ... 168 more
Request Progress:
0 (2013-05-20 10:08:16) TIMER_START{Request Processing}
0 (2013-05-20 10:08:16) COMMENT timer_end format is {<elapsed msec>,<timer name>} <optional message>
0 (2013-05-20 10:08:16) LOG Method=GET, PathInfo=/content/mainoclc/canada/en_us/illiad/ordering.html
0 (2013-05-20 10:08:16) TIMER_START{ResourceResolution}
2 (2013-05-20 10:08:16) TIMER_END{2,ResourceResolution} URI=/content/mainoclc/canada/en_us/illiad/ordering.html resolves to Resource=JcrNodeResource, type=cq:Page, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering
2 (2013-05-20 10:08:16) LOG Resource Path Info: SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering', selectorString='null', extension='html', suffix='null'
2 (2013-05-20 10:08:16) TIMER_START{ServletResolution}
2 (2013-05-20 10:08:16) TIMER_START{resolveServlet(JcrNodeResource, type=cq:Page, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering)}
2 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(JcrNodeResource, type=cq:Page, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering)} Using servlet /libs/foundation/components/primary/cq/Page/Page.jsp
2 (2013-05-20 10:08:16) TIMER_END{0,ServletResolution} URI=/content/mainoclc/canada/en_us/illiad/ordering.html handled by Servlet=/libs/foundation/components/primary/cq/Page/Page.jsp
2 (2013-05-20 10:08:16) LOG Applying Requestfilters
2 (2013-05-20 10:08:16) LOG Calling filter: org.apache.sling.bgservlets.impl.BackgroundServletStarterFilter
2 (2013-05-20 10:08:16) LOG Calling filter: org.apache.sling.portal.container.internal.request.PortalFilter
2 (2013-05-20 10:08:16) LOG Calling filter: org.apache.sling.rewriter.impl.RewriterFilter
2 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMRequestFilter
2 (2013-05-20 10:08:16) LOG Calling filter: org.apache.sling.i18n.impl.I18NFilter
2 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.theme.impl.ThemeResolverFilter
2 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.foundation.forms.impl.FormsHandlingServlet
2 (2013-05-20 10:08:16) LOG Calling filter: org.apache.sling.engine.impl.debug.RequestProgressTrackerLogFilter
2 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.mobile.core.impl.redirect.RedirectFilter
3 (2013-05-20 10:08:16) LOG RedirectFilter did not redirect (MobileUtil.isMobileResource() returns false)
3 (2013-05-20 10:08:16) LOG Applying Componentfilters
3 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
3 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
3 (2013-05-20 10:08:16) TIMER_START{/libs/foundation/components/primary/cq/Page/Page.jsp#0}
3 (2013-05-20 10:08:16) LOG Including resource JcrNodeResource, type=oclc/components/page/oclcinterior, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content', selectorString='null', extension='html', suffix='null')
3 (2013-05-20 10:08:16) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/page/oclcinterior, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content)}
3 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/page/oclcinterior, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content)} Using servlet /apps/oclc/components/page/common/common.jsp
3 (2013-05-20 10:08:16) LOG Applying Includefilters
3 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
3 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
3 (2013-05-20 10:08:16) TIMER_START{/apps/oclc/components/page/common/common.jsp#1}
49 (2013-05-20 10:08:16) LOG Including resource SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader', selectorString='null', extension='html', suffix='null')
49 (2013-05-20 10:08:16) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader)}
49 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader)} Using servlet /libs/foundation/components/iparsys/iparsys.jsp
49 (2013-05-20 10:08:16) LOG Applying Includefilters
49 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
49 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
49 (2013-05-20 10:08:16) TIMER_START{/libs/foundation/components/iparsys/iparsys.jsp#2}
50 (2013-05-20 10:08:16) LOG Including resource TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*', selectorString='null', extension='html', suffix='null')
50 (2013-05-20 10:08:16) TIMER_START{resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*])}
50 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/*])} Using servlet /apps/foundation/components/parsys/new/new.jsp
50 (2013-05-20 10:08:16) LOG Applying Includefilters
50 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
50 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
50 (2013-05-20 10:08:16) TIMER_START{/apps/foundation/components/parsys/new/new.jsp#3}
50 (2013-05-20 10:08:16) TIMER_END{0,/apps/foundation/components/parsys/new/new.jsp#3}
52 (2013-05-20 10:08:16) LOG Including resource SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/iparsys_fake_par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/iparsys_fake_par', selectorString='null', extension='html', suffix='null')
52 (2013-05-20 10:08:16) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/iparsys_fake_par)}
52 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/invader/iparsys_fake_par)} Using servlet /libs/foundation/components/iparsys/par/par.jsp
52 (2013-05-20 10:08:16) LOG Applying Includefilters
52 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
52 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
52 (2013-05-20 10:08:16) TIMER_START{/libs/foundation/components/iparsys/par/par.jsp#4}
53 (2013-05-20 10:08:16) TIMER_END{1,/libs/foundation/components/iparsys/par/par.jsp#4}
53 (2013-05-20 10:08:16) TIMER_END{4,/libs/foundation/components/iparsys/iparsys.jsp#2}
54 (2013-05-20 10:08:16) LOG Including resource SyntheticResource, type=cq/personalization/components/clickstreamcloud, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/clickstreamcloud (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/clickstreamcloud', selectorString='null', extension='html', suffix='null')
54 (2013-05-20 10:08:16) TIMER_START{resolveServlet(SyntheticResource, type=cq/personalization/components/clickstreamcloud, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/clickstreamcloud)}
54 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(SyntheticResource, type=cq/personalization/components/clickstreamcloud, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/clickstreamcloud)} Using servlet /apps/cq/personalization/components/clickstreamcloud/clickstreamcloud.jsp
54 (2013-05-20 10:08:16) LOG Applying Includefilters
54 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
54 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
54 (2013-05-20 10:08:16) TIMER_START{/apps/cq/personalization/components/clickstreamcloud/clickstreamcloud.jsp#5}
56 (2013-05-20 10:08:16) TIMER_END{2,/apps/cq/personalization/components/clickstreamcloud/clickstreamcloud.jsp#5}
57 (2013-05-20 10:08:16) LOG Including resource SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar', selectorString='null', extension='html', suffix='null')
57 (2013-05-20 10:08:16) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar)}
57 (2013-05-20 10:08:16) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar)} Using servlet /libs/foundation/components/iparsys/iparsys.jsp
57 (2013-05-20 10:08:16) LOG Applying Includefilters
57 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
57 (2013-05-20 10:08:16) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
57 (2013-05-20 10:08:16) TIMER_START{/libs/foundation/components/iparsys/iparsys.jsp#6}
58 (2013-05-20 10:08:17) LOG Including resource TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*', selectorString='null', extension='html', suffix='null')
58 (2013-05-20 10:08:17) TIMER_START{resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*])}
58 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/*])} Using servlet /apps/foundation/components/parsys/new/new.jsp
58 (2013-05-20 10:08:17) LOG Applying Includefilters
58 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
58 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
58 (2013-05-20 10:08:17) TIMER_START{/apps/foundation/components/parsys/new/new.jsp#7}
58 (2013-05-20 10:08:17) TIMER_END{0,/apps/foundation/components/parsys/new/new.jsp#7}
60 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/iparsys_fake_par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/iparsys_fake_par', selectorString='null', extension='html', suffix='null')
60 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/iparsys_fake_par)}
60 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header_microbar/iparsys_fake_par)} Using servlet /libs/foundation/components/iparsys/par/par.jsp
60 (2013-05-20 10:08:17) LOG Applying Includefilters
60 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
60 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
60 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/par/par.jsp#8}
61 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/sitebar, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header_microbar/sitebar_33b7 (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/jcr:content/header_microbar/sitebar_33b7', selectorString='null', extension='html', suffix='null')
61 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/sitebar, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header_microbar/sitebar_33b7)}
61 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/sitebar, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header_microbar/sitebar_33b7)} Using servlet /apps/oclc/components/content/sitebar/sitebar.jsp
61 (2013-05-20 10:08:17) LOG Applying Includefilters
61 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
61 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
61 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/sitebar/sitebar.jsp#9}
88 (2013-05-20 10:08:17) TIMER_END{27,/apps/oclc/components/content/sitebar/sitebar.jsp#9}
88 (2013-05-20 10:08:17) TIMER_END{28,/libs/foundation/components/iparsys/par/par.jsp#8}
88 (2013-05-20 10:08:17) TIMER_END{31,/libs/foundation/components/iparsys/iparsys.jsp#6}
90 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header', selectorString='null', extension='html', suffix='null')
90 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header)}
90 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header)} Using servlet /libs/foundation/components/iparsys/iparsys.jsp
90 (2013-05-20 10:08:17) LOG Applying Includefilters
90 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
90 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
90 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/iparsys.jsp#10}
91 (2013-05-20 10:08:17) LOG Including resource TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*', selectorString='null', extension='html', suffix='null')
91 (2013-05-20 10:08:17) TIMER_START{resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*])}
91 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/*])} Using servlet /apps/foundation/components/parsys/new/new.jsp
91 (2013-05-20 10:08:17) LOG Applying Includefilters
91 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
91 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
91 (2013-05-20 10:08:17) TIMER_START{/apps/foundation/components/parsys/new/new.jsp#11}
91 (2013-05-20 10:08:17) TIMER_END{0,/apps/foundation/components/parsys/new/new.jsp#11}
93 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/iparsys_fake_par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/iparsys_fake_par', selectorString='null', extension='html', suffix='null')
93 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/iparsys_fake_par)}
93 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/header/iparsys_fake_par)} Using servlet /libs/foundation/components/iparsys/par/par.jsp
93 (2013-05-20 10:08:17) LOG Applying Includefilters
93 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
93 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
93 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/par/par.jsp#12}
94 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/oclcbase/header, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb', selectorString='null', extension='html', suffix='null')
94 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/oclcbase/header, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb)}
94 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/oclcbase/header, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb)} Using servlet /apps/oclc/components/content/oclcbase/header/header.jsp
94 (2013-05-20 10:08:17) LOG Applying Includefilters
94 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
94 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
94 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/oclcbase/header/header.jsp#13}
95 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/globallogo, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/logo (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/logo', selectorString='null', extension='html', suffix='null')
95 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/globallogo, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/logo)}
95 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/globallogo, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/logo)} Using servlet /apps/oclc/components/content/globallogo/globallogo.jsp
95 (2013-05-20 10:08:17) LOG Applying Includefilters
95 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
95 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
95 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/globallogo/globallogo.jsp#14}
96 (2013-05-20 10:08:17) TIMER_END{1,/apps/oclc/components/content/globallogo/globallogo.jsp#14}
140 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/search, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/search (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/search', selectorString='null', extension='html', suffix='null')
140 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/search, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/search)}
140 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/search, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/search)} Using servlet /apps/oclc/components/content/search/search.jsp
140 (2013-05-20 10:08:17) LOG Applying Includefilters
140 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
140 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
140 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/search/search.jsp#15}
143 (2013-05-20 10:08:17) TIMER_END{3,/apps/oclc/components/content/search/search.jsp#15}
147 (2013-05-20 10:08:17) LOG Found processor for post processing ProcessorConfiguration: {contentTypes=[text/html],order=-1, active=true, valid=true, processErrorResponse=true, pipeline=(generator=Config(type=htmlparser, config={}), transformers=(Config(type=linkchecker, config={}), Config(type=mobile, config=org.apache.sling.jcr.resource.JcrPropertyMap@6b347429), Config(type=mobiledebug, config=org.apache.sling.jcr.resource.JcrPropertyMap@40b666bb), serializer=Config(type=htmlwriter, config={}))}
158 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/topnav, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/topnav (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/topnav', selectorString='null', extension='html', suffix='null')
158 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/topnav, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/topnav)}
158 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/topnav, superType=null, path=/content/mainoclc/canada/en_us/jcr:content/header/header_6cbb/topnav)} Using servlet /apps/oclc/components/content/topnav/topnav.jsp
158 (2013-05-20 10:08:17) LOG Applying Includefilters
158 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
158 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
158 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/topnav/topnav.jsp#16}
160 (2013-05-20 10:08:17) TIMER_END{2,/apps/oclc/components/content/topnav/topnav.jsp#16}
160 (2013-05-20 10:08:17) TIMER_END{66,/apps/oclc/components/content/oclcbase/header/header.jsp#13}
160 (2013-05-20 10:08:17) TIMER_END{67,/libs/foundation/components/iparsys/par/par.jsp#12}
160 (2013-05-20 10:08:17) TIMER_END{70,/libs/foundation/components/iparsys/iparsys.jsp#10}
162 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=oclc/components/content/breadcrumb, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/breadcrumb (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/breadcrumb', selectorString='null', extension='html', suffix='null')
162 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=oclc/components/content/breadcrumb, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/breadcrumb)}
162 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=oclc/components/content/breadcrumb, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/breadcrumb)} Using servlet /apps/oclc/components/content/breadcrumb/breadcrumb.jsp
162 (2013-05-20 10:08:17) LOG Applying Includefilters
162 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
162 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
162 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/breadcrumb/breadcrumb.jsp#17}
165 (2013-05-20 10:08:17) TIMER_END{3,/apps/oclc/components/content/breadcrumb/breadcrumb.jsp#17}
166 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/addThis, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/addThis (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/addThis', selectorString='null', extension='html', suffix='null')
166 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/addThis, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/addThis)}
166 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/addThis, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/addThis)} Using servlet /apps/oclc/components/content/addThis/addThis.jsp
166 (2013-05-20 10:08:17) LOG Applying Includefilters
166 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
166 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
166 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/addThis/addThis.jsp#18}
166 (2013-05-20 10:08:17) TIMER_END{0,/apps/oclc/components/content/addThis/addThis.jsp#18}
168 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation', selectorString='null', extension='html', suffix='null')
168 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation)}
168 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation)} Using servlet /libs/foundation/components/iparsys/iparsys.jsp
168 (2013-05-20 10:08:17) LOG Applying Includefilters
168 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
168 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
168 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/iparsys.jsp#19}
169 (2013-05-20 10:08:17) LOG Including resource TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*', selectorString='null', extension='html', suffix='null')
169 (2013-05-20 10:08:17) TIMER_START{resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*])}
169 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/*])} Using servlet /apps/foundation/components/parsys/new/new.jsp
169 (2013-05-20 10:08:17) LOG Applying Includefilters
169 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
169 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
169 (2013-05-20 10:08:17) TIMER_START{/apps/foundation/components/parsys/new/new.jsp#20}
169 (2013-05-20 10:08:17) TIMER_END{0,/apps/foundation/components/parsys/new/new.jsp#20}
171 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/iparsys_fake_par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/iparsys_fake_par', selectorString='null', extension='html', suffix='null')
171 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/iparsys_fake_par)}
171 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftNavigation/iparsys_fake_par)} Using servlet /libs/foundation/components/iparsys/par/par.jsp
171 (2013-05-20 10:08:17) LOG Applying Includefilters
171 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
171 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
171 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/par/par.jsp#21}
172 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/interiornav, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/leftNavigation/interiornav_a545 (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/jcr:content/leftNavigation/interiornav_a545', selectorString='null', extension='html', suffix='null')
172 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/interiornav, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/leftNavigation/interiornav_a545)}
172 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/interiornav, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/leftNavigation/interiornav_a545)} Using servlet /apps/oclc/components/content/interiornav/interiornav.jsp
172 (2013-05-20 10:08:17) LOG Applying Includefilters
172 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
172 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
172 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/interiornav/interiornav.jsp#22}
180 (2013-05-20 10:08:17) TIMER_END{8,/apps/oclc/components/content/interiornav/interiornav.jsp#22}
180 (2013-05-20 10:08:17) TIMER_END{9,/libs/foundation/components/iparsys/par/par.jsp#21}
180 (2013-05-20 10:08:17) TIMER_END{12,/libs/foundation/components/iparsys/iparsys.jsp#19}
182 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea', selectorString='null', extension='html', suffix='null')
182 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea)}
182 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea)} Using servlet /libs/foundation/components/iparsys/iparsys.jsp
182 (2013-05-20 10:08:17) LOG Applying Includefilters
182 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
182 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
182 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/iparsys.jsp#23}
183 (2013-05-20 10:08:17) LOG Including resource TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*', selectorString='null', extension='html', suffix='null')
183 (2013-05-20 10:08:17) TIMER_START{resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*])}
183 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/*])} Using servlet /apps/foundation/components/parsys/new/new.jsp
183 (2013-05-20 10:08:17) LOG Applying Includefilters
183 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
183 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
183 (2013-05-20 10:08:17) TIMER_START{/apps/foundation/components/parsys/new/new.jsp#24}
183 (2013-05-20 10:08:17) TIMER_END{0,/apps/foundation/components/parsys/new/new.jsp#24}
185 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/iparsys_fake_par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/iparsys_fake_par', selectorString='null', extension='html', suffix='null')
185 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/iparsys_fake_par)}
185 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/subscriptionArea/iparsys_fake_par)} Using servlet /libs/foundation/components/iparsys/par/par.jsp
185 (2013-05-20 10:08:17) LOG Applying Includefilters
185 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
185 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
185 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/par/par.jsp#25}
186 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=foundation/components/reference, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/subscriptionArea/reference_f317 (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/jcr:content/subscriptionArea/reference_f317', selectorString='null', extension='html', suffix='null')
186 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=foundation/components/reference, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/subscriptionArea/reference_f317)}
186 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=foundation/components/reference, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/subscriptionArea/reference_f317)} Using servlet /libs/foundation/components/reference/reference.jsp
186 (2013-05-20 10:08:17) LOG Applying Includefilters
186 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
186 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
186 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/reference/reference.jsp#26}
188 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/whatcounts, superType=null, path=/content/mainoclc/canada/en_us/1-design-samples/whatcounts/jcr:content/par/whatcounts_b9dc (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/1-design-samples/whatcounts/jcr:content/par/whatcounts_b9dc', selectorString='null', extension='html', suffix='null')
188 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/whatcounts, superType=null, path=/content/mainoclc/canada/en_us/1-design-samples/whatcounts/jcr:content/par/whatcounts_b9dc)}
188 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/whatcounts, superType=null, path=/content/mainoclc/canada/en_us/1-design-samples/whatcounts/jcr:content/par/whatcounts_b9dc)} Using servlet /apps/oclc/components/content/whatcounts/whatcounts.jsp
188 (2013-05-20 10:08:17) LOG Applying Includefilters
188 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
188 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
188 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/whatcounts/whatcounts.jsp#27}
211 (2013-05-20 10:08:17) TIMER_END{23,/apps/oclc/components/content/whatcounts/whatcounts.jsp#27}
211 (2013-05-20 10:08:17) TIMER_END{25,/libs/foundation/components/reference/reference.jsp#26}
211 (2013-05-20 10:08:17) TIMER_END{26,/libs/foundation/components/iparsys/par/par.jsp#25}
211 (2013-05-20 10:08:17) TIMER_END{29,/libs/foundation/components/iparsys/iparsys.jsp#23}
214 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps', selectorString='null', extension='html', suffix='null')
214 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps)}
214 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps)} Using servlet /libs/foundation/components/iparsys/iparsys.jsp
214 (2013-05-20 10:08:17) LOG Applying Includefilters
214 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
214 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
214 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/iparsys.jsp#28}
215 (2013-05-20 10:08:17) LOG Including resource TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*', selectorString='null', extension='html', suffix='null')
215 (2013-05-20 10:08:17) TIMER_START{resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*])}
215 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(TypeOverwritingResourceWrapper, type=foundation/components/iparsys/new, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*, resource=[StarResource, type=sling:syntheticStarResource, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/*])} Using servlet /apps/foundation/components/parsys/new/new.jsp
215 (2013-05-20 10:08:17) LOG Applying Includefilters
215 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
215 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
215 (2013-05-20 10:08:17) TIMER_START{/apps/foundation/components/parsys/new/new.jsp#29}
215 (2013-05-20 10:08:17) TIMER_END{0,/apps/foundation/components/parsys/new/new.jsp#29}
217 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/iparsys_fake_par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/iparsys_fake_par', selectorString='null', extension='html', suffix='null')
217 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/iparsys_fake_par)}
217 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/iparsys/par, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/nextSteps/iparsys_fake_par)} Using servlet /libs/foundation/components/iparsys/par/par.jsp
217 (2013-05-20 10:08:17) LOG Applying Includefilters
217 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
217 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
217 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/iparsys/par/par.jsp#30}
218 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_1f9c (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_1f9c', selectorString='null', extension='html', suffix='null')
218 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_1f9c)}
218 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_1f9c)} Using servlet /apps/oclc/components/content/text/text.jsp
218 (2013-05-20 10:08:17) LOG Applying Includefilters
218 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
218 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
218 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/text/text.jsp#31}
219 (2013-05-20 10:08:17) TIMER_END{1,/apps/oclc/components/content/text/text.jsp#31}
220 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_94bd (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_94bd', selectorString='null', extension='html', suffix='null')
220 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_94bd)}
220 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/jcr:content/nextSteps/text_94bd)} Using servlet /apps/oclc/components/content/text/text.jsp
220 (2013-05-20 10:08:17) LOG Applying Includefilters
220 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
220 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
220 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/text/text.jsp#32}
220 (2013-05-20 10:08:17) TIMER_END{0,/apps/oclc/components/content/text/text.jsp#32}
220 (2013-05-20 10:08:17) TIMER_END{3,/libs/foundation/components/iparsys/par/par.jsp#30}
220 (2013-05-20 10:08:17) TIMER_END{6,/libs/foundation/components/iparsys/iparsys.jsp#28}
222 (2013-05-20 10:08:17) LOG Including resource SyntheticResource, type=foundation/components/parsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftpar (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftpar', selectorString='null', extension='html', suffix='null')
222 (2013-05-20 10:08:17) TIMER_START{resolveServlet(SyntheticResource, type=foundation/components/parsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftpar)}
222 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(SyntheticResource, type=foundation/components/parsys, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/leftpar)} Using servlet /libs/foundation/components/parsys/parsys.jsp
222 (2013-05-20 10:08:17) LOG Applying Includefilters
222 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
222 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
222 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/parsys/parsys.jsp#33}
222 (2013-05-20 10:08:17) TIMER_END{0,/libs/foundation/components/parsys/parsys.jsp#33}
230 (2013-05-20 10:08:17) LOG Including resource JcrNodeResource, type=foundation/components/parsys, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par', selectorString='null', extension='html', suffix='null')
230 (2013-05-20 10:08:17) TIMER_START{resolveServlet(JcrNodeResource, type=foundation/components/parsys, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par)}
230 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(JcrNodeResource, type=foundation/components/parsys, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par)} Using servlet /libs/foundation/components/parsys/parsys.jsp
230 (2013-05-20 10:08:17) LOG Applying Includefilters
230 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
230 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
230 (2013-05-20 10:08:17) TIMER_START{/libs/foundation/components/parsys/parsys.jsp#34}
234 (2013-05-20 10:08:17) LOG Including resource Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title', selectorString='null', extension='html', suffix='null')
234 (2013-05-20 10:08:17) TIMER_START{resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title])}
234 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_title])} Using servlet /apps/oclc/components/content/text/text.jsp
234 (2013-05-20 10:08:17) LOG Applying Includefilters
234 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
234 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
234 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/text/text.jsp#35}
235 (2013-05-20 10:08:17) TIMER_END{1,/apps/oclc/components/content/text/text.jsp#35}
235 (2013-05-20 10:08:17) LOG Including resource Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0', selectorString='null', extension='html', suffix='null')
235 (2013-05-20 10:08:17) TIMER_START{resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0])}
235 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_0])} Using servlet /apps/oclc/components/content/text/text.jsp
235 (2013-05-20 10:08:17) LOG Applying Includefilters
235 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
235 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
235 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/text/text.jsp#36}
235 (2013-05-20 10:08:17) TIMER_END{0,/apps/oclc/components/content/text/text.jsp#36}
235 (2013-05-20 10:08:17) LOG Including resource Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1', selectorString='null', extension='html', suffix='null')
235 (2013-05-20 10:08:17) TIMER_START{resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1])}
235 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1, type=oclc/components/content/text, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=oclc/components/content/text, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_1])} Using servlet /apps/oclc/components/content/text/text.jsp
235 (2013-05-20 10:08:17) LOG Applying Includefilters
235 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
235 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
235 (2013-05-20 10:08:17) TIMER_START{/apps/oclc/components/content/text/text.jsp#37}
236 (2013-05-20 10:08:17) TIMER_END{1,/apps/oclc/components/content/text/text.jsp#37}
236 (2013-05-20 10:08:17) LOG Including resource Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2, type=wcm/msm/components/ghost, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=wcm/msm/components/ghost, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2] (SlingRequestPathInfo: path='/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2', selectorString='null', extension='html', suffix='null')
236 (2013-05-20 10:08:17) TIMER_START{resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2, type=wcm/msm/components/ghost, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=wcm/msm/components/ghost, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2])}
236 (2013-05-20 10:08:17) TIMER_END{0,resolveServlet(Paragraph, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2, type=wcm/msm/components/ghost, cssClass=default, column=0/0, diffInfo=[null], resource=[JcrNodeResource, type=wcm/msm/components/ghost, superType=null, path=/content/mainoclc/canada/en_us/illiad/ordering/jcr:content/par/text_2])} Using servlet /libs/wcm/msm/components/ghost/ghost.jsp
236 (2013-05-20 10:08:17) LOG Applying Includefilters
236 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter
236 (2013-05-20 10:08:17) LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter
236 (2013-05-20 10:08:17) TIMER_START{/libs/wcm/msm/components/ghost/ghost.jsp#38}
237 (2013-05-20 10:08:17) LOG SCRIPT ERROR:
237 (2013-05-20 10:08:17) TIMER_END{1,/libs/wcm/msm/components/ghost/ghost.jsp#38}
238 (2013-05-20 10:08:17) LOG SCRIPT ERROR:
238 (2013-05-20 10:08:17) TIMER_END{8,/libs/foundation/components/parsys/parsys.jsp#34}
239 (2013-05-20 10:08:17) LOG SCRIPT ERROR:
240 (2013-05-20 10:08:17) LOG SCRIPT ERROR: javax.servlet.ServletException: javax.servlet.jsp.JspException: Error while executing script content.jsp
241 (2013-05-20 10:08:17) LOG SCRIPT ERROR: javax.servlet.ServletException: javax.servlet.jsp.JspException: Error while executing script body.jsp
241 (2013-05-20 10:08:17) TIMER_END{238,/apps/oclc/components/page/common/common.jsp#1}
242 (2013-05-20 10:08:17) LOG SCRIPT ERROR:
242 (2013-05-20 10:08:17) TIMER_END{239,/libs/foundation/components/primary/cq/Page/Page.jsp#0}
243 (2013-05-20 10:08:17) TIMER_START{handleError:throwable=org.apache.sling.api.SlingException}
274 (2013-05-20 10:08:17) TIMER_END{31,handleError:throwable=org.apache.sling.api.SlingException} Using handler org.apache.sling.servlets.resolver.internal.defaults.DefaultErrorHandlerServlet
277 (2013-05-20 10:08:17) TIMER_END{277,Request Processing} Dumping SlingRequestProgressTracker Entries
ApacheSling/2.2 (Day-Servlet-Engine/4.1.12, Java HotSpot(TM) 64-Bit Server VM 1.6.0_20, Linux 2.6.18-8.el5 amd64)