2015-10-12 15:44:12,721 org.apache.shiro.session.mgt.AbstractValidatingSessionManager.createSessionValidationScheduler(AbstractValidatingSessionManager.java:213) DEBUG - No sessionValidationScheduler set.  Attempting to create default instance.
2015-10-12 15:44:12,722 org.apache.shiro.session.mgt.AbstractValidatingSessionManager.enableSessionValidation(AbstractValidatingSessionManager.java:230) INFO  - Enabling session validation scheduler...
2015-10-12 15:44:12,724 org.apache.shiro.web.servlet.SimpleCookie.readValue(SimpleCookie.java:366) DEBUG - Found 'sid' cookie value [sq7ls6pk42jptqo7729766jtkf]
2015-10-12 15:44:12,724 org.apache.shiro.cache.ehcache.EhCacheManager.getCache(EhCacheManager.java:170) INFO  - Using existing EHCache named [shiro-activeSessionCache]
2015-10-12 15:44:12,728 org.apache.shiro.mgt.DefaultSecurityManager.resolveSession(DefaultSecurityManager.java:447) DEBUG - Resolved SubjectContext context session is invalid.  Ignoring and creating an anonymous (session-less) Subject instance.
org.apache.shiro.session.UnknownSessionException: There is no session with id [sq7ls6pk42jptqo7729766jtkf]
	at org.apache.shiro.session.mgt.eis.AbstractSessionDAO.readSession(AbstractSessionDAO.java:170)
	at org.apache.shiro.session.mgt.eis.CachingSessionDAO.readSession(CachingSessionDAO.java:261)
	at org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSessionFromDataSource(DefaultSessionManager.java:236)
	at org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSession(DefaultSessionManager.java:222)
	at org.apache.shiro.session.mgt.AbstractValidatingSessionManager.doGetSession(AbstractValidatingSessionManager.java:118)
	at org.apache.shiro.session.mgt.AbstractNativeSessionManager.lookupSession(AbstractNativeSessionManager.java:108)
	at org.apache.shiro.session.mgt.AbstractNativeSessionManager.getSession(AbstractNativeSessionManager.java:100)
	at org.apache.shiro.mgt.SessionsSecurityManager.getSession(SessionsSecurityManager.java:125)
	at org.apache.shiro.mgt.DefaultSecurityManager.resolveContextSession(DefaultSecurityManager.java:456)
	at org.apache.shiro.mgt.DefaultSecurityManager.resolveSession(DefaultSecurityManager.java:442)
	at org.apache.shiro.mgt.DefaultSecurityManager.createSubject(DefaultSecurityManager.java:338)
	at org.apache.shiro.subject.Subject$Builder.buildSubject(Subject.java:846)
	at org.apache.shiro.web.subject.WebSubject$Builder.buildWebSubject(WebSubject.java:148)
	at org.apache.shiro.web.servlet.AbstractShiroFilter.createSubject(AbstractShiroFilter.java:292)
	at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:359)
	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125)
	at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1212)
	at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:399)
	at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
	at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)
	at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:766)
	at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:450)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.Server.handle(Server.java:326)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)
	at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:928)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:549)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)
2015-10-12 15:44:12,740 org.nutz.mvc.impl.UrlMappingImpl.get(UrlMappingImpl.java:92) DEBUG - Found mapping for [GET] path=/front/company-list : WebRouter.companyList(...)
2015-10-12 15:44:12,740 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:145) DEBUG - Get 'webRouter'<class vc.thinker.module.WebRouter>
2015-10-12 15:44:12,741 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:165) DEBUG - 	 >> Load definition
2015-10-12 15:44:12,741 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:144) DEBUG - Found IocObject(webRouter) in IocLoader(AnnotationIocLoader@1294192564)
2015-10-12 15:44:12,741 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:195) DEBUG - 	 >> Make...'webRouter'<class vc.thinker.module.WebRouter>
2015-10-12 15:44:12,742 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:84) DEBUG - class vc.thinker.module.WebRouter without AOP
2015-10-12 15:44:12,742 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'webRouter' to [app] 
2015-10-12 15:44:12,743 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:145) DEBUG - Get 'productService'<>
2015-10-12 15:44:12,743 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:165) DEBUG - 	 >> Load definition
2015-10-12 15:44:12,744 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:144) DEBUG - Found IocObject(productService) in IocLoader(AnnotationIocLoader@1294192564)
2015-10-12 15:44:12,744 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:195) DEBUG - 	 >> Make...'productService'<>
2015-10-12 15:44:12,745 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:84) DEBUG - class vc.thinker.module.simplecn.firm.service.ProductService without AOP
2015-10-12 15:44:12,745 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:59) DEBUG - Save object 'productService' to [app] 
2015-10-12 15:44:12,746 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:145) DEBUG - Get 'dao'<>
2015-10-12 15:44:12,746 vc.thinker.mvc.processor.DemoProcessor.process(DemoProcessor.java:27) DEBUG - demoProcessor ...
2015-10-12 15:44:20,997 org.nutz.dao.util.Daos.getColumnIndex(Daos.java:128) INFO  - Can not find @Column(level) in table/view (products_categories)
2015-10-12 15:44:21,144 org.nutz.dao.util.Daos.getColumnIndex(Daos.java:128) INFO  - Can not find @Column(category_id) in table/view (v_products)
2015-10-12 15:44:21,145 org.nutz.dao.util.Daos.getColumnIndex(Daos.java:128) INFO  - Can not find @Column(category_name) in table/view (v_products)
2015-10-12 15:44:21,159 org.nutz.plugins.cache.dao.CachedNutDaoExecutor.exec(CachedNutDaoExecutor.java:120) DEBUG - sql = SELECT * FROM v_products  LIMIT 0, 20 , tables = [v_products]
2015-10-12 15:44:21,160 org.nutz.plugins.cache.dao.CachedNutDaoExecutor.exec(CachedNutDaoExecutor.java:148) DEBUG - not good for cache >> SELECT * FROM v_products  LIMIT 0, 20 
2015-10-12 15:44:21,161 org.nutz.dao.impl.sql.run.NutDaoExecutor._runSelect(NutDaoExecutor.java:193) DEBUG - SELECT * FROM v_products  LIMIT 0, 20 
2015-10-12 15:44:21,523 vc.thinker.mvc.processor.LogTimeProcessor.process(LogTimeProcessor.java:30) DEBUG - [ GET]URI=/front/company-list 8783ms
2015-10-12 15:44:21,735 org.apache.shiro.web.servlet.SimpleCookie.readValue(SimpleCookie.java:366) DEBUG - Found 'sid' cookie value [sq7ls6pk42jptqo7729766jtkf]
2015-10-12 15:44:21,735 org.apache.shiro.mgt.DefaultSecurityManager.resolveSession(DefaultSecurityManager.java:447) DEBUG - Resolved SubjectContext context session is invalid.  Ignoring and creating an anonymous (session-less) Subject instance.