Quartz定期清理未激活用户不好用。启动的时候也不加载日志。不知道为啥

这个是cron.properties

# clean non-active user
# 一分钟检测一次
cron.net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob=0 */1 * * * ?
cron.pkgs=net.wendal.nutzbook.quartz.job

这个是CleanNonActiveUserJob任务类

package net.wendal.nutzbook.quartz.job;

import java.util.Date;

import net.wendal.nutzbook.bean.User;
import net.wendal.nutzbook.bean.UserProfile;

import org.nutz.dao.Cnd;
import org.nutz.dao.Dao;
import org.nutz.dao.Sqls;
import org.nutz.dao.sql.Sql;
import org.nutz.ioc.loader.annotation.Inject;
import org.nutz.ioc.loader.annotation.IocBean;
import org.nutz.log.Log;
import org.nutz.log.Logs;
import org.quartz.Job;
import org.quartz.JobExecutionContext;
import org.quartz.JobExecutionException;

@IocBean
public class CleanNonActiveUserJob implements Job {

    private static final Log log = Logs.get();

    @Inject protected Dao dao;

    public void execute(JobExecutionContext context) throws JobExecutionException {
        log.debug("clean Non-Active User , start");
        Date deadtime = new Date(System.currentTimeMillis() - 60*1000L); // 测试时间一分钟
        Cnd cnd = Cnd.where("userId", ">", 10).and("createTime", "<", deadtime).and(Cnd.exps("emailChecked", "=", false).or("email", "IS", null));
        int deleted = dao.clear(UserProfile.class, cnd);
        log.debugf("delete %d UserProfile", deleted);

        Sql sql = Sqls.create("delete from $user_table where id > 10 and not exists (select 1 from $user_profile_table where $user_table.id = uid ) and ct < @deadtime");
        sql.vars().set("user_table", dao.getEntity(User.class).getTableName());
        sql.vars().set("user_profile_table", dao.getEntity(UserProfile.class).getTableName());
        sql.params().set("deadtime", deadtime);
        dao.execute(sql);
        log.debugf("delete %d User", sql.getUpdateCount());

        log.debug("clean Non-Active User , Done");
    }
}

这个是MainSetup中的代码

public class MainSetup implements Setup {

    public void init(NutConfig nc) { //使用Ioc
        Ioc ncIoc = nc.getIoc();
        Dao dao = ncIoc.get(Dao.class);
        Daos.createTablesInPackage(dao, "net.wendal.nutzbook", false);
        Daos.migration(dao, User.class, true, false, false);

        // 初始化默认根用户
        if (dao.count(User.class) == 0) {
            UserServiceImpl us = ncIoc.get(UserServiceImpl.class);
            us.add("admin", "123456");
            // 获取NutQuartzCronJobFactory从而触发计划任务的初始化与启动。就是启动定时器
            ncIoc.get(NutQuartzCronJobFactory.class);
            // 测试发送邮件
            try {
                HtmlEmail email = ncIoc.get(HtmlEmail.class);
                email.setSubject("测试NutzBook");
                email.setMsg("This is a test mail ... :-)" + System.currentTimeMillis());
                email.addTo("j13130254758@163.com");//请务必改成您自己的邮箱啊!!!
                email.buildMimeMessage();
                email.sendMimeMessage();
            } catch (Exception e) {
                e.printStackTrace();
            }
        }


    }


    public void destroy(NutConfig nc) {

    }
}

这个是pom文件

<?xml version="1.0" encoding="UTF-8"?>
<project xmlns="http://maven.apache.org/POM/4.0.0"
         xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xsi:schemaLocation="http://maven.apache.org/POM/4.0.0 http://maven.apache.org/xsd/maven-4.0.0.xsd">
    <modelVersion>4.0.0</modelVersion>

    <groupId>com.itcast</groupId>
    <artifactId>nutzbook</artifactId>
    <version>1.0-SNAPSHOT</version>
    <packaging>war</packaging>
    <name>demo Maven Webapp</name>
    <!-- FIXME change it to the project's website -->
    <url>http://www.example.com</url>



    <dependencies>


        <dependency>
            <groupId>junit</groupId>
            <artifactId>junit</artifactId>
            <version>4.11</version>
            <scope>test</scope>
        </dependency>

        <dependency>
            <groupId>org.nutz</groupId>
            <artifactId>nutz</artifactId>
            <version>1.r.60</version>
        </dependency>

        <dependency>
            <groupId>com.alibaba</groupId>
            <artifactId>druid</artifactId>
            <version>1.0.26</version>
            <exclusions>
                <exclusion>
                    <artifactId>jconsole</artifactId>
                    <groupId>com.alibaba</groupId>
                </exclusion>
                <exclusion>
                    <artifactId>tools</artifactId>
                    <groupId>com.alibaba</groupId>
                </exclusion>
            </exclusions>
        </dependency>

        <dependency>
            <groupId>mysql</groupId>
            <artifactId>mysql-connector-java</artifactId>
            <version>5.1.47</version>
        </dependency>

        <dependency>
            <groupId>log4j</groupId>
            <artifactId>log4j</artifactId>
            <version>1.2.17</version>
        </dependency>

        <dependency>
            <groupId>javax.servlet</groupId>
            <artifactId>servlet-api</artifactId>
            <version>2.5</version>
        </dependency>

        <dependency>
            <groupId>org.apache.shiro</groupId>
            <artifactId>shiro-all</artifactId>
            <version>1.4.0</version>
            <exclusions>
                <exclusion>
                    <groupId>org.apache.shiro</groupId>
                    <artifactId>shiro-quartz</artifactId>
                </exclusion>
            </exclusions>
        </dependency>

        <dependency>
            <groupId>org.slf4j</groupId>
            <artifactId>slf4j-api</artifactId>
            <version>1.7.7</version>
        </dependency>

        <dependency>
            <groupId>org.slf4j</groupId>
            <artifactId>slf4j-log4j12</artifactId>
            <version>1.7.25</version>
        </dependency>

        <!-- https://mvnrepository.com/artifact/commons-beanutils/commons-beanutils -->
        <dependency>
            <groupId>commons-beanutils</groupId>
            <artifactId>commons-beanutils</artifactId>
            <version>1.9.3</version>
        </dependency>



        <dependency>
            <groupId>commons-logging</groupId>
            <artifactId>commons-logging</artifactId>
            <version>1.1.1</version>
        </dependency>

        <dependency>
            <groupId>org.nutz</groupId>
            <artifactId>nutz-integration-shiro</artifactId>
            <version>1.r.66</version>
        </dependency>

        <dependency>
            <groupId>org.quartz-scheduler</groupId>
            <artifactId>quartz</artifactId>
            <version>2.3.0</version>
        </dependency>

        <dependency>
            <groupId>org.nutz</groupId>
            <artifactId>nutz-integration-quartz</artifactId>
            <version>1.r.66</version>
        </dependency>

        <dependency>
            <groupId>jstl</groupId>
            <artifactId>jstl</artifactId>
            <version>1.2</version>
        </dependency>

        <dependency>
            <groupId>taglibs</groupId>
            <artifactId>standard</artifactId>
            <version>1.1.2</version>
        </dependency>

        <!-- https://mvnrepository.com/artifact/com.sun.mail/javax.mail -->
        <dependency>
            <groupId>com.sun.mail</groupId>
            <artifactId>javax.mail</artifactId>
            <version>1.6.2</version>
        </dependency>

        <!-- https://mvnrepository.com/artifact/org.apache.commons/commons-email -->
        <dependency>
            <groupId>org.apache.commons</groupId>
            <artifactId>commons-email</artifactId>
            <version>1.5</version>
        </dependency>

        <!-- https://mvnrepository.com/artifact/cn.apiclub.tool/simplecaptcha -->
        <dependency>
            <groupId>cn.apiclub.tool</groupId>
            <artifactId>simplecaptcha</artifactId>
            <version>1.2.2</version>
        </dependency>

        <!-- https://mvnrepository.com/artifact/javax.mail/javax.mail-api -->
        <dependency>
            <groupId>javax.mail</groupId>
            <artifactId>javax.mail-api</artifactId>
            <version>1.6.2</version>
        </dependency>

        <!-- https://mvnrepository.com/artifact/commons-io/commons-io -->
        <dependency>
            <groupId>commons-io</groupId>
            <artifactId>commons-io</artifactId>
            <version>2.6</version>
        </dependency>




    </dependencies>

    <build>
        <finalName>demo</finalName>
        <pluginManagement><!-- lock down plugins versions to avoid using Maven defaults (may be moved to parent pom) -->
            <plugins>
                <plugin>
                    <artifactId>maven-clean-plugin</artifactId>
                    <version>3.1.0</version>
                </plugin>
                <!-- see http://maven.apache.org/ref/current/maven-core/default-bindings.html#Plugin_bindings_for_war_packaging -->
                <plugin>
                    <artifactId>maven-resources-plugin</artifactId>
                    <version>3.0.2</version>
                </plugin>
                <plugin>
                    <artifactId>maven-compiler-plugin</artifactId>
                    <version>3.8.0</version>
                </plugin>
                <plugin>
                    <artifactId>maven-surefire-plugin</artifactId>
                    <version>2.22.1</version>
                </plugin>
                <plugin>
                    <artifactId>maven-war-plugin</artifactId>
                    <version>3.2.2</version>
                </plugin>
                <plugin>
                    <artifactId>maven-install-plugin</artifactId>
                    <version>2.5.2</version>
                </plugin>
                <plugin>
                    <artifactId>maven-deploy-plugin</artifactId>
                    <version>2.8.2</version>
                </plugin>
            </plugins>

        </pluginManagement>
    </build>
</project>

wx_p064hjeoh9l2g0cqspro 1楼•1712天前

这个是整个项目启动的日志信息

D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\catalina.bat run
[2019-08-12 11:03:22,566] Artifact nutzbook:war exploded: Waiting for server connection to start artifact deployment...
Using CATALINA_BASE:   "C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook"
Using CATALINA_HOME:   "D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14"
Using CATALINA_TMPDIR: "D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\temp"
Using JRE_HOME:        "D:\JavaJdk"
Using CLASSPATH:       "D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\bootstrap.jar;D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\tomcat-juli.jar"
12-Aug-2019 11:03:23.739 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Server.服务器版本:        Apache Tomcat/9.0.14
12-Aug-2019 11:03:23.744 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Server.构建:          Dec 6 2018 21:13:53 UTC
12-Aug-2019 11:03:23.745 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.14.0
12-Aug-2019 11:03:23.745 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name:               Windows 10
12-Aug-2019 11:03:23.745 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log OS.版本:            10.0
12-Aug-2019 11:03:23.745 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log 结.造:          amd64
12-Aug-2019 11:03:23.745 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Java 环境变量:             D:\JavaJdk
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log JVM 版本:           9.0.4+11
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log JVM.供应商:            Oracle Corporation
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE:         C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME:         D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.lang=ALL-UNNAMED
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.io=ALL-UNNAMED
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED
12-Aug-2019 11:03:23.746 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook\conf\logging.properties
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dfile.encoding=UTF-8
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote=
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.port=1099
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.ssl=false
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.password.file=C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook\jmxremote.password
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcom.sun.management.jmxremote.access.file=C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook\jmxremote.access
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.rmi.server.hostname=127.0.0.1
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
12-Aug-2019 11:03:23.747 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs=
12-Aug-2019 11:03:23.749 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook
12-Aug-2019 11:03:23.750 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14
12-Aug-2019 11:03:23.750 信息 [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\temp
12-Aug-2019 11:03:23.750 信息 [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: [D:\JavaJdk\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;D:\dev_soft\IntelliJ IDEA 2019.2\jbr\\bin;D:\dev_soft\IntelliJ IDEA 2019.2\jbr\\bin\server;D:\JavaJdk\bin;C:\ProgramData\Oracle\Java\javapath;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;D:\dev_soft\apache-maven-3.6.1\bin;D:\mysql-5.7.20-winx64\bin;C:\Users\Administrator\AppData\Local\Microsoft\WindowsApps;;.]
12-Aug-2019 11:03:23.799 信息 [main] org.apache.coyote.AbstractProtocol.init 初始化协议处理器 ["http-nio-8080"]
12-Aug-2019 11:03:23.824 信息 [main] org.apache.coyote.AbstractProtocol.init 初始化协议处理器 ["ajp-nio-8009"]
12-Aug-2019 11:03:23.830 信息 [main] org.apache.catalina.startup.Catalina.load 服务器在[544]毫秒内初始化
12-Aug-2019 11:03:23.916 信息 [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
12-Aug-2019 11:03:23.916 信息 [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.14]
12-Aug-2019 11:03:23.932 信息 [main] org.apache.coyote.AbstractProtocol.start 开始协议处理句柄["http-nio-8080"]
12-Aug-2019 11:03:23.946 信息 [main] org.apache.coyote.AbstractProtocol.start 开始协议处理句柄["ajp-nio-8009"]
12-Aug-2019 11:03:23.951 信息 [main] org.apache.catalina.startup.Catalina.start Server startup in [120] milliseconds
Connected to server
[2019-08-12 11:03:24,250] Artifact nutzbook:war exploded: Artifact is being deployed, please wait...
12-Aug-2019 11:03:26.222 信息 [RMI TCP Connection(3)-127.0.0.1] org.apache.jasper.servlet.TldScanner.scanJars 至少有一个JAR被扫描用于TLD但尚未包含TLD。 为此记录器启用调试日志记录，以获取已扫描但未在其中找到TLD的完整JAR列表。 在扫描期间跳过不需要的JAR可以缩短启动时间和JSP编译时间。
2019-08-12 11:03:26,370 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:133) INFO  - Starting Shiro environment initialization.
2019-08-12 11:03:26,413 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: false
2019-08-12 11:03:26,413 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean' value 'false' to type 'Boolean'
2019-08-12 11:03:26,413 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean
2019-08-12 11:03:26,415 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,415 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Byte'
2019-08-12 11:03:26,416 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Byte value '0'
2019-08-12 11:03:26,416 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value:  
2019-08-12 11:03:26,417 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character' value ' ' to type 'Character'
2019-08-12 11:03:26,417 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character
2019-08-12 11:03:26,418 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,418 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Double'
2019-08-12 11:03:26,418 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Double value '0.0'
2019-08-12 11:03:26,419 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,419 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Float'
2019-08-12 11:03:26,419 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Float value '0.0'
2019-08-12 11:03:26,421 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,421 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Integer'
2019-08-12 11:03:26,421 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer
2019-08-12 11:03:26,422 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,422 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Long'
2019-08-12 11:03:26,422 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Long value '0'
2019-08-12 11:03:26,423 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,424 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Short'
2019-08-12 11:03:26,424 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Short value '0'
2019-08-12 11:03:26,428 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0.0
2019-08-12 11:03:26,429 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigDecimal' value '0.0' to type 'BigDecimal'
2019-08-12 11:03:26,429 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigDecimal
2019-08-12 11:03:26,430 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,430 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigInteger' value '0' to type 'BigInteger'
2019-08-12 11:03:26,430 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigInteger
2019-08-12 11:03:26,431 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: false
2019-08-12 11:03:26,431 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean' value 'false' to type 'Boolean'
2019-08-12 11:03:26,431 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean
2019-08-12 11:03:26,432 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,432 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Byte'
2019-08-12 11:03:26,432 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Byte value '0'
2019-08-12 11:03:26,433 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value:  
2019-08-12 11:03:26,434 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character' value ' ' to type 'Character'
2019-08-12 11:03:26,434 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character
2019-08-12 11:03:26,434 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,435 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Double'
2019-08-12 11:03:26,435 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Double value '0.0'
2019-08-12 11:03:26,435 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,436 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Float'
2019-08-12 11:03:26,436 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Float value '0.0'
2019-08-12 11:03:26,436 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,438 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Integer'
2019-08-12 11:03:26,438 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer
2019-08-12 11:03:26,438 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,439 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Long'
2019-08-12 11:03:26,439 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Long value '0'
2019-08-12 11:03:26,439 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 0
2019-08-12 11:03:26,439 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer' value '0' to type 'Short'
2019-08-12 11:03:26,440 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:171) DEBUG -     Converted to Short value '0'
2019-08-12 11:03:26,442 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: 
2019-08-12 11:03:26,443 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value '' to type 'String'
2019-08-12 11:03:26,449 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Z@6e5506ff
2019-08-12 11:03:26,449 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'boolean[]' value '[Z@6e5506ff' to type 'boolean[]'
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a boolean[]
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [B@180e9868
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'byte[]' value '[B@180e9868' to type 'byte[]'
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a byte[]
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [C@70dd5507
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'char[]' value '[C@70dd5507' to type 'char[]'
2019-08-12 11:03:26,450 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a char[]
2019-08-12 11:03:26,451 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [D@501b0a56
2019-08-12 11:03:26,451 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'double[]' value '[D@501b0a56' to type 'double[]'
2019-08-12 11:03:26,451 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a double[]
2019-08-12 11:03:26,451 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [F@163f8c54
2019-08-12 11:03:26,451 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'float[]' value '[F@163f8c54' to type 'float[]'
2019-08-12 11:03:26,451 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a float[]
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [I@7a1e8b23
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'int[]' value '[I@7a1e8b23' to type 'int[]'
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a int[]
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [J@60f19c0a
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'long[]' value '[J@60f19c0a' to type 'long[]'
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a long[]
2019-08-12 11:03:26,452 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [S@68b073f8
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'short[]' value '[S@68b073f8' to type 'short[]'
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a short[]
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.math.BigDecimal;@2871f96
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigDecimal[]' value '[Ljava.math.BigDecimal;@2871f96' to type 'BigDecimal[]'
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigDecimal[]
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.math.BigInteger;@60330739
2019-08-12 11:03:26,453 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'BigInteger[]' value '[Ljava.math.BigInteger;@60330739' to type 'BigInteger[]'
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a BigInteger[]
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Boolean;@31b64223
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Boolean[]' value '[Ljava.lang.Boolean;@31b64223' to type 'Boolean[]'
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Boolean[]
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Byte;@33ab7695
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Byte[]' value '[Ljava.lang.Byte;@33ab7695' to type 'Byte[]'
2019-08-12 11:03:26,454 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Byte[]
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Character;@7746640
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Character[]' value '[Ljava.lang.Character;@7746640' to type 'Character[]'
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Character[]
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Double;@690c4428
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Double[]' value '[Ljava.lang.Double;@690c4428' to type 'Double[]'
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Double[]
2019-08-12 11:03:26,455 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Float;@4de37a2f
2019-08-12 11:03:26,456 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Float[]' value '[Ljava.lang.Float;@4de37a2f' to type 'Float[]'
2019-08-12 11:03:26,456 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Float[]
2019-08-12 11:03:26,456 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Integer;@1bc97c0b
2019-08-12 11:03:26,456 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Integer[]' value '[Ljava.lang.Integer;@1bc97c0b' to type 'Integer[]'
2019-08-12 11:03:26,460 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Integer[]
2019-08-12 11:03:26,460 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Long;@789248c4
2019-08-12 11:03:26,463 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Long[]' value '[Ljava.lang.Long;@789248c4' to type 'Long[]'
2019-08-12 11:03:26,463 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Long[]
2019-08-12 11:03:26,463 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Short;@44ca2308
2019-08-12 11:03:26,463 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Short[]' value '[Ljava.lang.Short;@44ca2308' to type 'Short[]'
2019-08-12 11:03:26,463 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Short[]
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.String;@7e0d9884
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String[]' value '[Ljava.lang.String;@7e0d9884' to type 'String[]'
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a String[]
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.lang.Class;@63d97101
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Class[]' value '[Ljava.lang.Class;@63d97101' to type 'Class[]'
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Class[]
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.util.Date;@769ecc11
2019-08-12 11:03:26,464 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Date[]' value '[Ljava.util.Date;@769ecc11' to type 'Date[]'
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Date[]
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.util.Calendar;@69ce7cf4
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'Calendar[]' value '[Ljava.util.Calendar;@69ce7cf4' to type 'Calendar[]'
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a Calendar[]
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.io.File;@41c9fbc6
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.io.File[]' value '[Ljava.io.File;@41c9fbc6' to type 'java.io.File[]'
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.io.File[]
2019-08-12 11:03:26,465 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Date;@8a9c6fc
2019-08-12 11:03:26,466 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Date[]' value '[Ljava.sql.Date;@8a9c6fc' to type 'java.sql.Date[]'
2019-08-12 11:03:26,466 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Date[]
2019-08-12 11:03:26,466 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Time;@7db11ec5
2019-08-12 11:03:26,466 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Time[]' value '[Ljava.sql.Time;@7db11ec5' to type 'java.sql.Time[]'
2019-08-12 11:03:26,466 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Time[]
2019-08-12 11:03:26,467 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.sql.Timestamp;@783c21fb
2019-08-12 11:03:26,467 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.sql.Timestamp[]' value '[Ljava.sql.Timestamp;@783c21fb' to type 'java.sql.Timestamp[]'
2019-08-12 11:03:26,467 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.sql.Timestamp[]
2019-08-12 11:03:26,467 org.apache.commons.beanutils.converters.AbstractConverter.setDefaultValue(AbstractConverter.java:353) DEBUG - Setting default value: [Ljava.net.URL;@38f63fbe
2019-08-12 11:03:26,467 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'java.net.URL[]' value '[Ljava.net.URL;@38f63fbe' to type 'java.net.URL[]'
2019-08-12 11:03:26,467 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:162) DEBUG -     No conversion required, value is already a java.net.URL[]
2019-08-12 11:03:26,495 org.apache.shiro.web.env.IniWebEnvironment.parseConfig(IniWebEnvironment.java:95) DEBUG - Checking any specified config locations.
2019-08-12 11:03:26,495 org.apache.shiro.web.env.IniWebEnvironment.parseConfig(IniWebEnvironment.java:100) DEBUG - No INI instance or config locations specified.  Trying default config locations.
2019-08-12 11:03:26,501 org.apache.shiro.io.ResourceUtils.loadFromClassPath(ResourceUtils.java:159) DEBUG - Opening resource from class path [shiro.ini]
2019-08-12 11:03:26,507 org.apache.shiro.config.Ini.load(Ini.java:401) DEBUG - Parsing [main]
2019-08-12 11:03:26,510 org.apache.shiro.config.Ini.load(Ini.java:401) DEBUG - Parsing [urls]
2019-08-12 11:03:26,510 org.apache.shiro.web.env.IniWebEnvironment.getDefaultIni(IniWebEnvironment.java:217) DEBUG - Discovered non-empty INI configuration at location 'classpath:shiro.ini'.  Using for configuration.
2019-08-12 11:03:26,513 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:149) DEBUG - Creating instance from Ini [sections=main,urls]
2019-08-12 11:03:26,617 org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:234) DEBUG - No authorizationCache instance set.  Checking for a cacheManager...
2019-08-12 11:03:26,617 org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:248) DEBUG - No cache or cacheManager properties have been set.  Authorization cache cannot be obtained.
2019-08-12 11:03:26,618 org.apache.shiro.config.ReflectionBuilder.createNewInstance(ReflectionBuilder.java:314) INFO  - An instance with name 'authc' already exists.  Redefining this object as a new instance of type org.nutz.integration.shiro.SimpleAuthenticationFilter
2019-08-12 11:03:26,640 org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) DEBUG - Convert string '/user/login' to class 'java.lang.String'
2019-08-12 11:03:26,641 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value '/user/login' to type 'String'
2019-08-12 11:03:26,651 org.apache.commons.beanutils.ConvertUtilsBean.convert(ConvertUtilsBean.java:481) DEBUG - Convert string '/user/login' to class 'java.lang.String'
2019-08-12 11:03:26,651 org.apache.commons.beanutils.converters.AbstractConverter.convert(AbstractConverter.java:140) DEBUG - Converting 'String' value '/user/login' to type 'String'
2019-08-12 11:03:26,651 org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:234) DEBUG - No authorizationCache instance set.  Checking for a cacheManager...
2019-08-12 11:03:26,651 org.apache.shiro.realm.AuthorizingRealm.getAuthorizationCacheLazy(AuthorizingRealm.java:248) DEBUG - No cache or cacheManager properties have been set.  Authorization cache cannot be obtained.
2019-08-12 11:03:26,653 org.apache.shiro.config.IniFactorySupport.createInstance(IniFactorySupport.java:149) DEBUG - Creating instance from Ini [sections=main,urls]
2019-08-12 11:03:26,657 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/rs/*] from String definition [anon]
2019-08-12 11:03:26,659 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/rs/*] to filter [anon] with config [null]
2019-08-12 11:03:26,663 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/logout] from String definition [logout]
2019-08-12 11:03:26,664 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/logout] to filter [logout] with config [null]
2019-08-12 11:03:26,665 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/error] from String definition [anon]
2019-08-12 11:03:26,665 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/error] to filter [anon] with config [null]
2019-08-12 11:03:26,665 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/login] from String definition [anon]
2019-08-12 11:03:26,665 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.applyChainConfig(DefaultFilterChainManager.java:278) DEBUG - Attempting to apply path [/user/login] to filter [anon] with config [null]
2019-08-12 11:03:26,665 org.apache.shiro.web.filter.mgt.DefaultFilterChainManager.createChain(DefaultFilterChainManager.java:127) DEBUG - Creating chain [/user/profile/active/mail] from String definition [anon]
2019-08-12 11:03:26,665 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]
2019-08-12 11:03:26,666 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:142) DEBUG - Published WebEnvironment as ServletContext attribute with name [org.apache.shiro.web.env.EnvironmentLoader.ENVIRONMENT_ATTRIBUTE_KEY]
2019-08-12 11:03:26,666 org.apache.shiro.web.env.EnvironmentLoader.initEnvironment(EnvironmentLoader.java:147) INFO  - Shiro environment initialized in 294 ms.
2019-08-12 11:03:26,745 org.nutz.mvc.NutFilter._init(NutFilter.java:81) INFO  - NutFilter[nutz] starting ...
2019-08-12 11:03:26,798 org.nutz.resource.Scans.<init>(Scans.java:509) DEBUG - Locations for Scans:
[Dir[path=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin], Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-1.r.60.jar], Dir[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\classes], Jar[path=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\bootstrap.jar], Jar[path=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\tomcat-juli.jar]]
2019-08-12 11:03:27,089 org.nutz.resource.Scans.init(Scans.java:105) DEBUG - Locations for Scans:
{D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin=Dir[path=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-1.r.60.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-1.r.60.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\classes=Dir[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\classes], D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\bootstrap.jar=Jar[path=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\bootstrap.jar], D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\tomcat-juli.jar=Jar[path=D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\tomcat-juli.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\slf4j-log4j12-1.7.25.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\slf4j-log4j12-1.7.25.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-hazelcast-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-hazelcast-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-event-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-event-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\jcl-over-slf4j-1.7.25.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\jcl-over-slf4j-1.7.25.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\guice-multibindings-3.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\guice-multibindings-3.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-all-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-all-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\aopalliance-1.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\aopalliance-1.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-email-1.5.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-email-1.5.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-web-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-web-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\druid-1.0.26.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\druid-1.0.26.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\simplecaptcha-1.2.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\simplecaptcha-1.2.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-config-ogdl-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-config-ogdl-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-ehcache-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-ehcache-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\quartz-2.3.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\quartz-2.3.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-beanutils-1.9.3.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-beanutils-1.9.3.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\guice-3.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\guice-3.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-crypto-cipher-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-crypto-cipher-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\c3p0-0.9.5.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\c3p0-0.9.5.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\javax.inject-1.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\javax.inject-1.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\servlet-api-2.5.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\servlet-api-2.5.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-crypto-core-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-crypto-core-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\aspectjrt-1.8.9.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\aspectjrt-1.8.9.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-io-2.6.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-io-2.6.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-integration-shiro-1.r.66.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-integration-shiro-1.r.66.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\activation-1.1.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\activation-1.1.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\jstl-1.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\jstl-1.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\slf4j-api-1.7.7.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\slf4j-api-1.7.7.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-integration-quartz-1.r.66.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\nutz-integration-quartz-1.r.66.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-config-core-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-config-core-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-lang-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-lang-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\hazelcast-3.7.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\hazelcast-3.7.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\mysql-connector-java-5.1.47.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\mysql-connector-java-5.1.47.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\standard-1.1.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\standard-1.1.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-core-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-core-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-spring-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-spring-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-crypto-hash-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-crypto-hash-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\ehcache-core-2.6.11.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\ehcache-core-2.6.11.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\javax.mail-1.6.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\javax.mail-1.6.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-cache-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-cache-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-collections-3.2.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-collections-3.2.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-guice-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-guice-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\aspectjweaver-1.8.9.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\aspectjweaver-1.8.9.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-aspectj-1.4.0.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\shiro-aspectj-1.4.0.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\log4j-1.2.17.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\log4j-1.2.17.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\mchange-commons-java-0.2.11.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\mchange-commons-java-0.2.11.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\HikariCP-java6-2.3.13.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\HikariCP-java6-2.3.13.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\javax.mail-api-1.6.2.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\javax.mail-api-1.6.2.jar], D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-logging-1.1.1.jar=Jar[path=D:\JavaCode\nutzbook\target\demo\WEB-INF\lib\commons-logging-1.1.1.jar]}
2019-08-12 11:03:27,140 org.nutz.castor.Castors.reload(Castors.java:115) DEBUG - Using 95 castor for Castors
2019-08-12 11:03:27,158 org.nutz.mvc.config.AbstractNutConfig.createLoading(AbstractNutConfig.java:59) DEBUG - Loading by class org.nutz.mvc.impl.NutLoading
2019-08-12 11:03:27,161 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:55) INFO  - Nutz Version : 1.r.60 
2019-08-12 11:03:27,161 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:56) INFO  - Nutz.Mvc[nutz] is initializing ...
2019-08-12 11:03:27,161 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:60) DEBUG - Web Container Information:
2019-08-12 11:03:27,162 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:61) DEBUG -  - Default Charset : UTF-8
2019-08-12 11:03:27,162 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:62) DEBUG -  - Current . path  : D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\bin\.
2019-08-12 11:03:27,162 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:63) DEBUG -  - Java Version    : 9.0.4
2019-08-12 11:03:27,163 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:64) DEBUG -  - File separator  : \
2019-08-12 11:03:27,163 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:65) DEBUG -  - Timezone        : Asia/Shanghai
2019-08-12 11:03:27,163 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:66) DEBUG -  - OS              : Windows 10 amd64
2019-08-12 11:03:27,163 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:67) DEBUG -  - ServerInfo      : Apache Tomcat/9.0.14
2019-08-12 11:03:27,163 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:68) DEBUG -  - Servlet API     : 4.0
2019-08-12 11:03:27,163 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:73) DEBUG -  - ContextPath     : /nutzbook
2019-08-12 11:03:27,164 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:74) DEBUG -  - context.tempdir : C:\Users\Administrator\.IntelliJIdea2019.2\system\tomcat\Tomcat_9_0_14_nutzbook\work\Catalina\localhost\nutzbook
2019-08-12 11:03:27,164 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:75) DEBUG -  - MainModule      : net.wendal.nutzbook.MainModule
2019-08-12 11:03:27,165 org.nutz.mvc.impl.NutLoading.createContext(NutLoading.java:238) DEBUG - >> app.root = D:/JavaCode/nutzbook/target/demo
2019-08-12 11:03:27,167 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=[])
2019-08-12 11:03:27,188 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 2 resource by src( ioc/ ) , regex( ^(.+[.])(js|json)$ )
2019-08-12 11:03:27,188 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [dao.js]
2019-08-12 11:03:27,191 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:48) DEBUG - loading ioc js config from [mail.js]
2019-08-12 11:03:27,193 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:56) DEBUG - Loaded 5 bean define from path=[ioc/] --> [conf, dao, emailAuthenticator, dataSource, htmlEmail]
2019-08-12 11:03:27,201 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 19 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2019-08-12 11:03:27,235 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.CaptchaModule
2019-08-12 11:03:27,240 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserModule
2019-08-12 11:03:27,252 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.module.UserProfileModule
2019-08-12 11:03:27,259 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.quartz.job.CleanNonActiveUserJob
2019-08-12 11:03:27,262 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.impl.EmailServiceImpl
2019-08-12 11:03:27,263 org.nutz.ioc.loader.annotation.AnnotationIocLoader.addClass(AnnotationIocLoader.java:74) DEBUG - Found @IocBean : class net.wendal.nutzbook.service.impl.UserServiceImpl
2019-08-12 11:03:27,267 org.nutz.ioc.loader.annotation.AnnotationIocLoader.<init>(AnnotationIocLoader.java:50) INFO  - Found 6 classes in 1 base-packages!
beans = ["cleanNonActiveUserJob", "userServiceImpl", "userProfileModule", "captchaModule", "userModule", "emailService"]
2019-08-12 11:03:27,269 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:40) DEBUG - Loaded 5 bean define from reader --
[txREPEATABLE_READ, txSERIALIZABLE, txNONE, txREAD_UNCOMMITTED, txREAD_COMMITTED]
2019-08-12 11:03:27,283 org.nutz.ioc.loader.combo.ComboIocLoader.createIocLoader(ComboIocLoader.java:118) DEBUG - found org.nutz.integration.quartz.QuartzIocLoader -- org.nutz.integration.quartz.QuartzIocLoader
2019-08-12 11:03:27,283 org.nutz.ioc.loader.json.JsonLoader.<init>(JsonLoader.java:56) DEBUG - Loaded 0 bean define from path=[] --> []
2019-08-12 11:03:27,288 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:98) INFO  - NutIoc init begin ...
2019-08-12 11:03:27,291 org.nutz.ioc.impl.NutIoc.<init>(NutIoc.java:115) INFO  - ... NutIoc init complete
2019-08-12 11:03:27,295 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:159) INFO  - Build URL mapping by org.nutz.mvc.impl.UrlMappingImpl ...
2019-08-12 11:03:27,297 org.nutz.mvc.impl.NutLoading.createViewMakers(NutLoading.java:369) DEBUG - @Views(DefaultViewMaker.class)
2019-08-12 11:03:27,315 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( mvc/nutzbook-mvc-chain.js ) , regex( ^(.+[.])(js|json)$ )
2019-08-12 11:03:27,332 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"
   }
}
2019-08-12 11:03:27,333 org.nutz.mvc.impl.NutLoading.createChainMaker(NutLoading.java:266) DEBUG - @ChainBy(org.nutz.mvc.impl.NutActionChainMaker)
2019-08-12 11:03:27,354 org.nutz.mvc.impl.Loadings.scanModules(Loadings.java:154) DEBUG - module class location 'file:/D:/JavaCode/nutzbook/target/demo/WEB-INF/classes/'
2019-08-12 11:03:27,356 org.nutz.mvc.impl.Loadings.scanModuleInPackage(Loadings.java:184) DEBUG -  > scan 'net.wendal.nutzbook'
2019-08-12 11:03:27,372 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 19 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2019-08-12 11:03:27,377 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.CaptchaModule'
2019-08-12 11:03:27,377 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserModule'
2019-08-12 11:03:27,377 org.nutz.mvc.impl.Loadings.checkModule(Loadings.java:198) DEBUG -    >> add 'net.wendal.nutzbook.module.UserProfileModule'
2019-08-12 11:03:27,434 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/'                    >> (UserModule.java:168).index           : void       | @Ok(jsp:jsp.user.list) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,449 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/add'                 >> (UserModule.java:83).add              : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,453 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/update'              >> (UserModule.java:100).update          : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,454 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/count'               >> (UserModule.java:48).count            : int        | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,454 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/delete'              >> (UserModule.java:110).delete          : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,457 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/query'               >> (UserModule.java:121).query           : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,457 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:164).loginPage       : void       | @Ok(jsp:jsp.user.login) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,459 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/logout'              >> (UserModule.java:77).logout           : void       | @Ok(>>:/ ) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,462 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/login'               >> (UserModule.java:59).login            : Object     | @Ok(json:{locked:'password|salt',ignoreNull:true}) @Fail(http:500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,464 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/'            >> (UserProfileModule.java:46).index     : UserProfile | @Ok(jsp:jsp.user.profile) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,464 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/get'         >> (UserProfileModule.java:50).get       : UserProfile | @Ok(json:full) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,466 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/update'      >> (UserProfileModule.java:66).update    : void       | @Ok(void ) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,468 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:126).readAvatar : Object     | @Ok(raw:jpg) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,469 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:136).activeMail : Object     | @Ok(json:full) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,469 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/active/mail' >> (UserProfileModule.java:164).activeMailCallback : String     | @Ok(raw  ) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,474 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: D:/JavaCode/nutzbook/target/demo/WEB-INF/tmp/user_avatar [20000]
2019-08-12 11:03:27,476 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'D:\JavaCode\nutzbook\target\demo\WEB-INF\tmp\user_avatar'
2019-08-12 11:03:27,477 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2019-08-12 11:03:27,481 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/user/profile/avatar'      >> (UserProfileModule.java:97).uploadAvatar : void       | @Ok(>>:/jsp/user/profile) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,482 org.nutz.mvc.impl.UrlMappingImpl.print(UrlMappingImpl.java:146) DEBUG -    '/captcha/next'             >> (CaptchaModule.java:23).next          : BufferedImage | @Ok(raw:png) @Fail(jsp:jsp.user.500) | by 0 Filters | (I:UTF-8/O:UTF-8)
2019-08-12 11:03:27,482 org.nutz.mvc.impl.NutLoading.evalUrlMapping(NutLoading.java:220) INFO  - Found 17 module methods
2019-08-12 11:03:27,482 org.nutz.mvc.impl.NutLoading.evalLocalization(NutLoading.java:306) DEBUG - Localization: org.nutz.mvc.impl.NutMessageLoader('msg/')  dft<zh-CN>
2019-08-12 11:03:27,484 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 1 resource by src( msg/ ) , regex( ^.+[.]properties$ )
2019-08-12 11:03:27,484 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:27) DEBUG - Load Messages in 1 resource : [[NutResource[zh-CN/user.properties]]]
2019-08-12 11:03:27,486 org.nutz.mvc.impl.NutMessageLoader.load(NutMessageLoader.java:95) DEBUG - Message Loaded, size = 2
2019-08-12 11:03:27,486 org.nutz.mvc.impl.NutLoading.evalSetup(NutLoading.java:274) INFO  - Setup application...
2019-08-12 11:03:27,486 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dao'<interface org.nutz.dao.Dao>
2019-08-12 11:03:27,488 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
2019-08-12 11:03:27,489 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dao
2019-08-12 11:03:27,489 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dao
2019-08-12 11:03:27,493 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dao) in JsonLoader(paths=[ioc/])
2019-08-12 11:03:27,494 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dao'<interface org.nutz.dao.Dao>
2019-08-12 11:03:27,502 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.dao.impl.NutDao without AOP
2019-08-12 11:03:27,502 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dao' to [app] 
2019-08-12 11:03:27,505 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2019-08-12 11:03:27,505 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=dataSource
2019-08-12 11:03:27,505 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=dataSource
2019-08-12 11:03:27,513 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(dataSource) in JsonLoader(paths=[ioc/])
2019-08-12 11:03:27,513 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'dataSource'<>
2019-08-12 11:03:27,531 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class com.alibaba.druid.pool.DruidDataSource without AOP
2019-08-12 11:03:27,531 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'dataSource' to [app] 
2019-08-12 11:03:27,533 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'conf'<>
2019-08-12 11:03:27,533 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=conf
2019-08-12 11:03:27,533 org.nutz.ioc.loader.map.MapLoader.load(MapLoader.java:67) DEBUG - Loading define for name=conf
2019-08-12 11:03:27,533 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(conf) in JsonLoader(paths=[ioc/])
2019-08-12 11:03:27,533 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'conf'<>
2019-08-12 11:03:27,534 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class org.nutz.ioc.impl.PropertiesProxy without AOP
2019-08-12 11:03:27,534 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'conf' to [app] 
2019-08-12 11:03:27,539 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 3 resource by src( custom/ ) , regex( ^.+[.]properties$ )
2019-08-12 11:03:27,606 com.alibaba.druid.pool.DruidDataSource.init(DruidDataSource.java:715) INFO  - {dataSource-1} inited
2019-08-12 11:03:27,607 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'dataSource'<>
2019-08-12 11:03:27,629 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:23) INFO  - Init file-pool by: C:\Users\Administrator/.nutz/tmp/dao/ [200000]
2019-08-12 11:03:27,630 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:37) DEBUG - file-pool.home: 'C:\Users\Administrator\.nutz\tmp\dao'
2019-08-12 11:03:27,630 org.nutz.filepool.NutFilePool.<init>(NutFilePool.java:66) INFO  - file-pool.cursor: 0
2019-08-12 11:03:27,636 org.nutz.dao.jdbc.Jdbcs.<clinit>(Jdbcs.java:90) DEBUG - Jdbcs init complete
2019-08-12 11:03:27,636 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
Mon Aug 12 11:03:27 CST 2019 WARN: Establishing SSL connection without server's identity verification is not recommended. According to MySQL 5.5.45+, 5.6.26+ and 5.7.6+ requirements SSL connection must be established by default if explicit option isn't set. For compliance with existing applications not using SSL the verifyServerCertificate property is set to 'false'. You need either to explicitly disable SSL by setting useSSL=false, or set useSSL=true and provide truststore for server certificate verification.
2019-08-12 11:03:27,843 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:188) DEBUG - select expert : org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert
2019-08-12 11:03:27,850 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:199) DEBUG - JDBC Driver --> mysql-connector-java-5.1.47 ( Revision: fe1903b1ecb4a96a917f7ed3190d80c049b1de29 )
2019-08-12 11:03:27,850 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:200) DEBUG - JDBC Name   --> MySQL Connector Java
2019-08-12 11:03:27,850 org.nutz.dao.impl.DaoSupport$1.invoke(DaoSupport.java:202) DEBUG - JDBC URL    --> jdbc:mysql://localhost:3306/nutzbook
2019-08-12 11:03:27,935 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_client=utf8
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_connection=utf8
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_database=utf8
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_filesystem=binary
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_results=
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_server=utf8
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_set_system=utf8
2019-08-12 11:03:27,936 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:212) DEBUG - Mysql : character_sets_dir=D:\mysql-5.7.20-winx64\share\charsets\
2019-08-12 11:03:27,939 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:219) DEBUG - Mysql : binlog_format=ROW
2019-08-12 11:03:27,940 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:226) DEBUG - Mysql : database=nutzbook
2019-08-12 11:03:27,940 org.nutz.dao.impl.jdbc.mysql.MysqlJdbcExpert.checkDataSource(MysqlJdbcExpert.java:232) DEBUG - Mysql : user=root@localhost
2019-08-12 11:03:27,968 org.nutz.dao.impl.DaoSupport.setDataSource(DaoSupport.java:221) DEBUG - Database info --> MYSQL:[MySQL - 5.7.20]
2019-08-12 11:03:27,976 org.nutz.resource.Scans.scan(Scans.java:274) DEBUG - Found 19 resource by src( net/wendal/nutzbook/ ) , regex( ^.+[.]class$ )
2019-08-12 11:03:28,067 org.nutz.dao.impl.sql.run.NutDaoExecutor.printSQL(NutDaoExecutor.java:388) DEBUG - SELECT COUNT(*) FROM t_user 
2019-08-12 11:03:28,069 org.nutz.mvc.impl.NutLoading.load(NutLoading.java:141) INFO  - Nutz.Mvc[nutz] is up in 904ms
2019-08-12 11:03:28,069 org.nutz.mvc.NutFilter._init(NutFilter.java:113) INFO  - exclusionsPrefix  = ^(/rs/|/druid/)
[2019-08-12 11:03:28,098] Artifact nutzbook:war exploded: Artifact is deployed successfully
[2019-08-12 11:03:28,098] Artifact nutzbook:war exploded: Deploy took 3,848 milliseconds
2019-08-12 11:03:29,040 org.nutz.mvc.impl.UrlMappingImpl.get(UrlMappingImpl.java:101) DEBUG - Found mapping for [GET] path=/captcha/next : CaptchaModule.next(CaptchaModule.java:23)
2019-08-12 11:03:29,045 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:151) DEBUG - Get 'captchaModule'<class net.wendal.nutzbook.module.CaptchaModule>
2019-08-12 11:03:29,045 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:177) DEBUG - 	 >> Load definition name=captchaModule
2019-08-12 11:03:29,046 org.nutz.ioc.loader.combo.ComboIocLoader.load(ComboIocLoader.java:169) DEBUG - Found IocObject(captchaModule) in AnnotationIocLoader(packages=[net.wendal.nutzbook])
2019-08-12 11:03:29,046 org.nutz.ioc.impl.NutIoc.get(NutIoc.java:209) DEBUG - 	 >> Make...'captchaModule'<class net.wendal.nutzbook.module.CaptchaModule>
2019-08-12 11:03:29,046 org.nutz.ioc.aop.impl.DefaultMirrorFactory.getMirror(DefaultMirrorFactory.java:70) DEBUG - Load class net.wendal.nutzbook.module.CaptchaModule without AOP
2019-08-12 11:03:29,047 org.nutz.ioc.impl.ScopeContext.save(ScopeContext.java:64) DEBUG - Save object 'captchaModule' to [app] 
2019-08-12 11:03:29,339 net.wendal.nutzbook.mvc.LogTimeProcessor.process(LogTimeProcessor.java:23) DEBUG - [ GET]URI=/nutzbook/captcha/next 298ms
12-Aug-2019 11:03:33.945 信息 [Catalina-utility-1] org.apache.catalina.startup.HostConfig.deployDirectory 部署 web 应用程序目录 [D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\webapps\manager]
12-Aug-2019 11:03:33.979 信息 [Catalina-utility-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\dev_soft\apache-tomcat-9.0.14\apache-tomcat-9.0.14\webapps\manager] has finished in [34] ms

wx_p064hjeoh9l2g0cqspro 2楼•1712天前

t_user数据库信息ct和ut都是这个时间

2019-08-05 10:43:26

wendal

3楼•1712天前

// 获取NutQuartzCronJobFactory从而触发计划任务的初始化与启动。就是启动定时器
            ncIoc.get(NutQuartzCronJobFactory.class);

这句放在if之外

wendal

4楼•1712天前

发email的代码也是

那个if判断是为了自动添加默认用户的, 其他代码放里面不合理的.

wx_p064hjeoh9l2g0cqspro 5楼•1712天前

好嘞大哥。谢谢你

请先登陆