Jar包：
ehcache-2.10.4.jar
shiro-all-1.3.2.jar
shiro-ehcache-1.3.2.jar

ehcache.xml

<ehcache xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
    xsi:noNamespaceSchemaLocation="ehcache.xsd" updateCheck="false"
    monitoring="autodetect" dynamicConfig="true" name="nutzbook">
    <!-- <diskStore path="java.io.tmpdir/shiro-ehcache"/> -->
    <defaultCache
            maxElementsInMemory="10000"
            eternal="false"
            timeToIdleSeconds="120"
            timeToLiveSeconds="120"
            overflowToDisk="false"
            diskPersistent="false"
            diskExpiryThreadIntervalSeconds="120"
            />
    <cache name="shiro-activeSessionCache"
           maxElementsInMemory="10000"
           overflowToDisk="true"
           eternal="true"
           timeToLiveSeconds="0"
           timeToIdleSeconds="0"
           diskPersistent="true"
           diskExpiryThreadIntervalSeconds="600"/>
</ehcache>

shiro.ini

[main]
nutzdao_realm = net.wendal.nutzbook.shiro.realm.SimpleAuthorizingRealm

cacheManager = org.apache.shiro.cache.ehcache.EhCacheManager
cacheManager.cacheManagerConfigFile=classpath:ehcache.xml
nutzdao_realm.cacheManager = $cacheManager

authc = org.nutz.integration.shiro.SimpleAuthenticationFilter
authc.loginUrl  = /user/login
logout.redirectUrl= /user/login
[urls]
/rs/*        = anon
/user/logout = logout
/user/error  = anon
/user/login  = anon
/user/profile/active/mail = anon

package net.wendal.nutzbook;

import java.util.Date;

import net.wendal.nutzbook.bean.User;
import net.wendal.nutzbook.service.AuthorityService;
import net.wendal.nutzbook.service.UserService;

import org.apache.commons.mail.HtmlEmail;
import org.apache.shiro.cache.CacheManager;
import org.nutz.dao.Dao;
import org.nutz.dao.util.Daos;
import org.nutz.integration.quartz.NutQuartzCronJobFactory;
import org.nutz.ioc.Ioc;

import org.nutz.log.Log;
import org.nutz.mvc.NutConfig;
import org.nutz.mvc.Setup;

public class MainSetup implements Setup {
	Log log;
  // 注意是init方法,不是destroy方法
    public void init(NutConfig nc) {
        Ioc ioc = nc.getIoc();
        Dao dao = ioc.get(Dao.class);
        // 如果提示没有createTablesInPackage方法,请确认用了最新版的nutz,且老版本的nutz已经删除干净
        Daos.createTablesInPackage(dao, "net.wendal.nutzbook", false);

        // 初始化默认根用户
        if (dao.count(User.class) == 0) {
            UserService us = ioc.get(UserService.class);
            us.add("admin", "123456");
        }
        // 获取NutQuartzCronJobFactory从而触发计划任务的初始化与启动
        ioc.get(NutQuartzCronJobFactory.class);
        // 测试发送邮件
        try {
            HtmlEmail email = ioc.get(HtmlEmail.class);
            email.setSubject("测试NutzBook");
            email.setMsg("This is a test mail ... :-)" + System.currentTimeMillis());
            email.addTo("250178656@qq.com");//请务必改成您自己的邮箱啊!!!
            email.buildMimeMessage();
            email.sendMimeMessage();
        } catch (Exception e) {
            e.printStackTrace();
        }
        
        
        /*以下代码就能在启动时扫描注解,初始化最基本的权限模型,即:
        	用户admin存在
        	角色admin存在
        	用户admin属于admin角色
        	admin角色拥有所有user:和authority:开头的权限*/
        AuthorityService as = ioc.get(AuthorityService.class);
        as.initFormPackage("net.wendal.nutzbook");
        as.checkBasicRoles(dao.fetch(User.class, "admin"));
        
        CacheManager cacheManager = ioc.get(CacheManager.class);
        log.debug("Ehcache CacheManager = " + cacheManager);
    }

    public void destroy(NutConfig nc) {
         // webapp销毁之前执行的逻辑
         // 这个时候依然可以从nc取出ioc, 然后取出需要的ioc 对象进行操作
    	
    }

}

改完之后

package net.wendal.nutzbook;

import java.util.Date;

import net.sf.ehcache.CacheManager;
import net.wendal.nutzbook.bean.User;
import net.wendal.nutzbook.service.AuthorityService;
import net.wendal.nutzbook.service.UserService;

import org.apache.commons.mail.HtmlEmail;

import org.nutz.dao.Dao;
import org.nutz.dao.util.Daos;
import org.nutz.integration.quartz.NutQuartzCronJobFactory;
import org.nutz.ioc.Ioc;

import org.nutz.log.Log;
import org.nutz.mvc.NutConfig;
import org.nutz.mvc.Setup;

public class MainSetup implements Setup {
	Log log;
  // 注意是init方法,不是destroy方法
    public void init(NutConfig nc) {
        Ioc ioc = nc.getIoc();
        Dao dao = ioc.get(Dao.class);
        // 如果提示没有createTablesInPackage方法,请确认用了最新版的nutz,且老版本的nutz已经删除干净
        Daos.createTablesInPackage(dao, "net.wendal.nutzbook", false);

        // 初始化默认根用户
        if (dao.count(User.class) == 0) {
            UserService us = ioc.get(UserService.class);
            us.add("admin", "123456");
        }
        // 获取NutQuartzCronJobFactory从而触发计划任务的初始化与启动
        ioc.get(NutQuartzCronJobFactory.class);
        // 测试发送邮件
        try {
            HtmlEmail email = ioc.get(HtmlEmail.class);
            email.setSubject("测试NutzBook");
            email.setMsg("This is a test mail ... :-)" + System.currentTimeMillis());
            email.addTo("250178656@qq.com");//请务必改成您自己的邮箱啊!!!
            email.buildMimeMessage();
            email.sendMimeMessage();
        } catch (Exception e) {
            e.printStackTrace();
        }
        
        
        /*以下代码就能在启动时扫描注解,初始化最基本的权限模型,即:
        	用户admin存在
        	角色admin存在
        	用户admin属于admin角色
        	admin角色拥有所有user:和authority:开头的权限*/
        AuthorityService as = ioc.get(AuthorityService.class);
        as.initFormPackage("net.wendal.nutzbook");
        as.checkBasicRoles(dao.fetch(User.class, "admin"));
        
        CacheManager cacheManager = ioc.get(CacheManager.class);
        log.debug("Ehcache CacheManager = " + cacheManager);
    }

    public void destroy(NutConfig nc) {
         // webapp销毁之前执行的逻辑
         // 这个时候依然可以从nc取出ioc, 然后取出需要的ioc 对象进行操作
    	
    }

}

又报错了

严重: Exception starting filter nutz
org.nutz.mvc.LoadingException: java.lang.NullPointerException
	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
	at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
	at org.nutz.lang.born.ConstructorBorning.born(ConstructorBorning.java:17)
	at org.nutz.lang.born.BornContext.doBorn(BornContext.java:60)
	at org.nutz.lang.Mirror.born(Mirror.java:990)
	at org.nutz.lang.Lang.wrapThrow(Lang.java:197)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:135)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:87)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:65)
	at org.apache.catalina.core.ApplicationFilterConfig.initFilter(ApplicationFilterConfig.java:279)
	at org.apache.catalina.core.ApplicationFilterConfig.getFilter(ApplicationFilterConfig.java:260)
	at org.apache.catalina.core.ApplicationFilterConfig.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4809)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5485)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:632)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1229)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1875)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:744)
Caused by: java.lang.NullPointerException
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:60)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:277)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	... 19 more

是log那有问题吗 怎么改

我从nutzbook复制过来的

        CacheManager cacheManager = ioc.get(CacheManager.class);
        log.debug("Ehcache CacheManager = " + cacheManager);

log底下有红线 我导入的这个包：import org.nutz.log.Log;

60行的代码

        return borning.born(args);

@wendal

as.checkBasicRoles(dao.fetch(User.class, "admin"));

2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigDecimal[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.math.BigInteger;@ed69d9
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigInteger[]' value '[Ljava.math.BigInteger;@ed69d9' to type 'BigInteger[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigInteger[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Boolean;@186eeb5
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean[]' value '[Ljava.lang.Boolean;@186eeb5' to type 'Boolean[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Byte;@17494f9
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Byte[]' value '[Ljava.lang.Byte;@17494f9' to type 'Byte[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Byte[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Character;@6bc023
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character[]' value '[Ljava.lang.Character;@6bc023' to type 'Character[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Double;@85ce81
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Double[]' value '[Ljava.lang.Double;@85ce81' to type 'Double[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Double[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Float;@c7f7c4
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Float[]' value '[Ljava.lang.Float;@c7f7c4' to type 'Float[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Float[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Integer;@17c5337
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer[]' value '[Ljava.lang.Integer;@17c5337' to type 'Integer[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Long;@38fec5
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Long[]' value '[Ljava.lang.Long;@38fec5' to type 'Long[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Long[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Short;@1df9cf4
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Short[]' value '[Ljava.lang.Short;@1df9cf4' to type 'Short[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Short[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.String;@dc688f
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String[]' value '[Ljava.lang.String;@dc688f' to type 'String[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a String[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Class;@54a79a
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Class[]' value '[Ljava.lang.Class;@54a79a' to type 'Class[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Class[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.util.Date;@1c0210e
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Date[]' value '[Ljava.util.Date;@1c0210e' to type 'Date[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Date[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.util.Calendar;@1ffdd03
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Calendar[]' value '[Ljava.util.Calendar;@1ffdd03' to type 'Calendar[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Calendar[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.io.File;@1658888
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.io.File[]' value '[Ljava.io.File;@1658888' to type 'java.io.File[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.io.File[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Date;@b8b75f
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Date[]' value '[Ljava.sql.Date;@b8b75f' to type 'java.sql.Date[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Date[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Time;@7af988
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Time[]' value '[Ljava.sql.Time;@7af988' to type 'java.sql.Time[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Time[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Timestamp;@7af56d
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Timestamp[]' value '[Ljava.sql.Timestamp;@7af56d' to type 'java.sql.Timestamp[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Timestamp[]
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.net.URL;@f686b
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.net.URL[]' value '[Ljava.net.URL;@f686b' to type 'java.net.URL[]'
2018-01-08 16:46:54,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.net.URL[]
2018-01-08 16:46:54,468 org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) DEBUG - Convert string 'classpath:ehcache.xml' to class 'java.lang.String'
2018-01-08 16:46:54,468 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value 'classpath:ehcache.xml' to type 'String'
2018-01-08 16:46:54,468 org.apache.shiro.cache.ehcache.EhCacheManager.ensureCacheManager(EhCacheManager.java:206) DEBUG - cacheManager property not set.  Constructing CacheManager instance... 
2018-01-08 16:46:54,468 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [ehcache.xml]
2018-01-08 16:46:54,484 net.sf.ehcache.config.ConfigurationFactory.parseConfiguration(ConfigurationFactory.java:150) DEBUG - Configuring ehcache from InputStream
2018-01-08 16:46:54,484 net.sf.ehcache.config.BeanHandler.setAttribute(BeanHandler.java:271) DEBUG - Ignoring ehcache attribute xmlns:xsi
2018-01-08 16:46:54,484 net.sf.ehcache.config.BeanHandler.setAttribute(BeanHandler.java:271) DEBUG - Ignoring ehcache attribute xsi:noNamespaceSchemaLocation
2018-01-08 16:46:54,515 net.sf.ehcache.util.PropertyUtil.parseProperties(PropertyUtil.java:87) DEBUG - propertiesString is null.
2018-01-08 16:46:54,531 net.sf.ehcache.CacheManager.configure(CacheManager.java:759) WARN  - One or more caches require a DiskStore but there is no diskStore element configured. Using the default disk store path of D:\java\apache-tomcat-7.0.52\temp. Please explicitly configure the diskStore element in ehcache.xml.
2018-01-08 16:46:54,531 net.sf.ehcache.config.ConfigurationHelper.createCacheManagerEventListener(ConfigurationHelper.java:189) DEBUG - No CacheManagerEventListenerFactory class specified. Skipping...
2018-01-08 16:46:54,594 net.sf.ehcache.Cache.createBootstrapCacheLoader(Cache.java:1044) DEBUG - No BootstrapCacheLoaderFactory class specified. Skipping...
2018-01-08 16:46:54,594 net.sf.ehcache.Cache.createCacheWriter(Cache.java:1017) DEBUG - CacheWriter factory not configured. Skipping...
2018-01-08 16:46:54,594 net.sf.ehcache.config.ConfigurationHelper.createCacheExceptionHandler(ConfigurationHelper.java:100) DEBUG - No CacheExceptionHandlerFactory class specified. Skipping...
2018-01-08 16:46:54,594 net.sf.ehcache.Cache.createBootstrapCacheLoader(Cache.java:1044) DEBUG - No BootstrapCacheLoaderFactory class specified. Skipping...
2018-01-08 16:46:54,594 net.sf.ehcache.Cache.createCacheWriter(Cache.java:1017) DEBUG - CacheWriter factory not configured. Skipping...
2018-01-08 16:46:54,594 net.sf.ehcache.config.ConfigurationHelper.createCacheExceptionHandler(ConfigurationHelper.java:100) DEBUG - No CacheExceptionHandlerFactory class specified. Skipping...
2018-01-08 16:46:54,609 net.sf.ehcache.DiskStorePathManager.resolveAndLockIfNeeded(DiskStorePathManager.java:169) DEBUG - Using diskstore path D:\java\apache-tomcat-7.0.52\temp
2018-01-08 16:46:54,609 net.sf.ehcache.DiskStorePathManager.resolveAndLockIfNeeded(DiskStorePathManager.java:170) DEBUG - Holding exclusive lock on D:\java\apache-tomcat-7.0.52\temp\.ehcache-diskstore.lock
2018-01-08 16:46:54,625 net.sf.ehcache.store.disk.DiskStorageFactory.<init>(DiskStorageFactory.java:170) DEBUG - Matching data file missing (or empty) for index file. Deleting index file D:\java\apache-tomcat-7.0.52\temp\shiro-active%0053ession%0043ache.index
2018-01-08 16:46:54,687 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE
2018-01-08 16:46:54,687 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE_BYTES
2018-01-08 16:46:54,687 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: WRITER_QUEUE_LENGTH
2018-01-08 16:46:54,687 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: REMOTE_SIZE
2018-01-08 16:46:54,687 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LAST_REJOIN_TIMESTAMP
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: OFFHEAP_GET
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: OFFHEAP_PUT
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: OFFHEAP_REMOVE
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: XA_COMMIT
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: XA_ROLLBACK
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: XA_RECOVERY
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: CLUSTER_EVENT
2018-01-08 16:46:54,703 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: NONSTOP
2018-01-08 16:46:54,703 net.sf.ehcache.Cache.initialise(Cache.java:1262) DEBUG - Initialised cache: shiro-activeSessionCache
2018-01-08 16:46:54,703 net.sf.ehcache.config.ConfigurationHelper.createCacheDecorators(ConfigurationHelper.java:334) DEBUG - CacheDecoratorFactory not configured. Skipping for 'shiro-activeSessionCache'.
2018-01-08 16:46:54,703 net.sf.ehcache.config.ConfigurationHelper.createDefaultCacheDecorators(ConfigurationHelper.java:364) DEBUG - CacheDecoratorFactory not configured for defaultCache. Skipping for 'shiro-activeSessionCache'.
2018-01-08 16:46:54,719 org.apache.shiro.cache.ehcache.EhCacheManager.ensureCacheManager(EhCacheManager.java:219) DEBUG - implicit cacheManager created successfully.
2018-01-08 16:46:54,719 org.apache.shiro.config.ReflectionBuilder.resolveReference(ReflectionBuilder.java:396) DEBUG - Encountered object reference '$cacheManager'.  Looking up object with id 'cacheManager'
2018-01-08 16:46:54,719 org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:234) DEBUG - No authorizationCache instance set.  Checking for a cacheManager...
2018-01-08 16:46:54,719 org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:242) DEBUG - CacheManager [org.apache.shiro.cache.ehcache.EhCacheManager@498030] has been configured.  Building authorization cache named [nutzdao_realm.authorizationCache]
2018-01-08 16:46:54,719 org.apache.shiro.cache.ehcache.EhCacheManager.getCache(EhCacheManager.java:159) INFO  - Cache with name 'nutzdao_realm.authorizationCache' does not yet exist.  Creating now.
2018-01-08 16:46:54,734 net.sf.ehcache.store.MemoryStore.<init>(MemoryStore.java:180) DEBUG - Initialized net.sf.ehcache.store.MemoryStore for nutzdao_realm.authorizationCache
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LOCAL_OFFHEAP_SIZE_BYTES
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LOCAL_DISK_SIZE
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LOCAL_DISK_SIZE_BYTES
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: WRITER_QUEUE_LENGTH
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: REMOTE_SIZE
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardPassThruStatistics(ExtendedStatisticsImpl.java:224) DEBUG - Mocking Pass-Through Statistic: LAST_REJOIN_TIMESTAMP
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: OFFHEAP_GET
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: OFFHEAP_PUT
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: OFFHEAP_REMOVE
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: DISK_GET
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: DISK_PUT
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: DISK_REMOVE
2018-01-08 16:46:54,734 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: XA_COMMIT
2018-01-08 16:46:54,750 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: XA_ROLLBACK
2018-01-08 16:46:54,750 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: XA_RECOVERY
2018-01-08 16:46:54,750 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: CLUSTER_EVENT
2018-01-08 16:46:54,750 net.sf.ehcache.statistics.extended.ExtendedStatisticsImpl.findStandardOperationStatistics(ExtendedStatisticsImpl.java:206) DEBUG - Mocking Operation Statistic: NONSTOP
2018-01-08 16:46:54,750 net.sf.ehcache.Cache.initialise(Cache.java:1262) DEBUG - Initialised cache: nutzdao_realm.authorizationCache
2018-01-08 16:46:54,750 net.sf.ehcache.config.ConfigurationHelper.createDefaultCacheDecorators(ConfigurationHelper.java:364) DEBUG - CacheDecoratorFactory not configured for defaultCache. Skipping for 'nutzdao_realm.authorizationCache'.
2018-01-08 16:46:54,750 org.apache.shiro.cache.ehcache.EhCacheManager.getCache(EhCacheManager.java:166) INFO  - Added EhCache named [nutzdao_realm.authorizationCache]
2018-01-08 16:46:54,750 org.apache.shiro.config.ReflectionBuilder.createNewInstance(ReflectionBuilder.java:296) INFO  - An instance with name 'authc' already exists.  Redefining this object as a new instance of type org.nutz.integration.shiro.SimpleAuthenticationFilter
2018-01-08 16:46:54,750 org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) DEBUG - Convert string '/user/login' to class 'java.lang.String'
2018-01-08 16:46:54,750 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value '/user/login' to type 'String'
2018-01-08 16:46:54,765 org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) DEBUG - Convert string '/user/login' to class 'java.lang.String'
2018-01-08 16:46:54,765 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value '/user/login' to type 'String'
2018-01-08 16:46:54,765 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:122) DEBUG - Creating instance from Ini [sections=main,urls]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/rs/*] from String definition [anon]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/rs/*] to filter [anon] with config [null]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/logout] from String definition [logout]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/logout] to filter [logout] with config [null]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/error] from String definition [anon]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/error] to filter [anon] with config [null]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/login] from String definition [anon]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/login] to filter [anon] with config [null]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/profile/active/mail] from String definition [anon]
2018-01-08 16:46:54,765 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/profile/active/mail] to filter [anon] with config [null]
2018-01-08 16:46:54,765 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:136) DEBUG - Published WebEnvironment as ServletContext attribute with name [org.apache.shiro.web.env.EnvironmentLoader.ENVIRONMENT_ATTRIBUTE_KEY]
2018-01-08 16:46:54,765 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:141) INFO  - Shiro environment initialized in 512 ms.
2018-01-08 16:46:54,797 org.nutz.mvc.NutFilter._init(NutFilter.java:81) INFO  - NutFilter[nutz] starting ...
2018-01-08 16:46:54,953 org.nutz.resource.Scans.<init>(Scans.java:509) DEBUG - Locations for Scans:
[Dir[path=D:\java\apache-tomcat-7.0.52\bin], Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-1.r.60.jar], Dir[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\classes], Jar[path=D:\java\apache-tomcat-7.0.52\bin\bootstrap.jar], Jar[path=D:\java\apache-tomcat-7.0.52\bin\tomcat-juli.jar], Jar[path=D:\java\jdk7\lib\tools.jar]]
2018-01-08 16:46:55,297 org.nutz.resource.Scans.init(Scans.java:105) DEBUG - Locations for Scans:
{D:\java\apache-tomcat-7.0.52\bin=Dir[path=D:\java\apache-tomcat-7.0.52\bin], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-1.r.60.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-1.r.60.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\classes=Dir[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\classes], D:\java\apache-tomcat-7.0.52\bin\bootstrap.jar=Jar[path=D:\java\apache-tomcat-7.0.52\bin\bootstrap.jar], D:\java\apache-tomcat-7.0.52\bin\tomcat-juli.jar=Jar[path=D:\java\apache-tomcat-7.0.52\bin\tomcat-juli.jar], D:\java\jdk7\lib\tools.jar=Jar[path=D:\java\jdk7\lib\tools.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jsf-impl.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jsf-impl.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\druid-1.0.26.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\druid-1.0.26.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\slf4j-api-1.7.25.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\slf4j-api-1.7.25.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\ehcache-2.10.4.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\ehcache-2.10.4.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\quartz-2.2.3.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\quartz-2.2.3.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-jstlel-1.2.5.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-jstlel-1.2.5.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-spec-1.2.5.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-spec-1.2.5.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-integration-shiro-1.r.60.r2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-integration-shiro-1.r.60.r2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jsf-api.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jsf-api.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-impl-1.2.5.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-impl-1.2.5.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\mysql-connector-java-5.1.40.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\mysql-connector-java-5.1.40.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\log4j-1.2.17.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\log4j-1.2.17.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-compat-1.2.5.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\taglibs-standard-compat-1.2.5.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\commons-beanutils-1.9.3.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\commons-beanutils-1.9.3.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\commons-email-1.3.3.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\commons-email-1.3.3.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\slf4j-log4j12-1.7.25.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\slf4j-log4j12-1.7.25.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jstl.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jstl.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\shiro-all-1.3.2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\shiro-all-1.3.2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\javax.servlet-api-3.0.1.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\javax.servlet-api-3.0.1.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\simplecaptcha-1.2.2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\simplecaptcha-1.2.2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\javax.mail-1.5.2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\javax.mail-1.5.2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\commons-logging-1.2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\commons-logging-1.2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\shiro-ehcache-1.3.2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\shiro-ehcache-1.3.2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-plugins-daocache-1.r.63.r3.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-plugins-daocache-1.r.63.r3.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jstl-1.2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\jstl-1.2.jar], D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-integration-quartz-1.r.60.r2.jar=Jar[path=D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\lib\nutz-integration-quartz-1.r.60.r2.jar]}
2018-01-08 16:46:55,376 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 95 castor for Castors
2018-01-08 16:46:55,392 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO  - Nutz Version : 1.r.60 
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO  - Nutz.Mvc[nutz] is initializing ...
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG -  - Default Charset : GBK
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG -  - Current . path  : D:\java\apache-tomcat-7.0.52\bin\.
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG -  - Java Version    : 1.7.0_51
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG -  - File separator  : \
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG -  - Timezone        : Asia/Shanghai
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG -  - OS              : Windows 8 x86
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG -  - ServerInfo      : Apache Tomcat/7.0.52
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG -  - Servlet API     : 3.0
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG -  - ContextPath     : /nutz
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG -  - context.tempdir : D:\java\apache-tomcat-7.0.52\work\Catalina\localhost\nutz
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) DEBUG -  - MainModule      : net.wendal.nutzbook.MainModule
2018-01-08 16:46:55,407 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:238) DEBUG - >> app.root = D:/java/apache-tomcat-7.0.52/webapps/nutz
2018-01-08 16:46:55,485 org.nutz.mvc.impl.NutLoading.createIoc(NutLoading.java:379) DEBUG - @IocBy(type=org.nutz.mvc.ioc.provider.ComboIocProvider, args=["*js", "ioc/", "*anno", "net.wendal.nutzbook", "*tx", "*quartz"],init=[])
2018-01-08 16:46:55,517 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2018-01-08 16:46:55,517 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [dao.js]
2018-01-08 16:46:55,532 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [ehcache.js]
2018-01-08 16:46:55,532 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [mail.js]
2018-01-08 16:46:55,532 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:56) DEBUG - Loaded 8 bean define from path=[ioc/] --> [htmlEmail, dataSource, dao, cacheProvider, cacheManager, cacheExecutor, conf, emailAuthenticator]
2018-01-08 16:46:55,532 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 23 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-01-08 16:46:55,595 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.AuthorityModule
2018-01-08 16:46:55,626 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.CaptchaModule
2018-01-08 16:46:55,626 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2018-01-08 16:46:55,642 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2018-01-08 16:46:55,642 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob
2018-01-08 16:46:55,642 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.AuthorityServiceImpl
2018-01-08 16:46:55,642 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.EmailServiceImpl
2018-01-08 16:46:55,657 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.UserService
2018-01-08 16:46:55,657 org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:50) INFO  - Found 8 classes in 1 base-packages!
beans = ["captchaModule", "cleanNonActiveUserJob", "userProfileModule", "userService", "authorityModule", "authorityService", "emailService", "userModule"]
2018-01-08 16:46:55,657 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txREAD_COMMITTED, txNONE, txSERIALIZABLE, txREAD_UNCOMMITTED]
2018-01-08 16:46:55,673 org.nutz.ioc.loader.combo.ComboIocLoader.createIocLoader(ComboIocLoader.java:118) DEBUG - found org.nutz.integration.quartz.QuartzIocLoader -- org.nutz.integration.quartz.QuartzIocLoader
2018-01-08 16:46:55,673 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:56) DEBUG - Loaded 0 bean define from path=[] --> []
2018-01-08 16:46:55,673 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2018-01-08 16:46:55,673 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2018-01-08 16:46:55,689 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2018-01-08 16:46:55,689 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:369) DEBUG - @Views(DefaultViewMaker.class)
2018-01-08 16:46:55,704 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2018-01-08 16:46:55,704 org.nutz.mvc.impl.chainconfig.JsonActionChainMakerConfiguretion.<init>(JsonActionChainMakerConfiguretion.java:37) DEBUG - ActionChain Config:
{
   "default": {
      "ps": ["net.wendal.nutzbook.mvc.LogTimeProcessor", "org.nutz.mvc.impl.processor.UpdateRequestAttributesProcessor", "org.nutz.mvc.impl.processor.EncodingProcessor", "org.nutz.mvc.impl.processor.ModuleProcessor", "org.nutz.integration.shiro.NutShiroProcessor", "org.nutz.mvc.impl.processor.ActionFiltersProcessor", "org.nutz.mvc.impl.processor.AdaptorProcessor", "org.nutz.mvc.impl.processor.MethodInvokeProcessor", "org.nutz.mvc.impl.processor.ViewProcessor"],
      "error": "org.nutz.mvc.impl.processor.FailProcessor"
   }
}
2018-01-08 16:46:55,704 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:266) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2018-01-08 16:46:55,720 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/D:/java/apache-tomcat-7.0.52/webapps/nutz/WEB-INF/classes/net/wendal/nutzbook/MainModule.class'
2018-01-08 16:46:55,720 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2018-01-08 16:46:55,720 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 23 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-01-08 16:46:55,720 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.AuthorityModule'
2018-01-08 16:46:55,720 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.CaptchaModule'
2018-01-08 16:46:55,720 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2018-01-08 16:46:55,720 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2018-01-08 16:46:55,767 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: D:/java/apache-tomcat-7.0.52/webapps/nutz/WEB-INF/tmp/user_avatar [20000]
2018-01-08 16:46:55,767 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'D:\java\apache-tomcat-7.0.52\webapps\nutz\WEB-INF\tmp\user_avatar'
2018-01-08 16:46:55,767 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:113).uploadAvatar : void       | @Ok(>>:/user/profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:162).activeMail : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:143).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:191).activeMailCallback : String     | @Ok(raw  ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:54).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:154).index    : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,798 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/delete'      >> (UserProfileModule.java:96).delete    : Object     | @Ok(json:full) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,814 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:69).update    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,814 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:66).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,814 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:85).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,814 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:56).loginPage        : void       | @Ok(jsp:jsp.user.login) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,829 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:91).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,829 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:49).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,829 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/'                    >> (UserModule.java:136).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,829 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:113).delete          : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,829 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:124).query           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,829 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:103).update          : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 1 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/roles'    >> (AuthorityModule.java:70).roles       : Object     | @Ok(json ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/users'    >> (AuthorityModule.java:58).users       : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/role/add' >> (AuthorityModule.java:203).addRole    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/permission/add' >> (AuthorityModule.java:309).addPermission : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/user/update' >> (AuthorityModule.java:106).updateUser : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/role/delete' >> (AuthorityModule.java:219).delRole    : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/permission/delete' >> (AuthorityModule.java:326).delPermission : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/role/update' >> (AuthorityModule.java:243).updateRole : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/permission/update' >> (AuthorityModule.java:343).updateRole : void       | @Ok(void ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/user/fetch/role' >> (AuthorityModule.java:175).fetchUserRoles : Object     | @Ok(json ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/user/fetch/permission' >> (AuthorityModule.java:153).fetchUserPermissions : Object     | @Ok(json ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/role/fetch' >> (AuthorityModule.java:281).fetchRolePermissions : Object     | @Ok(json ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/admin/authority/permissions' >> (AuthorityModule.java:82).permissions : Object     | @Ok(json ) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/captcha/next'             >> (CaptchaModule.java:23).next          : BufferedImage | @Ok(raw:png) @Fail(jsp:jsp.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2018-01-08 16:46:55,845 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:220) INFO  - Found 31 module methods
2018-01-08 16:46:55,845 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:306) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2018-01-08 16:46:55,860 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2018-01-08 16:46:55,860 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[NutResource[zh-CN/user.properties]]]
2018-01-08 16:46:55,860 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2018-01-08 16:46:55,860 org.nutz.mvc.impl.NutLoading.createSessionProvider(NutLoading.java:413) INFO  - SessionBy --> org.nutz.integration.shiro.ShiroSessionProvider@7dcf00
2018-01-08 16:46:55,860 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274) INFO  - Setup application...
2018-01-08 16:46:55,860 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2018-01-08 16:46:55,860 org.nutz.ioc.aop.SimpleAopMaker.<init>(SimpleAopMaker.java:79) DEBUG - Load AopConfigure for anno=org.nutz.ioc.aop.Aop by type=org.nutz.ioc.aop.config.impl.AnnotationAopConfigration
2018-01-08 16:46:55,860 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2018-01-08 16:46:55,860 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2018-01-08 16:46:55,860 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:55,860 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2018-01-08 16:46:55,876 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2018-01-08 16:46:55,876 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2018-01-08 16:46:55,892 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-01-08 16:46:55,892 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2018-01-08 16:46:55,892 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2018-01-08 16:46:55,892 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:55,892 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2018-01-08 16:46:55,907 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2018-01-08 16:46:55,907 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2018-01-08 16:46:55,907 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:55,907 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2018-01-08 16:46:55,907 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2018-01-08 16:46:55,907 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:55,907 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2018-01-08 16:46:55,907 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2018-01-08 16:46:55,907 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2018-01-08 16:46:55,923 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2018-01-08 16:46:55,985 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2018-01-08 16:46:55,985 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2018-01-08 16:46:56,017 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: C:\Users\acer/.nutz/tmp/dao/ [200000]
2018-01-08 16:46:56,017 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'C:\Users\acer\.nutz\tmp\dao'
2018-01-08 16:46:56,017 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2018-01-08 16:46:56,017 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2018-01-08 16:46:56,017 org.nutz.dao.jdbc.Jdbcs.getExpert(Jdbcs.java:103) INFO  - Get Connection from DataSource for JdbcExpert, if you lock at here, check your database server and configure
2018-01-08 16:46:56,220 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2018-01-08 16:46:56,220 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.1.40 ( Revision: 402933ef52cad9aa82624e80acbea46e3a701ce6 )
2018-01-08 16:46:56,220 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL Connector Java
2018-01-08 16:46:56,220 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://127.0.0.1:3306/nutz
2018-01-08 16:46:56,298 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_client=utf8
2018-01-08 16:46:56,314 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_connection=utf8
2018-01-08 16:46:56,314 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_database=utf8
2018-01-08 16:46:56,314 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_filesystem=binary
2018-01-08 16:46:56,314 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_results=
2018-01-08 16:46:56,315 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_server=utf8
2018-01-08 16:46:56,315 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_system=utf8
2018-01-08 16:46:56,315 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_sets_dir=D:\java\mysql\share\charsets\
2018-01-08 16:46:56,315 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:219) DEBUG - Mysql : binlog_format=STATEMENT
2018-01-08 16:46:56,315 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : database=nutz
2018-01-08 16:46:56,315 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:232) DEBUG - Mysql : user=root@localhost
2018-01-08 16:46:56,347 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.5.40]
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'cacheExecutor'<>
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=cacheExecutor
2018-01-08 16:46:56,347 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=cacheExecutor
2018-01-08 16:46:56,347 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(cacheExecutor) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'cacheExecutor'<>
2018-01-08 16:46:56,347 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.plugins.cache.dao.DaoCacheInterceptor without AOP
2018-01-08 16:46:56,347 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'cacheExecutor' to [app] 
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'cacheProvider'<>
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=cacheProvider
2018-01-08 16:46:56,347 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=cacheProvider
2018-01-08 16:46:56,347 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(cacheProvider) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'cacheProvider'<>
2018-01-08 16:46:56,347 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.plugins.cache.dao.impl.provider.EhcacheDaoCacheProvider without AOP
2018-01-08 16:46:56,347 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'cacheProvider' to [app] 
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'cacheManager'<>
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=cacheManager
2018-01-08 16:46:56,347 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=cacheManager
2018-01-08 16:46:56,347 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(cacheManager) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:56,347 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'cacheManager'<>
2018-01-08 16:46:56,362 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class net.sf.ehcache.CacheManager without AOP
2018-01-08 16:46:56,362 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'cacheManager' to [app] 
2018-01-08 16:46:56,362 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 23 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-01-08 16:46:56,440 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2018-01-08 16:46:56,440 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-01-08 16:46:56,440 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=nutQuartzCronJobFactory
2018-01-08 16:46:56,440 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=nutQuartzCronJobFactory
2018-01-08 16:46:56,440 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(nutQuartzCronJobFactory) in QuartzIocLoader@22204695
2018-01-08 16:46:56,440 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'nutQuartzCronJobFactory'<class org.nutz.integration.quartz.NutQuartzCronJobFactory>
2018-01-08 16:46:56,440 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzCronJobFactory without AOP
2018-01-08 16:46:56,440 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'nutQuartzCronJobFactory' to [app] 
2018-01-08 16:46:56,440 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'scheduler'<>
2018-01-08 16:46:56,440 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=scheduler
2018-01-08 16:46:56,440 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=scheduler
2018-01-08 16:46:56,440 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(scheduler) in QuartzIocLoader@22204695
2018-01-08 16:46:56,440 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'scheduler'<>
2018-01-08 16:46:56,440 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'scheduler' to [app] 
2018-01-08 16:46:56,472 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1172) INFO  - Using default implementation for ThreadExecutor
2018-01-08 16:46:56,503 org.quartz.core.SchedulerSignalerImpl.<init>(SchedulerSignalerImpl.java:61) INFO  - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2018-01-08 16:46:56,503 org.quartz.core.QuartzScheduler.<init>(QuartzScheduler.java:240) INFO  - Quartz Scheduler v.2.2.3 created.
2018-01-08 16:46:56,503 org.quartz.simpl.RAMJobStore.initialize(RAMJobStore.java:155) INFO  - RAMJobStore initialized.
2018-01-08 16:46:56,503 org.quartz.core.QuartzScheduler.initialize(QuartzScheduler.java:305) INFO  - Scheduler meta-data: Quartz Scheduler (v2.2.3) 'NutzbookScheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2018-01-08 16:46:56,503 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1327) INFO  - Quartz scheduler 'NutzbookScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2018-01-08 16:46:56,503 org.quartz.impl.StdSchedulerFactory.instantiate(StdSchedulerFactory.java:1331) INFO  - Quartz scheduler version: 2.2.3
2018-01-08 16:46:56,503 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'jobFactory'<>
2018-01-08 16:46:56,503 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=jobFactory
2018-01-08 16:46:56,503 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=jobFactory
2018-01-08 16:46:56,503 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(jobFactory) in QuartzIocLoader@22204695
2018-01-08 16:46:56,503 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'jobFactory'<>
2018-01-08 16:46:56,503 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.integration.quartz.NutQuartzJobFactory without AOP
2018-01-08 16:46:56,503 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'jobFactory' to [app] 
2018-01-08 16:46:56,503 org.quartz.core.QuartzScheduler.setJobFactory(QuartzScheduler.java:2311) INFO  - JobFactory set to: org.nutz.integration.quartz.NutQuartzJobFactory@32bc0b
2018-01-08 16:46:56,503 org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:575) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED started.
2018-01-08 16:46:56,503 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2018-01-08 16:46:56,503 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,503 org.nutz.integration.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:35) DEBUG - job define name=net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob cron=0 0/2 * * * ?
2018-01-08 16:46:56,518 org.nutz.integration.quartz.NutQuartzCronJobFactory.init(NutQuartzCronJobFactory.java:28) DEBUG - found cron job packages = net.wendal.nutzbook.quartz.job
2018-01-08 16:46:56,518 org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:276) DEBUG - batch acquisition of 0 triggers
2018-01-08 16:46:56,518 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( net/wendal/nutzbook/quartz/job/ ) , regex( ^.+[.]class$ )
2018-01-08 16:46:56,518 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'htmlEmail'<class org.apache.commons.mail.HtmlEmail>
2018-01-08 16:46:56,518 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=htmlEmail
2018-01-08 16:46:56,518 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=htmlEmail
2018-01-08 16:46:56,518 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(htmlEmail) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:56,518 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'htmlEmail'<class org.apache.commons.mail.HtmlEmail>
2018-01-08 16:46:56,534 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.apache.commons.mail.ImageHtmlEmail without AOP
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'emailAuthenticator'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=emailAuthenticator
2018-01-08 16:46:56,550 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=emailAuthenticator
2018-01-08 16:46:56,550 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(emailAuthenticator) in JsonLoader(paths=[ioc/])
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'emailAuthenticator'<>
2018-01-08 16:46:56,550 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.apache.commons.mail.DefaultAuthenticator without AOP
2018-01-08 16:46:56,550 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'emailAuthenticator' to [app] 
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:56,550 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2018-01-08 16:46:57,597 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'authorityService'<interface net.wendal.nutzbook.service.AuthorityService>
2018-01-08 16:46:57,597 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=authorityService
2018-01-08 16:46:57,607 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(authorityService) in AnnotationIocLoader(packages=[net.wendal.nutzbook])
2018-01-08 16:46:57,607 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'authorityService'<interface net.wendal.nutzbook.service.AuthorityService>
2018-01-08 16:46:57,607 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class net.wendal.nutzbook.service.AuthorityServiceImpl without AOP
2018-01-08 16:46:57,608 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'authorityService' to [app] 
2018-01-08 16:46:57,608 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2018-01-08 16:46:57,623 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 23 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2018-01-08 16:46:57,639 net.wendal.nutzbook.service.AuthorityServiceImpl.initFormPackage(AuthorityServiceImpl.java:95) DEBUG - found 14 permission
2018-01-08 16:46:57,639 net.wendal.nutzbook.service.AuthorityServiceImpl.initFormPackage(AuthorityServiceImpl.java:96) DEBUG - found 0 role
2018-01-08 16:46:57,655 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT * FROM t_permission 
2018-01-08 16:46:57,655 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT * FROM t_role 
2018-01-08 16:46:57,670 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT * FROM t_user  WHERE name=?
    |     1 |
    |-------|
    | admin |
  For example:> "SELECT * FROM t_user  WHERE name='admin'"
2018-01-08 16:46:57,670 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT * FROM t_role  WHERE name=?
    |     1 |
    |-------|
    | admin |
  For example:> "SELECT * FROM t_role  WHERE name='admin'"
2018-01-08 16:46:57,670 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user_role  WHERE u_id=? AND role_id=?
    | 1 | 2 |
    |---|---|
    | 1 | 1 |
  For example:> "SELECT COUNT(*) FROM t_user_role  WHERE u_id=1 AND role_id=1"
2018-01-08 16:46:57,686 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT * FROM t_role_permission  WHERE role_id=?
    | 1 |
    |---|
    | 1 |
  For example:> "SELECT * FROM t_role_permission  WHERE role_id=1"
2018-01-08 16:46:57,686 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT * FROM t_permission  WHERE name LIKE ? OR name LIKE ? OR name LIKE ?
    |           1 |      2 |       3 |
    |-------------|--------|---------|
    | authority:% | user:% | topic:% |
  For example:> "SELECT * FROM t_permission  WHERE name LIKE 'authority:%' OR name LIKE 'user:%' OR name LIKE 'topic:%'"
2018-01-08 16:46:57,686 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'cacheManager'<class net.sf.ehcache.CacheManager>
2018-01-08 16:46:57,686 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:125) ERROR - Error happend during start serivce!
java.lang.NullPointerException
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:63)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:277)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:87)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:65)
	at org.apache.catalina.core.ApplicationFilterConfig.initFilter(ApplicationFilterConfig.java:279)
	at org.apache.catalina.core.ApplicationFilterConfig.getFilter(ApplicationFilterConfig.java:260)
	at org.apache.catalina.core.ApplicationFilterConfig.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4809)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5485)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:632)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1229)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1875)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:744)
2018-01-08 16:46:57,686 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:127) ERROR - try to depose ioc
2018-01-08 16:46:57,686 org.nutz.ioc.impl.NutIoc.depose(NutIoc.java:255) INFO  - org.nutz.ioc.impl.NutIoc@29618102 is closing. startup date [18-01-08 16:46:55.673]
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'authorityService' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'emailAuthenticator' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'jobFactory' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'scheduler' ...
2018-01-08 16:46:57,686 org.quartz.core.QuartzScheduler.shutdown(QuartzScheduler.java:694) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED shutting down.
2018-01-08 16:46:57,686 org.quartz.core.QuartzScheduler.standby(QuartzScheduler.java:613) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED paused.
2018-01-08 16:46:57,686 org.quartz.simpl.SimpleThreadPool.shutdown(SimpleThreadPool.java:328) DEBUG - Shutting down threadpool...
2018-01-08 16:46:57,686 org.quartz.simpl.SimpleThreadPool.shutdown(SimpleThreadPool.java:395) DEBUG - Shutdown of threadpool complete.
2018-01-08 16:46:57,686 org.quartz.core.QuartzScheduler.shutdown(QuartzScheduler.java:771) INFO  - Scheduler NutzbookScheduler_$_NON_CLUSTERED shutdown complete.
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'nutQuartzCronJobFactory' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'cacheManager' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'cacheProvider' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'cacheExecutor' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'conf' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:108) DEBUG - Depose object 'dao' ...
2018-01-08 16:46:57,686 org.nutz.ioc.impl.ScopeContext.clear(ScopeContext.java:113) DEBUG - Depose object 'dataSource' ...
2018-01-08 16:46:57,686 com.alibaba.druid.pool.DruidDataSource.close(DruidDataSource.java:1444) INFO  - {dataSource-1} closed
2018-01-08 16:46:57,701 org.nutz.ioc.impl.NutIoc.depose(NutIoc.java:262) INFO  - org.nutz.ioc.impl.NutIoc@29618102 is deposed. startup date [18-01-08 16:46:55.673]
一月 08, 2018 4:46:57 下午 org.apache.catalina.core.StandardContext filterStart
严重: Exception starting filter nutz
org.nutz.mvc.LoadingException: java.lang.NullPointerException
	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
	at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
	at org.nutz.lang.born.ConstructorBorning.born(ConstructorBorning.java:17)
	at org.nutz.lang.born.BornContext.doBorn(BornContext.java:60)
	at org.nutz.lang.Mirror.born(Mirror.java:990)
	at org.nutz.lang.Lang.wrapThrow(Lang.java:197)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:135)
	at org.nutz.mvc.ActionHandler.<init>(ActionHandler.java:19)
	at org.nutz.mvc.NutFilter._init(NutFilter.java:87)
	at org.nutz.mvc.NutFilter.init(NutFilter.java:65)
	at org.apache.catalina.core.ApplicationFilterConfig.initFilter(ApplicationFilterConfig.java:279)
	at org.apache.catalina.core.ApplicationFilterConfig.getFilter(ApplicationFilterConfig.java:260)
	at org.apache.catalina.core.ApplicationFilterConfig.<init>(ApplicationFilterConfig.java:105)
	at org.apache.catalina.core.StandardContext.filterStart(StandardContext.java:4809)
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5485)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:632)
	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1229)
	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1875)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:744)
Caused by: java.lang.NullPointerException
	at net.wendal.nutzbook.MainSetup.init(MainSetup.java:63)
	at org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:277)
	at org.nutz.mvc.impl.NutLoading.load(NutLoading.java:121)
	... 19 more
一月 08, 2018 4:46:57 下午 org.apache.catalina.core.StandardContext startInternal
严重: Error filterStart
一月 08, 2018 4:46:57 下午 org.apache.catalina.core.StandardContext startInternal
严重: Context [/nutz] startup failed due to previous errors
一月 08, 2018 4:46:57 下午 org.apache.catalina.core.ApplicationContext log
信息: Cleaning up Shiro Environment
一月 08, 2018 4:46:57 下午 org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
严重: The web application [/nutz] registered the JDBC driver [com.alibaba.druid.proxy.DruidDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
一月 08, 2018 4:46:57 下午 org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
严重: The web application [/nutz] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
一月 08, 2018 4:46:57 下午 org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
严重: The web application [/nutz] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
一月 08, 2018 4:46:57 下午 org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
严重: The web application [/nutz] appears to have started a thread named [NutzbookScheduler_Worker-1] but has failed to stop it. This is very likely to create a memory leak.
一月 08, 2018 4:46:57 下午 org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
严重: The web application [/nutz] appears to have started a thread named [NutzbookScheduler_Worker-2] but has failed to stop it. This is very likely to create a memory leak.
一月 08, 2018 4:46:57 下午 org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
严重: The web application [/nutz] appears to have started a thread named [NutzbookScheduler_Worker-3] but has failed to stop it. This is very likely to create a memory leak.
一月 08, 2018 4:46:57 下午 org.apache.catalina.startup.HostConfig deployDirectory
信息: Deploying web application directory D:\java\apache-tomcat-7.0.52\webapps\ROOT
一月 08, 2018 4:46:57 下午 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["http-bio-8080"]
一月 08, 2018 4:46:57 下午 org.apache.coyote.AbstractProtocol start
信息: Starting ProtocolHandler ["ajp-bio-8009"]
一月 08, 2018 4:46:57 下午 org.apache.catalina.startup.Catalina start
信息: Server startup in 6168 ms
2018-01-08 16:46:58,001 org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:612) DEBUG - WorkerThread is shut down.
2018-01-08 16:46:58,001 org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:612) DEBUG - WorkerThread is shut down.
2018-01-08 16:46:58,001 org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:612) DEBUG - WorkerThread is shut down.

@wendal 已解决！！ 万分感谢！！ 总感觉这里有点问题。。。