三月 08, 2021 12:01:21 上午 org.springframework.test.context.support.DefaultTestContextBootstrapper getDefaultTestExecutionListenerClassNames
信息: Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener]
三月 08, 2021 12:01:22 上午 org.springframework.test.context.support.DefaultTestContextBootstrapper getTestExecutionListeners
信息: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@6615435c, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@4909b8da, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@3a03464, org.springframework.test.context.support.DirtiesContextTestExecutionListener@2d3fcdbd, org.springframework.test.context.transaction.TransactionalTestExecutionListener@617c74e5, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@6537cf78]
三月 08, 2021 12:01:22 上午 org.springframework.beans.factory.xml.XmlBeanDefinitionReader loadBeanDefinitions
信息: Loading XML bean definitions from class path resource [spring/spring-dao.xml]
三月 08, 2021 12:01:22 上午 org.springframework.beans.factory.xml.XmlBeanDefinitionReader loadBeanDefinitions
信息: Loading XML bean definitions from class path resource [spring/spring-service.xml]
三月 08, 2021 12:01:22 上午 org.springframework.context.support.GenericApplicationContext prepareRefresh
信息: Refreshing org.springframework.context.support.GenericApplicationContext@457e2f02: startup date [Mon Mar 08 00:01:22 CST 2021]; root of context hierarchy
00:01:22,867 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
00:01:22,868 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
00:01:22,868 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/D:/eclipse-workspacei/o2o/target/classes/logback.xml]
00:01:22,942 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
00:01:22,948 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Will scan for changes in [file:/D:/eclipse-workspacei/o2o/target/classes/logback.xml]
00:01:22,948 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeTask scanning period to 1 minutes
00:01:22,957 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
00:01:22,964 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [consoleAppender]
00:01:22,972 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
00:01:23,028 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
00:01:23,034 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [debugAppender]
00:01:23,047 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@255334292 - Will use gz compression
00:01:23,050 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@255334292 - Will use the pattern catalina.base_IS_UNDEFINED/logs/webapps/debug/debug.%d{yyyy-MM-dd}.log for the active file
00:01:23,056 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is ‘yyyy-MM-dd’ from file name pattern ‘catalina.base_IS_UNDEFINED/logs/webapps/debug/debug.%d{yyyy-MM-dd}.log.gz’.
00:01:23,056 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
00:01:23,058 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Sun Mar 07 23:46:39 CST 2021
00:01:23,060 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
00:01:23,064 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@40:16 - no applicable action for [onMisMatch], current ElementPath is [[configuration][appender][filter][onMisMatch]]
00:01:23,066 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[debugAppender] - Active log file name: catalina.base_IS_UNDEFINED/logs/webapps/debug.log
00:01:23,066 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[debugAppender] - File property is set to [catalina.base_IS_UNDEFINED/logs/webapps/debug.log]
00:01:23,068 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
00:01:23,068 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [infoAppender]
00:01:23,069 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@752684363 - Will use gz compression
00:01:23,069 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@752684363 - Will use the pattern catalina.base_IS_UNDEFINED/logs/webapps/info/info.%d{yyyy-MM-dd}.log for the active file
00:01:23,070 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is ‘yyyy-MM-dd’ from file name pattern ‘catalina.base_IS_UNDEFINED/logs/webapps/info/info.%d{yyyy-MM-dd}.log.gz’.
00:01:23,070 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
00:01:23,071 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Sun Mar 07 23:46:39 CST 2021
00:01:23,071 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
00:01:23,072 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[infoAppender] - Active log file name: catalina.base_IS_UNDEFINED/logs/webapps/debug.log
00:01:23,072 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[infoAppender] - File property is set to [catalina.base_IS_UNDEFINED/logs/webapps/debug.log]
00:01:23,072 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[infoAppender] - ‘File’ option has the same value “catalina.base_IS_UNDEFINED/logs/webapps/debug.log” as that given for appender [debugAppender] defined earlier.
00:01:23,072 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[infoAppender] - Collisions detected with FileAppender/RollingAppender instances defined earlier. Aborting.
00:01:23,072 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[infoAppender] - For more information, please visit http://logback.qos.ch/codes.html#earlier_fa_collision
00:01:23,072 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
00:01:23,072 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [errorAppender]
00:01:23,073 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@2123444693 - Will use gz compression
00:01:23,073 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@2123444693 - Will use the pattern catalina.base_IS_UNDEFINED/logs/webapps/error/error.%d{yyyy-MM-dd}.log for the active file
00:01:23,075 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is ‘yyyy-MM-dd’ from file name pattern ‘catalina.base_IS_UNDEFINED/logs/webapps/error/error.%d{yyyy-MM-dd}.log.gz’.
00:01:23,075 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
00:01:23,076 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Sun Mar 07 23:46:39 CST 2021
00:01:23,077 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
00:01:23,078 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@81:16 - no applicable action for [onMisMatch], current ElementPath is [[configuration][appender][filter][onMisMatch]]
00:01:23,079 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[errorAppender] - Active log file name: catalina.base_IS_UNDEFINED/logs/webapps/debug.log
00:01:23,079 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[errorAppender] - File property is set to [catalina.base_IS_UNDEFINED/logs/webapps/debug.log]
00:01:23,079 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[errorAppender] - ‘File’ option has the same value “catalina.base_IS_UNDEFINED/logs/webapps/debug.log” as that given for appender [debugAppender] defined earlier.
00:01:23,079 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[errorAppender] - ‘File’ option has the same value “catalina.base_IS_UNDEFINED/logs/webapps/debug.log” as that given for appender [infoAppender] defined earlier.
00:01:23,079 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[errorAppender] - Collisions detected with FileAppender/RollingAppender instances defined earlier. Aborting.
00:01:23,079 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[errorAppender] - For more information, please visit http://logback.qos.ch/codes.html#earlier_fa_collision
00:01:23,080 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@84:67 - RuntimeException in Action for tag [logger] java.lang.IllegalArgumentException: All tokens consumed but was expecting "}"
at java.lang.IllegalArgumentException: All tokens consumed but was expecting "}"
at at ch.qos.logback.core.subst.Parser.expectNotNull(Parser.java:143)
at at ch.qos.logback.core.subst.Parser.expectCurlyRight(Parser.java:148)
at at ch.qos.logback.core.subst.Parser.T(Parser.java:94)
at at ch.qos.logback.core.subst.Parser.E(Parser.java:51)
at at ch.qos.logback.core.subst.Parser.parse(Parser.java:47)
at at ch.qos.logback.core.subst.NodeToStringTransformer.tokenizeAndParseString(NodeToStringTransformer.java:55)
at at ch.qos.logback.core.subst.NodeToStringTransformer.substituteVariable(NodeToStringTransformer.java:46)
at at ch.qos.logback.core.util.OptionHelper.substVars(OptionHelper.java:112)
at at ch.qos.logback.core.joran.spi.InterpretationContext.subst(InterpretationContext.java:157)
at at ch.qos.logback.classic.joran.action.LoggerAction.begin(LoggerAction.java:56)
at at ch.qos.logback.core.joran.spi.Interpreter.callBeginAction(Interpreter.java:269)
at at ch.qos.logback.core.joran.spi.Interpreter.startElement(Interpreter.java:145)
at at ch.qos.logback.core.joran.spi.Interpreter.startElement(Interpreter.java:128)
at at ch.qos.logback.core.joran.spi.EventPlayer.play(EventPlayer.java:50)
at at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:165)
at at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:152)
at at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:110)
at at ch.qos.logback.core.joran.GenericConfigurator.doConfigure(GenericConfigurator.java:53)
at at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:75)
at at ch.qos.logback.classic.util.ContextInitializer.autoConfig(ContextInitializer.java:150)
at at org.slf4j.impl.StaticLoggerBinder.init(StaticLoggerBinder.java:84)
at at org.slf4j.impl.StaticLoggerBinder.(StaticLoggerBinder.java:55)
at at org.slf4j.LoggerFactory.bind(LoggerFactory.java:150)
at at org.slf4j.LoggerFactory.performInitialization(LoggerFactory.java:124)
at at org.slf4j.LoggerFactory.getILoggerFactory(LoggerFactory.java:412)
at at com.mchange.v2.log.slf4j.Slf4jMLog.(Slf4jMLog.java:68)
at at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
at at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
at at java.lang.Class.newInstance(Class.java:442)
at at com.mchange.v2.log.MLog.findByClassnames(MLog.java:143)
at at com.mchange.v2.log.MLog.refreshConfig(MLog.java:73)
at at com.mchange.v2.log.MLog.(MLog.java:51)
at at com.mchange.v2.naming.JavaBeanReferenceMaker.(JavaBeanReferenceMaker.java:51)
at at com.mchange.v2.c3p0.impl.PoolBackedDataSourceBase.(PoolBackedDataSourceBase.java:263)
at at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
at at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
at at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:142)
at at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:89)
at at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateBean(AbstractAutowireCapableBeanFactory.java:1147)
at at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1099)
at at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:513)
at at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483)
at at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
at at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
at at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
at at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197)
at at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:761)
at at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:866)
at at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:542)
at at org.springframework.test.context.support.AbstractGenericContextLoader.loadContext(AbstractGenericContextLoader.java:128)
at at org.springframework.test.context.support.AbstractGenericContextLoader.loadContext(AbstractGenericContextLoader.java:60)
at at org.springframework.test.context.support.AbstractDelegatingSmartContextLoader.delegateLoading(AbstractDelegatingSmartContextLoader.java:108)
at at org.springframework.test.context.support.AbstractDelegatingSmartContextLoader.loadContext(AbstractDelegatingSmartContextLoader.java:251)
at at org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate.loadContextInternal(DefaultCacheAwareContextLoaderDelegate.java:98)
at at org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate.loadContext(DefaultCacheAwareContextLoaderDelegate.java:116)
at at org.springframework.test.context.support.DefaultTestContext.getApplicationContext(DefaultTestContext.java:83)
at at org.springframework.test.context.support.DependencyInjectionTestExecutionListener.injectDependencies(DependencyInjectionTestExecutionListener.java:117)
at at org.springframework.test.context.support.DependencyInjectionTestExecutionListener.prepareTestInstance(DependencyInjectionTestExecutionListener.java:83)
at at org.springframework.test.context.TestContextManager.prepareTestInstance(TestContextManager.java:230)
at at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.createTest(SpringJUnit4ClassRunner.java:228)
at at org.springframework.test.context.junit4.SpringJUnit4ClassRunner$1.runReflectiveCall(SpringJUnit4ClassRunner.java:287)
at at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.methodBlock(SpringJUnit4ClassRunner.java:289)
at at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:247)
at at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:94)
at at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
at at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
at at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
at at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
at at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
at at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
at at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70)
at at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
at at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:191)
at at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:89)
at at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:41)
at at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:542)
at at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:770)
at at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:464)
at at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:210)
00:01:23,080 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
00:01:23,080 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [consoleAppender] to Logger[ROOT]
00:01:23,081 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
00:01:23,081 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2525ff7e - Registering current configuration as safe fallback point

2021-03-08 00:01:23.101 [MLog-Init-Reporter] INFO com.mchange.v2.log.MLog - MLog clients using slf4j logging.
2021-03-08 00:01:24.043 [main] INFO com.mchange.v2.c3p0.C3P0Registry - Initializing c3p0-0.9.5.2 [built 08-December-2015 22:06:04 -0800; debug? true; trace: 10]

2021-03-08 00:01:25.083 [main] INFO c.m.v2.c3p0.impl.AbstractPoolBackedDataSource - Initializing c3p0 pool… com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 3, acquireRetryAttempts -> 2, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, contextClassLoaderSource -> caller, dataSourceName -> 1hgekugag3q2dwedq8fly|37654521, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.cj.jdbc.Driver, extensions -> {}, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, forceSynchronousCheckins -> false, forceUseNamedDriverClass -> false, identityToken -> 1hgekugag3q2dwedq8fly|37654521, idleConnectionTestPeriod -> 0, initialPoolSize -> 10, jdbcUrl -> jdbc:mysql://localhost:3306/o2o?characterEncoding=utf8&useUnicode=true&useSSL=false&serverTimezone=UTC, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 30, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 10, numHelperThreads -> 3, preferredTestQuery -> null, privilegeSpawnedThreads -> false, properties -> {user=******, password=******}, propertyCycle -> 0, statementCacheNumDeferredCloseThreads -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, userOverrides -> {}, usesTraditionalReflectiveProxies -> false ]
1