08-Jul-2021 00:04:16.543 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/9.0.48 08-Jul-2021 00:04:16.544 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Jun 10 2021 09:22:01 UTC 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.48.0 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Windows 10 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 10.0 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: E:\Java\jdk1.8.0_291\jre 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 1.8.0_291-b10 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 08-Jul-2021 00:04:16.545 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48\conf\logging.properties 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs= 08-Jul-2021 00:04:16.546 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48 08-Jul-2021 00:04:16.548 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48 08-Jul-2021 00:04:16.548 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48\temp 08-Jul-2021 00:04:16.551 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.30] using APR version [1.7.0]. 08-Jul-2021 00:04:16.551 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [true]. 08-Jul-2021 00:04:16.551 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 08-Jul-2021 00:04:16.555 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1k 25 Mar 2021] 08-Jul-2021 00:04:16.807 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 08-Jul-2021 00:04:16.824 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [408] milliseconds 08-Jul-2021 00:04:16.849 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 08-Jul-2021 00:04:16.849 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.48] 08-Jul-2021 00:04:16.866 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48\webapps\kaui.war] 08-Jul-2021 00:04:19.621 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time. 00:04:19,680 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 00:04:19,680 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy] 00:04:19,681 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/E:/Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/kaui/WEB-INF/classes/logback.xml] 00:04:19,756 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 00:04:19,758 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 00:04:19,772 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 00:04:19,781 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 00:04:19,836 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender] 00:04:19,843 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [MAIN] 00:04:19,863 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@52290172 - Will use gz compression 00:04:19,865 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@52290172 - Will use the pattern ./logs/kaui-%d{yyyy-MM-dd}.%i.out for the active file 00:04:19,868 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7ebfe01a - The date pattern is 'yyyy-MM-dd' from file name pattern './logs/kaui-%d{yyyy-MM-dd}.%i.out.gz'. 00:04:19,868 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7ebfe01a - Roll-over at midnight. 00:04:19,869 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7ebfe01a - Setting initial period to Thu Jul 08 00:02:18 SGT 2021 00:04:19,870 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7ebfe01a - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead 00:04:19,870 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7ebfe01a - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy 00:04:19,873 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@52290172 - Cleaning on start up 00:04:19,876 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 00:04:19,876 |-INFO in c.q.l.core.rolling.helper.TimeBasedArchiveRemover - first clean up after appender initialization 00:04:19,876 |-INFO in c.q.l.core.rolling.helper.TimeBasedArchiveRemover - Multiple periods, i.e. 32 periods, seem to have elapsed. This is expected at application start. 00:04:19,878 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[MAIN] - Active log file name: ./logs/kaui.out 00:04:19,878 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[MAIN] - File property is set to [./logs/kaui.out] 00:04:19,880 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO 00:04:19,880 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [MAIN] to Logger[ROOT] 00:04:19,881 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT] 00:04:19,881 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 00:04:19,882 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@154b0748 - Registering current configuration as safe fallback point 2021-07-07T16:04:20,159+0000 lvl='INFO', log='', th='main', xff='', rId='', aId='', tId='', jruby 9.1.17.0 (2.3.3) 2018-04-20 d8b1ff9 Java HotSpot(TM) 64-Bit Server VM 25.291-b10 on 1.8.0_291-b10 +jit [mswin32-x86_64] 2021-07-07T16:04:20,164+0000 lvl='INFO', log='', th='main', xff='', rId='', aId='', tId='', using a shared (threadsafe!) runtime E:/Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/kaui/WEB-INF/gems/gems/js-routes-1.4.14/lib/js_routes.rb:22: warning: Dir.exists? is a deprecated name, use Dir.exist? instead System property java.security.egd has not been set, this may cause some requests to hang because of a lack of entropy. You should probably set it to 'file:/dev/./urandom' 08-Jul-2021 00:04:41.758 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48\webapps\kaui.war] has finished in [24,892] ms 08-Jul-2021 00:04:41.760 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [E:\Tools\apache-tomcat-9.0.48-windows-x64\apache-tomcat-9.0.48\webapps\ROOT.war] 08-Jul-2021 00:04:46.943 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time. 2021-07-07T16:04:47,244+0000 lvl='INFO', log='DefaultKillbillConfigSource', th='main', xff='', rId='', tok='', aRId='', tRId='', Overwrite of user.timezone system property with Asia/Singapore may break database serialization of date. Kill Bill will overwrite to GMT 2021-07-07T16:04:47,251+0000 lvl='WARN', log='DefaultKillbillConfigSource', th='main', xff='', rId='', tok='', aRId='', tRId='', System property java.security.egd has not been set, this may cause some requests to hang because of a lack of entropy. You should probably set it to 'file:/dev/./urandom' 2021-07-07T16:04:47,287+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [local] for [org.killbill.server.region] on [org.killbill.billing.server.config.KillbillServerConfig#getRegion()] 2021-07-07T16:04:47,291+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0s] for [org.killbill.server.shutdownDelay] on [org.killbill.billing.server.config.KillbillServerConfig#getShutdownDelay()] 2021-07-07T16:04:47,292+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.server.multitenant] on [org.killbill.billing.server.config.KillbillServerConfig#isMultiTenancyEnabled()] 2021-07-07T16:04:47,293+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.server.test.mode] on [org.killbill.billing.server.config.KillbillServerConfig#isTestModeEnabled()] 2021-07-07T16:04:47,293+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.server.test.clock.redis] on [org.killbill.billing.server.config.KillbillServerConfig#isRedisClockEnabled()] 2021-07-07T16:04:47,294+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.server.test.clock.redis.connectionMinimumIdleSize] on [org.killbill.billing.server.config.KillbillServerConfig#getConnectionMinimumIdleSize()] 2021-07-07T16:04:47,294+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [http://127.0.0.1:8080] for [org.killbill.server.baseUrl] on [org.killbill.billing.server.config.KillbillServerConfig#getBaseUrl()] 2021-07-07T16:04:47,295+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [redis://127.0.0.1:6379] for [org.killbill.server.test.clock.redis.url] on [org.killbill.billing.server.config.KillbillServerConfig#getUrl()] 2021-07-07T16:04:47,295+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.server.http.gzip] on [org.killbill.billing.server.config.KillbillServerConfig#isConfiguredToReturnGZIPResponses()] 2021-07-07T16:04:47,406+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2003] for [org.killbill.metrics.graphite.port] on [org.killbill.billing.server.config.MetricsGraphiteConfig#getPort()] 2021-07-07T16:04:47,406+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.metrics.graphite.prefix] on [org.killbill.billing.server.config.MetricsGraphiteConfig#getPrefix()] 2021-07-07T16:04:47,407+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30] for [org.killbill.metrics.graphite.interval] on [org.killbill.billing.server.config.MetricsGraphiteConfig#getInterval()] 2021-07-07T16:04:47,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [localhost] for [org.killbill.metrics.graphite.host] on [org.killbill.billing.server.config.MetricsGraphiteConfig#getHostname()] 2021-07-07T16:04:47,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.metrics.graphite] on [org.killbill.billing.server.config.MetricsGraphiteConfig#isGraphiteReportingEnabled()] 2021-07-07T16:04:47,412+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2003] for [org.killbill.metrics.influxDb.port] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getPort()] 2021-07-07T16:04:47,412+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.metrics.influxDb.prefix] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getPrefix()] 2021-07-07T16:04:47,413+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30] for [org.killbill.metrics.influxDb.interval] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getInterval()] 2021-07-07T16:04:47,413+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.metrics.influxDb.database] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getDatabase()] 2021-07-07T16:04:47,414+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [localhost] for [org.killbill.metrics.influxDb.host] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getHostname()] 2021-07-07T16:04:47,414+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [HTTP] for [org.killbill.metrics.influxDb.senderType] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getSenderType()] 2021-07-07T16:04:47,416+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.metrics.influxDb] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#isInfluxDbReportingEnabled()] 2021-07-07T16:04:47,416+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1000] for [org.killbill.metrics.influxDb.socketTimeout] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#getSocketTimeout()] 2021-07-07T16:04:48,418+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.dao.readOnly] on [org.killbill.commons.jdbi.guice.DaoConfig#isReadOnly()] 2021-07-07T16:04:48,418+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.connectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionTimeout()] 2021-07-07T16:04:48,419+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.dao.maxActive] on [org.killbill.commons.jdbi.guice.DaoConfig#getMaxActive()] 2021-07-07T16:04:48,420+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.dao.password] on [org.killbill.commons.jdbi.guice.DaoConfig#getPassword()] 2021-07-07T16:04:48,420+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.dao.user] on [org.killbill.commons.jdbi.guice.DaoConfig#getUsername()] 2021-07-07T16:04:48,421+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2048] for [org.killbill.dao.prepStmtCacheSqlLimit] on [org.killbill.commons.jdbi.guice.DaoConfig#getPreparedStatementsCacheSqlLimit()] 2021-07-07T16:04:48,421+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.dao.useServerPrepStmts] on [org.killbill.commons.jdbi.guice.DaoConfig#isServerSidePreparedStatementsEnabled()] 2021-07-07T16:04:48,421+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50ms] for [org.killbill.dao.healthCheckExpected99thPercentile] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckExpected99thPercentile()] 2021-07-07T16:04:48,422+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0m] for [org.killbill.dao.maxConnectionAge] on [org.killbill.commons.jdbi.guice.DaoConfig#getMaxConnectionAge()] 2021-07-07T16:04:48,422+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.dao.initializationFailFast] on [org.killbill.commons.jdbi.guice.DaoConfig#isInitializationFailFast()] 2021-07-07T16:04:48,423+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.dao.idleConnectionTestPeriod] on [org.killbill.commons.jdbi.guice.DaoConfig#getIdleConnectionTestPeriod()] 2021-07-07T16:04:48,423+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.dataSourceClassName] on [org.killbill.commons.jdbi.guice.DaoConfig#getDataSourceClassName()] 2021-07-07T16:04:48,424+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [HIKARICP] for [org.killbill.dao.poolingType] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionPoolingType()] 2021-07-07T16:04:48,426+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.connectionInitSql] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionInitSql()] 2021-07-07T16:04:48,427+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [TRANSACTION_READ_COMMITTED] for [org.killbill.dao.transactionIsolationLevel] on [org.killbill.commons.jdbi.guice.DaoConfig#getTransactionIsolationLevel()] 2021-07-07T16:04:48,428+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5.1] for [org.killbill.dao.mysqlServerVersion] on [org.killbill.commons.jdbi.guice.DaoConfig#getMySQLServerVersion()] 2021-07-07T16:04:48,428+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.driverClassName] on [org.killbill.commons.jdbi.guice.DaoConfig#getDriverClassName()] 2021-07-07T16:04:48,428+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.dao.cachePrepStmts] on [org.killbill.commons.jdbi.guice.DaoConfig#isPreparedStatementsCacheEnabled()] 2021-07-07T16:04:48,429+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60s] for [org.killbill.dao.leakDetectionThreshold] on [org.killbill.commons.jdbi.guice.DaoConfig#getLeakDetectionThreshold()] 2021-07-07T16:04:48,429+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [500] for [org.killbill.dao.prepStmtCacheSize] on [org.killbill.commons.jdbi.guice.DaoConfig#getPreparedStatementsCacheSize()] 2021-07-07T16:04:48,430+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.healthCheckConnectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckConnectionTimeout()] 2021-07-07T16:04:48,430+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.dao.minIdle] on [org.killbill.commons.jdbi.guice.DaoConfig#getMinIdle()] 2021-07-07T16:04:48,430+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [DEBUG] for [org.killbill.dao.logLevel] on [org.killbill.commons.jdbi.guice.DaoConfig#getLogLevel()] 2021-07-07T16:04:48,431+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning value [jdbc:mysql://127.0.0.1:3306/killbill?allowPublicKeyRetrieval=true&useSSL=false] for [org.killbill.dao.url] on [org.killbill.commons.jdbi.guice.DaoConfig#getJdbcUrl()] 2021-07-07T16:04:48,431+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60m] for [org.killbill.dao.idleMaxAge] on [org.killbill.commons.jdbi.guice.DaoConfig#getIdleMaxAge()] 2021-07-07T16:04:48,438+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.billing.main-ro.dao.readOnly] on [org.killbill.billing.server.modules.MainRoDaoConfig#isReadOnly()] 2021-07-07T16:04:48,438+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.billing.main-ro.dao.enabled] on [org.killbill.billing.server.modules.MainRoDaoConfig#isEnabled()] 2021-07-07T16:04:48,439+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.billing.main-ro.dao.connectionTimeout] on [org.killbill.billing.server.modules.MainRoDaoConfig#getConnectionTimeout()] 2021-07-07T16:04:48,439+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.billing.main-ro.dao.maxActive] on [org.killbill.billing.server.modules.MainRoDaoConfig#getMaxActive()] 2021-07-07T16:04:48,440+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.billing.main-ro.dao.password] on [org.killbill.billing.server.modules.MainRoDaoConfig#getPassword()] 2021-07-07T16:04:48,440+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.billing.main-ro.dao.user] on [org.killbill.billing.server.modules.MainRoDaoConfig#getUsername()] 2021-07-07T16:04:48,441+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2048] for [org.killbill.billing.main-ro.dao.prepStmtCacheSqlLimit] on [org.killbill.billing.server.modules.MainRoDaoConfig#getPreparedStatementsCacheSqlLimit()] 2021-07-07T16:04:48,441+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.billing.main-ro.dao.useServerPrepStmts] on [org.killbill.billing.server.modules.MainRoDaoConfig#isServerSidePreparedStatementsEnabled()] 2021-07-07T16:04:48,441+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0m] for [org.killbill.billing.main-ro.dao.maxConnectionAge] on [org.killbill.billing.server.modules.MainRoDaoConfig#getMaxConnectionAge()] 2021-07-07T16:04:48,442+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.billing.main-ro.dao.initializationFailFast] on [org.killbill.billing.server.modules.MainRoDaoConfig#isInitializationFailFast()] 2021-07-07T16:04:48,442+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.billing.main-ro.dao.idleConnectionTestPeriod] on [org.killbill.billing.server.modules.MainRoDaoConfig#getIdleConnectionTestPeriod()] 2021-07-07T16:04:48,442+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.billing.main-ro.dao.dataSourceClassName] on [org.killbill.billing.server.modules.MainRoDaoConfig#getDataSourceClassName()] 2021-07-07T16:04:48,443+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [HIKARICP] for [org.killbill.billing.main-ro.dao.poolingType] on [org.killbill.billing.server.modules.MainRoDaoConfig#getConnectionPoolingType()] 2021-07-07T16:04:48,443+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [TRANSACTION_READ_COMMITTED] for [org.killbill.billing.main-ro.dao.transactionIsolationLevel] on [org.killbill.billing.server.modules.MainRoDaoConfig#getTransactionIsolationLevel()] 2021-07-07T16:04:48,444+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5.1] for [org.killbill.billing.main-ro.dao.mysqlServerVersion] on [org.killbill.billing.server.modules.MainRoDaoConfig#getMySQLServerVersion()] 2021-07-07T16:04:48,444+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.billing.main-ro.dao.driverClassName] on [org.killbill.billing.server.modules.MainRoDaoConfig#getDriverClassName()] 2021-07-07T16:04:48,444+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.billing.main-ro.dao.cachePrepStmts] on [org.killbill.billing.server.modules.MainRoDaoConfig#isPreparedStatementsCacheEnabled()] 2021-07-07T16:04:48,445+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60s] for [org.killbill.billing.main-ro.dao.leakDetectionThreshold] on [org.killbill.billing.server.modules.MainRoDaoConfig#getLeakDetectionThreshold()] 2021-07-07T16:04:48,445+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [500] for [org.killbill.billing.main-ro.dao.prepStmtCacheSize] on [org.killbill.billing.server.modules.MainRoDaoConfig#getPreparedStatementsCacheSize()] 2021-07-07T16:04:48,445+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.billing.main-ro.dao.minIdle] on [org.killbill.billing.server.modules.MainRoDaoConfig#getMinIdle()] 2021-07-07T16:04:48,446+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [DEBUG] for [org.killbill.billing.main-ro.dao.logLevel] on [org.killbill.billing.server.modules.MainRoDaoConfig#getLogLevel()] 2021-07-07T16:04:48,446+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [jdbc:h2:file:/var/tmp/killbill;MODE=MYSQL;DB_CLOSE_DELAY=-1;DB_CLOSE_ON_EXIT=FALSE;ACCESS_MODE_DATA=r] for [org.killbill.billing.main-ro.dao.url] on [org.killbill.billing.server.modules.MainRoDaoConfig#getJdbcUrl()] 2021-07-07T16:04:48,447+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60m] for [org.killbill.billing.main-ro.dao.idleMaxAge] on [org.killbill.billing.server.modules.MainRoDaoConfig#getIdleMaxAge()] 2021-07-07T16:04:48,447+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50ms] for [org.killbill.dao.healthCheckExpected99thPercentile] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckExpected99thPercentile()] 2021-07-07T16:04:48,447+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.connectionInitSql] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionInitSql()] 2021-07-07T16:04:48,450+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.healthCheckConnectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckConnectionTimeout()] 2021-07-07T16:04:51,087+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [bus_events] for [org.killbill.persistent.bus.${instanceName}.tableName] on [org.killbill.bus.api.PersistentBusConfig#getTableName()] 2021-07-07T16:04:51,087+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.persistent.bus.${instanceName}.inMemory] on [org.killbill.bus.api.PersistentBusConfig#isInMemory()] 2021-07-07T16:04:51,089+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.persistent.bus.${instanceName}.claim.time] on [org.killbill.bus.api.PersistentBusConfig#getClaimedTime()] 2021-07-07T16:04:51,090+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.persistent.bus.${instanceName}.off] on [org.killbill.bus.api.PersistentBusConfig#isProcessingOff()] 2021-07-07T16:04:51,091+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3m] for [org.killbill.persistent.bus.${instanceName}.reapSchedule] on [org.killbill.bus.api.PersistentBusConfig#getReapSchedule()] 2021-07-07T16:04:51,092+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10m] for [org.killbill.persistent.bus.${instanceName}.reapThreshold] on [org.killbill.bus.api.PersistentBusConfig#getReapThreshold()] 2021-07-07T16:04:51,092+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3] for [org.killbill.persistent.bus.${instanceName}.max.failure.retry] on [org.killbill.bus.api.PersistentBusConfig#getMaxFailureRetries()] 2021-07-07T16:04:51,093+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.persistent.bus.${instanceName}.inflight.max] on [org.killbill.bus.api.PersistentBusConfig#getMaxInFlightEntries()] 2021-07-07T16:04:51,093+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30] for [org.killbill.persistent.bus.${instanceName}.nbThreads] on [org.killbill.bus.api.PersistentBusConfig#geMaxDispatchThreads()] 2021-07-07T16:04:51,094+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3000] for [org.killbill.persistent.bus.${instanceName}.sleep] on [org.killbill.bus.api.PersistentBusConfig#getPollingSleepTimeMs()] 2021-07-07T16:04:51,094+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2] for [org.killbill.persistent.bus.${instanceName}.lifecycle.complete.nbThreads] on [org.killbill.bus.api.PersistentBusConfig#geNbLifecycleCompleteThreads()] 2021-07-07T16:04:51,094+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.persistent.bus.${instanceName}.lifecycle.dispatch.nbThreads] on [org.killbill.bus.api.PersistentBusConfig#geNbLifecycleDispatchThreads()] 2021-07-07T16:04:51,095+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.persistent.bus.${instanceName}.inflight.min] on [org.killbill.bus.api.PersistentBusConfig#getMinInFlightEntries()] 2021-07-07T16:04:51,095+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.persistent.bus.${instanceName}.claimed] on [org.killbill.bus.api.PersistentBusConfig#getMaxEntriesClaimed()] 2021-07-07T16:04:51,096+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30000] for [org.killbill.persistent.bus.${instanceName}.queue.capacity] on [org.killbill.bus.api.PersistentBusConfig#getEventQueueCapacity()] 2021-07-07T16:04:51,096+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [STICKY_EVENTS] for [org.killbill.persistent.bus.${instanceName}.queue.mode] on [org.killbill.bus.api.PersistentBusConfig#getPersistentQueueMode()] 2021-07-07T16:04:51,097+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.persistent.bus.${instanceName}.maxReDispatchCount] on [org.killbill.bus.api.PersistentBusConfig#getMaxReDispatchCount()] 2021-07-07T16:04:51,097+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [bus_events_history] for [org.killbill.persistent.bus.${instanceName}.historyTableName] on [org.killbill.bus.api.PersistentBusConfig#getHistoryTableName()] 2021-07-07T16:04:51,105+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning value [bus_ext_events] for [org.killbill.persistent.bus.external.tableName] on [org.killbill.bus.api.PersistentBusConfig#getTableName()] 2021-07-07T16:04:51,105+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.persistent.bus.${instanceName}.inMemory] on [org.killbill.bus.api.PersistentBusConfig#isInMemory()] 2021-07-07T16:04:51,106+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.persistent.bus.${instanceName}.claim.time] on [org.killbill.bus.api.PersistentBusConfig#getClaimedTime()] 2021-07-07T16:04:51,106+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.persistent.bus.${instanceName}.off] on [org.killbill.bus.api.PersistentBusConfig#isProcessingOff()] 2021-07-07T16:04:51,106+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3m] for [org.killbill.persistent.bus.${instanceName}.reapSchedule] on [org.killbill.bus.api.PersistentBusConfig#getReapSchedule()] 2021-07-07T16:04:51,107+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10m] for [org.killbill.persistent.bus.${instanceName}.reapThreshold] on [org.killbill.bus.api.PersistentBusConfig#getReapThreshold()] 2021-07-07T16:04:51,107+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3] for [org.killbill.persistent.bus.${instanceName}.max.failure.retry] on [org.killbill.bus.api.PersistentBusConfig#getMaxFailureRetries()] 2021-07-07T16:04:51,107+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.persistent.bus.${instanceName}.inflight.max] on [org.killbill.bus.api.PersistentBusConfig#getMaxInFlightEntries()] 2021-07-07T16:04:51,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30] for [org.killbill.persistent.bus.${instanceName}.nbThreads] on [org.killbill.bus.api.PersistentBusConfig#geMaxDispatchThreads()] 2021-07-07T16:04:51,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3000] for [org.killbill.persistent.bus.${instanceName}.sleep] on [org.killbill.bus.api.PersistentBusConfig#getPollingSleepTimeMs()] 2021-07-07T16:04:51,109+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2] for [org.killbill.persistent.bus.${instanceName}.lifecycle.complete.nbThreads] on [org.killbill.bus.api.PersistentBusConfig#geNbLifecycleCompleteThreads()] 2021-07-07T16:04:51,109+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.persistent.bus.${instanceName}.lifecycle.dispatch.nbThreads] on [org.killbill.bus.api.PersistentBusConfig#geNbLifecycleDispatchThreads()] 2021-07-07T16:04:51,109+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.persistent.bus.${instanceName}.inflight.min] on [org.killbill.bus.api.PersistentBusConfig#getMinInFlightEntries()] 2021-07-07T16:04:51,110+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.persistent.bus.${instanceName}.claimed] on [org.killbill.bus.api.PersistentBusConfig#getMaxEntriesClaimed()] 2021-07-07T16:04:51,110+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30000] for [org.killbill.persistent.bus.${instanceName}.queue.capacity] on [org.killbill.bus.api.PersistentBusConfig#getEventQueueCapacity()] 2021-07-07T16:04:51,110+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [STICKY_EVENTS] for [org.killbill.persistent.bus.${instanceName}.queue.mode] on [org.killbill.bus.api.PersistentBusConfig#getPersistentQueueMode()] 2021-07-07T16:04:51,111+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.persistent.bus.${instanceName}.maxReDispatchCount] on [org.killbill.bus.api.PersistentBusConfig#getMaxReDispatchCount()] 2021-07-07T16:04:51,111+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning value [bus_ext_events_history] for [org.killbill.persistent.bus.external.historyTableName] on [org.killbill.bus.api.PersistentBusConfig#getHistoryTableName()] 2021-07-07T16:04:51,118+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [notifications] for [org.killbill.notificationq.${instanceName}.tableName] on [org.killbill.notificationq.api.NotificationQueueConfig#getTableName()] 2021-07-07T16:04:51,119+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.notificationq.${instanceName}.inMemory] on [org.killbill.notificationq.api.NotificationQueueConfig#isInMemory()] 2021-07-07T16:04:51,120+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.notificationq.${instanceName}.claim.time] on [org.killbill.notificationq.api.NotificationQueueConfig#getClaimedTime()] 2021-07-07T16:04:51,120+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.notificationq.${instanceName}.notification.off] on [org.killbill.notificationq.api.NotificationQueueConfig#isProcessingOff()] 2021-07-07T16:04:51,120+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3m] for [org.killbill.notificationq.${instanceName}.reapSchedule] on [org.killbill.notificationq.api.NotificationQueueConfig#getReapSchedule()] 2021-07-07T16:04:51,121+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10m] for [org.killbill.notificationq.${instanceName}.reapThreshold] on [org.killbill.notificationq.api.NotificationQueueConfig#getReapThreshold()] 2021-07-07T16:04:51,121+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3] for [org.killbill.notificationq.${instanceName}.max.failure.retry] on [org.killbill.notificationq.api.NotificationQueueConfig#getMaxFailureRetries()] 2021-07-07T16:04:51,122+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [-1] for [org.killbill.persistent.bus.${instanceName}.inflight.max] on [org.killbill.notificationq.api.NotificationQueueConfig#getMaxInFlightEntries()] 2021-07-07T16:04:51,122+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.notificationq.${instanceName}.notification.nbThreads] on [org.killbill.notificationq.api.NotificationQueueConfig#geMaxDispatchThreads()] 2021-07-07T16:04:51,122+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3000] for [org.killbill.notificationq.${instanceName}.sleep] on [org.killbill.notificationq.api.NotificationQueueConfig#getPollingSleepTimeMs()] 2021-07-07T16:04:51,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2] for [org.killbill.notificationq.${instanceName}.lifecycle.complete.nbThreads] on [org.killbill.notificationq.api.NotificationQueueConfig#geNbLifecycleCompleteThreads()] 2021-07-07T16:04:51,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.notificationq.${instanceName}.lifecycle.dispatch.nbThreads] on [org.killbill.notificationq.api.NotificationQueueConfig#geNbLifecycleDispatchThreads()] 2021-07-07T16:04:51,124+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [-1] for [org.killbill.persistent.bus.${instanceName}.inflight.min] on [org.killbill.notificationq.api.NotificationQueueConfig#getMinInFlightEntries()] 2021-07-07T16:04:51,124+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.notificationq.${instanceName}.claimed] on [org.killbill.notificationq.api.NotificationQueueConfig#getMaxEntriesClaimed()] 2021-07-07T16:04:51,124+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.notificationq.${instanceName}.queue.capacity] on [org.killbill.notificationq.api.NotificationQueueConfig#getEventQueueCapacity()] 2021-07-07T16:04:51,125+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [STICKY_POLLING] for [org.killbill.notificationq.${instanceName}.queue.mode] on [org.killbill.notificationq.api.NotificationQueueConfig#getPersistentQueueMode()] 2021-07-07T16:04:51,125+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.notificationq.${instanceName}.maxReDispatchCount] on [org.killbill.notificationq.api.NotificationQueueConfig#getMaxReDispatchCount()] 2021-07-07T16:04:51,125+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [notifications_history] for [org.killbill.notificationq.${instanceName}.historyTableName] on [org.killbill.notificationq.api.NotificationQueueConfig#getHistoryTableName()] 2021-07-07T16:04:51,130+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.billing.osgi.dao.readOnly] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#isReadOnly()] 2021-07-07T16:04:51,130+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.billing.osgi.dao.connectionTimeout] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getConnectionTimeout()] 2021-07-07T16:04:51,130+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.billing.osgi.dao.maxActive] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getMaxActive()] 2021-07-07T16:04:51,131+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.billing.osgi.dao.password] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getPassword()] 2021-07-07T16:04:51,131+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill] for [org.killbill.billing.osgi.dao.user] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getUsername()] 2021-07-07T16:04:51,132+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2048] for [org.killbill.billing.osgi.dao.prepStmtCacheSqlLimit] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getPreparedStatementsCacheSqlLimit()] 2021-07-07T16:04:51,132+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.billing.osgi.dao.useServerPrepStmts] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#isServerSidePreparedStatementsEnabled()] 2021-07-07T16:04:51,132+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0m] for [org.killbill.billing.osgi.dao.maxConnectionAge] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getMaxConnectionAge()] 2021-07-07T16:04:51,133+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.billing.osgi.dao.initializationFailFast] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#isInitializationFailFast()] 2021-07-07T16:04:51,133+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.billing.osgi.dao.idleConnectionTestPeriod] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getIdleConnectionTestPeriod()] 2021-07-07T16:04:51,133+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.billing.osgi.dao.dataSourceClassName] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getDataSourceClassName()] 2021-07-07T16:04:51,135+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [HIKARICP] for [org.killbill.billing.osgi.dao.poolingType] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getConnectionPoolingType()] 2021-07-07T16:04:51,136+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [TRANSACTION_READ_COMMITTED] for [org.killbill.billing.osgi.dao.transactionIsolationLevel] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getTransactionIsolationLevel()] 2021-07-07T16:04:51,136+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5.1] for [org.killbill.billing.osgi.dao.mysqlServerVersion] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getMySQLServerVersion()] 2021-07-07T16:04:51,137+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.billing.osgi.dao.driverClassName] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getDriverClassName()] 2021-07-07T16:04:51,137+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.billing.osgi.dao.cachePrepStmts] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#isPreparedStatementsCacheEnabled()] 2021-07-07T16:04:51,137+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60s] for [org.killbill.billing.osgi.dao.leakDetectionThreshold] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getLeakDetectionThreshold()] 2021-07-07T16:04:51,138+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [500] for [org.killbill.billing.osgi.dao.prepStmtCacheSize] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getPreparedStatementsCacheSize()] 2021-07-07T16:04:51,138+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.billing.osgi.dao.minIdle] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getMinIdle()] 2021-07-07T16:04:51,139+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [DEBUG] for [org.killbill.billing.osgi.dao.logLevel] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getLogLevel()] 2021-07-07T16:04:51,139+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [jdbc:h2:file:/var/tmp/killbill;MODE=MYSQL;DB_CLOSE_DELAY=-1;DB_CLOSE_ON_EXIT=FALSE] for [org.killbill.billing.osgi.dao.url] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getJdbcUrl()] 2021-07-07T16:04:51,139+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60m] for [org.killbill.billing.osgi.dao.idleMaxAge] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getIdleMaxAge()] 2021-07-07T16:04:51,140+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50ms] for [org.killbill.dao.healthCheckExpected99thPercentile] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckExpected99thPercentile()] 2021-07-07T16:04:51,140+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.connectionInitSql] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionInitSql()] 2021-07-07T16:04:51,141+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.healthCheckConnectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckConnectionTimeout()] 2021-07-07T16:04:51,527+0000 lvl='INFO', log='EmbeddedDB', th='main', xff='', rId='', tok='', aRId='', tRId='', H2 started on http://127.0.0.1:8082. JDBC=jdbc:h2:file:/var/tmp/killbill;MODE=MYSQL;DB_CLOSE_DELAY=-1;DB_CLOSE_ON_EXIT=FALSE, Username=killbill, Password=killbill 2021-07-07T16:04:51,571+0000 lvl='ERROR', log='EmbeddedDBProvider', th='main', xff='', rId='', tok='', aRId='', tRId='', Error while initializing H2, opportunistically continuing the startup sequence java.io.IOException: org.h2.jdbc.JdbcSQLNonTransientConnectionException: A file path that is implicitly relative to the current working directory is not allowed in the database URL "jdbc:h2:file:/var/tmp/killbill;MODE=MYSQL;DB_CLOSE_DELAY=-1;DB_CLOSE_ON_EXIT=FALSE". Use an absolute path, ~/name, ./name, or the baseDir setting instead. [90011-200] at org.killbill.commons.embeddeddb.h2.H2EmbeddedDB.refreshTableNames(H2EmbeddedDB.java:114) at org.killbill.commons.embeddeddb.h2.H2EmbeddedDB.start(H2EmbeddedDB.java:97) at org.killbill.billing.server.modules.EmbeddedDBProvider.initializeEmbeddedDB(EmbeddedDBProvider.java:76) at org.killbill.billing.server.modules.EmbeddedDBProvider.get(EmbeddedDBProvider.java:63) at org.killbill.billing.server.modules.KillbillPlatformModule.configureOSGI(KillbillPlatformModule.java:211) at org.killbill.billing.server.modules.KillbillPlatformModule.configure(KillbillPlatformModule.java:92) at org.killbill.billing.server.modules.KillbillServerModule.configure(KillbillServerModule.java:99) at com.google.inject.AbstractModule.configure(AbstractModule.java:61) at com.google.inject.spi.Elements$RecordingBinder.install(Elements.java:347) at com.google.inject.spi.Elements.getElements(Elements.java:104) at com.google.inject.internal.InjectorShell$Builder.build(InjectorShell.java:137) at com.google.inject.internal.InternalInjectorCreator.build(InternalInjectorCreator.java:105) at com.google.inject.Guice.createInjector(Guice.java:87) at org.killbill.commons.skeleton.listeners.GuiceServletContextListener.getInjector(GuiceServletContextListener.java:97) at com.google.inject.servlet.GuiceServletContextListener.contextInitialized(GuiceServletContextListener.java:45) at org.killbill.commons.skeleton.listeners.GuiceServletContextListener.contextInitialized(GuiceServletContextListener.java:59) at org.killbill.billing.server.listeners.KillbillPlatformGuiceListener.initializeGuice(KillbillPlatformGuiceListener.java:185) at org.killbill.billing.server.listeners.KillbillPlatformGuiceListener.contextInitialized(KillbillPlatformGuiceListener.java:123) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4768) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5230) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:728) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:700) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:696) at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1024) at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1911) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75) at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:825) at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:475) at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1618) at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:319) at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123) at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423) at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366) at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:948) at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:835) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1398) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1388) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75) at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134) at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:921) at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:263) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) at org.apache.catalina.core.StandardService.startInternal(StandardService.java:437) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:934) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) at org.apache.catalina.startup.Catalina.start(Catalina.java:772) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:345) at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:476) Caused by: org.h2.jdbc.JdbcSQLNonTransientConnectionException: A file path that is implicitly relative to the current working directory is not allowed in the database URL "jdbc:h2:file:/var/tmp/killbill;MODE=MYSQL;DB_CLOSE_DELAY=-1;DB_CLOSE_ON_EXIT=FALSE". Use an absolute path, ~/name, ./name, or the baseDir setting instead. [90011-200] at org.h2.message.DbException.getJdbcSQLException(DbException.java:622) at org.h2.message.DbException.getJdbcSQLException(DbException.java:429) at org.h2.message.DbException.get(DbException.java:205) at org.h2.message.DbException.get(DbException.java:181) at org.h2.engine.ConnectionInfo.getName(ConnectionInfo.java:396) at org.h2.engine.Engine.openSession(Engine.java:50) at org.h2.engine.Engine.openSession(Engine.java:192) at org.h2.engine.Engine.createSessionAndValidate(Engine.java:171) at org.h2.engine.Engine.createSession(Engine.java:166) at org.h2.engine.Engine.createSession(Engine.java:29) at org.h2.engine.SessionRemote.connectEmbeddedOrServer(SessionRemote.java:340) at org.h2.jdbc.JdbcConnection.(JdbcConnection.java:173) at org.h2.jdbc.JdbcConnection.(JdbcConnection.java:152) at org.h2.Driver.connect(Driver.java:69) at org.h2.jdbcx.JdbcDataSource.getJdbcConnection(JdbcDataSource.java:189) at org.h2.jdbcx.JdbcDataSource.getXAConnection(JdbcDataSource.java:352) at org.h2.jdbcx.JdbcDataSource.getPooledConnection(JdbcDataSource.java:384) at org.h2.jdbcx.JdbcConnectionPool.getConnectionNow(JdbcConnectionPool.java:234) at org.h2.jdbcx.JdbcConnectionPool.getConnection(JdbcConnectionPool.java:199) at org.killbill.commons.embeddeddb.EmbeddedDB.getConnection(EmbeddedDB.java:230) at org.killbill.commons.embeddeddb.EmbeddedDB.executeQuery(EmbeddedDB.java:222) at org.killbill.commons.embeddeddb.h2.H2EmbeddedDB.refreshTableNames(H2EmbeddedDB.java:104) ... 58 common frames omitted 2021-07-07T16:04:51,573+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [/var/tmp/bundles] for [org.killbill.osgi.bundle.install.dir] on [org.killbill.billing.osgi.config.OSGIConfig#getRootInstallationDir()] 2021-07-07T16:04:51,575+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [osgi-cache] for [org.killbill.osgi.bundle.cache.name] on [org.killbill.billing.osgi.config.OSGIConfig#getOSGIBundleCacheName()] 2021-07-07T16:04:51,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [/var/tmp/felix] for [org.killbill.osgi.root.dir] on [org.killbill.billing.osgi.config.OSGIConfig#getOSGIBundleRootDir()] 2021-07-07T16:04:51,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill.properties] for [org.killbill.osgi.bundle.property.name] on [org.killbill.billing.osgi.config.OSGIConfig#getOSGIKillbillPropertyName()] 2021-07-07T16:04:51,577+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [org.killbill.billing.account.api,org.killbill.billing.analytics.api.sanity,org.killbill.billing.analytics.api.user,org.killbill.billing.beatrix.bus.api,org.killbill.billing.catalog.api,org.killbill.billing.catalog.api.rules,org.killbill.billing.invoice.plugin.api,org.killbill.billing.invoice.api,org.killbill.billing.invoice.api.formatters,org.killbill.billing.entitlement.api,org.killbill.billing,org.killbill.clock,org.killbill.billing.notification.api,org.killbill.billing.notification.plugin.api,org.killbill.billing.notification.plugin,org.killbill.billing.osgi.api,org.killbill.billing.osgi.api.config,org.killbill.billing.overdue,org.killbill.billing.overdue.api,org.killbill.billing.payment.api,org.killbill.billing.payment.plugin.api,org.killbill.billing.control.plugin.api,org.killbill.billing.tenant.api,org.killbill.billing.usage.api,org.killbill.billing.util.api,org.killbill.billing.util.nodes,org.killbill.billing.util.audit,org.killbill.billing.util.callcontext,org.killbill.billing.util.customfield,org.killbill.billing.util.email,org.killbill.billing.util.entity,org.killbill.billing.util.tag,org.killbill.billing.util.template,org.killbill.billing.util.template.translation,org.killbill.billing.currency.plugin.api,org.killbill.billing.catalog.plugin.api,org.killbill.billing.entitlement.plugin.api,org.killbill.billing.currency.api,org.killbill.billing.usage.plugin.api,org.killbill.billing.security,org.killbill.billing.security.api,org.killbill.billing.osgi.libs.killbill,org.joda.time;org.joda.time.format;version=2.9,org.apache.shiro;org.apache.shiro.subject;org.apache.shiro.util;version=1.3,org.slf4j;version=1.7.2,org.slf4j.event;version=1.7.2,org.slf4j.helpers;version=1.7.2,org.slf4j.spi;version=1.7.2,org.osgi.service.log;version=1.3,org.osgi.service.http;version=1.2.0,org.osgi.service.deploymentadmin;version=1.1.0,org.osgi.service.event;version=1.2.0] for [org.killbill.osgi.system.bundle.export.packages.api] on [org.killbill.billing.osgi.config.OSGIConfig#getSystemBundleExportPackagesApi()] 2021-07-07T16:04:51,578+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [com.sun.xml.internal.ws,com.sun.xml.internal.ws.addressing,com.sun.xml.internal.ws.addressing.model,com.sun.xml.internal.ws.addressing.policy,com.sun.xml.internal.ws.addressing.v200408,com.sun.xml.internal.ws.api,com.sun.xml.internal.ws.api.addressing,com.sun.xml.internal.ws.api.client,com.sun.xml.internal.ws.api.config.management,com.sun.xml.internal.ws.api.config.management.policy,com.sun.xml.internal.ws.api.fastinfoset,com.sun.xml.internal.ws.api.ha,com.sun.xml.internal.ws.api.handler,com.sun.xml.internal.ws.api.message,com.sun.xml.internal.ws.api.message.stream,com.sun.xml.internal.ws.api.model,com.sun.xml.internal.ws.api.model.soap,com.sun.xml.internal.ws.api.model.wsdl,com.sun.xml.internal.ws.api.pipe,com.sun.xml.internal.ws.api.pipe.helper,com.sun.xml.internal.ws.api.policy,com.sun.xml.internal.ws.api.server,com.sun.xml.internal.ws.api.streaming,com.sun.xml.internal.ws.api.wsdl.parser,com.sun.xml.internal.ws.api.wsdl.writer,com.sun.xml.internal.ws.binding,com.sun.xml.internal.ws.client,com.sun.xml.internal.ws.client.dispatch,com.sun.xml.internal.ws.client.sei,com.sun.xml.internal.ws.config.management.policy,com.sun.xml.internal.ws.developer,com.sun.xml.internal.ws.encoding,com.sun.xml.internal.ws.encoding.fastinfoset,com.sun.xml.internal.ws.encoding.policy,com.sun.xml.internal.ws.encoding.soap,com.sun.xml.internal.ws.encoding.soap.streaming,com.sun.xml.internal.ws.encoding.xml,com.sun.xml.internal.ws.fault,com.sun.xml.internal.ws.handler,com.sun.xml.internal.ws.message,com.sun.xml.internal.ws.message.jaxb,com.sun.xml.internal.ws.message.saaj,com.sun.xml.internal.ws.message.source,com.sun.xml.internal.ws.message.stream,com.sun.xml.internal.ws.model,com.sun.xml.internal.ws.model.soap,com.sun.xml.internal.ws.model.wsdl,com.sun.xml.internal.ws.org.objectweb.asm,com.sun.xml.internal.ws.policy,com.sun.xml.internal.ws.policy.jaxws,com.sun.xml.internal.ws.policy.jaxws.spi,com.sun.xml.internal.ws.policy.privateutil,com.sun.xml.internal.ws.policy.sourcemodel,com.sun.xml.internal.ws.policy.sourcemodel.attach,com.sun.xml.internal.ws.policy.sourcemodel.wspolicy,com.sun.xml.internal.ws.policy.spi,com.sun.xml.internal.ws.policy.subject,com.sun.xml.internal.ws.protocol.soap,com.sun.xml.internal.ws.protocol.xml,com.sun.xml.internal.ws.resources,com.sun.xml.internal.ws.server,com.sun.xml.internal.ws.server.provider,com.sun.xml.internal.ws.server.sei,com.sun.xml.internal.ws.spi,com.sun.xml.internal.ws.streaming,com.sun.xml.internal.ws.transport,com.sun.xml.internal.ws.transport.http,com.sun.xml.internal.ws.transport.http.client,com.sun.xml.internal.ws.transport.http.server,com.sun.xml.internal.ws.util,com.sun.xml.internal.ws.util.exception,com.sun.xml.internal.ws.util.localization,com.sun.xml.internal.ws.util.pipe,com.sun.xml.internal.ws.util.xml,com.sun.xml.internal.ws.wsdl,com.sun.xml.internal.ws.wsdl.parser,com.sun.xml.internal.ws.wsdl.writer,com.sun.xml.internal.ws.wsdl.writer.document,com.sun.xml.internal.ws.wsdl.writer.document.http,com.sun.xml.internal.ws.wsdl.writer.document.soap,com.sun.xml.internal.ws.wsdl.writer.document.soap12,com.sun.xml.internal.ws.wsdl.writer.document.xsd,javax.annotation,javax.management,javax.naming,javax.naming.ldap,javax.net,javax.net.ssl,javax.crypto,javax.crypto.spec,javax.sql,javax.sql.rowset,javax.sql.rowset.serial,javax.transaction,javax.transaction.xa,javax.xml,javax.xml.bind,javax.xml.validation,javax.xml.namespace,javax.xml.parsers,javax.xml.validation,javax.xml.stream,javax.xml.stream.events,javax.xml.stream.util,javax.xml.transform,javax.xml.transform.dom,javax.xml.transform.sax,javax.xml.transform.stax,javax.xml.transform.stream,javax.xml.xpath,javax.jws.soap,javax.security,javax.security.cert,com.sun.org,com.sun.org.apache,com.sun.org.apache.xml,com.sun.org.apache.xml.internal,com.sun.org.apache.xml.internal.utils,com.sun.org.apache.xpath,com.sun.org.apache.xpath.internal,com.sun.org.apache.xpath.internal.jaxp,com.sun.org.apache.xpath.internal.objects,org.w3c.dom,org.w3c.dom.bootstrap,org.w3c.dom.events,org.w3c.dom.ls,org.w3c.dom.css,org.w3c.dom.html,org.w3c.dom.ranges,org.w3c.dom.stylesheets,org.w3c.dom.traversal,org.w3c.dom.views,org.xml.sax,org.xml.sax.ext,org.xml.sax.helpers,sun.misc,sun.misc.unsafe,sun.security,sun.security.util,javax.servlet;version=3.1,javax.servlet.http;version=3.1] for [org.killbill.osgi.system.bundle.export.packages.java] on [org.killbill.billing.osgi.config.OSGIConfig#getSystemBundleExportPackagesJava()] 2021-07-07T16:04:51,582+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [] for [org.killbill.osgi.system.bundle.export.packages.extra] on [org.killbill.billing.osgi.config.OSGIConfig#getSystemBundleExportPackagesExtra()] 2021-07-07T16:04:51,597+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5s] for [org.killbill.billing.util.broadcast.rate] on [org.killbill.billing.util.config.definition.BroadcastConfig#getBroadcastServiceRunningRate()] 2021-07-07T16:04:51,599+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [ehcache.xml] for [org.killbill.cache.config.location] on [org.killbill.billing.util.config.definition.EhCacheConfig#getCacheConfigLocation()] 2021-07-07T16:04:51,601+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.cache.config.redis.connectionMinimumIdleSize] on [org.killbill.billing.util.config.definition.RedisCacheConfig#getConnectionMinimumIdleSize()] 2021-07-07T16:04:51,601+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.cache.config.redis.password] on [org.killbill.billing.util.config.definition.RedisCacheConfig#getPassword()] 2021-07-07T16:04:51,602+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [redis://127.0.0.1:6379] for [org.killbill.cache.config.redis.url] on [org.killbill.billing.util.config.definition.RedisCacheConfig#getUrl()] 2021-07-07T16:04:51,602+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.cache.config.redis] on [org.killbill.billing.util.config.definition.RedisCacheConfig#isRedisCachingEnabled()] 2021-07-07T16:04:51,614+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [] for [org.killbill.billing.server.event.dispatch.type.skip] on [org.killbill.billing.util.config.definition.EventConfig#getSkipDispatchBusEventTypeList()] 2021-07-07T16:04:51,615+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [] for [org.killbill.billing.server.event.post.type.skip] on [org.killbill.billing.util.config.definition.EventConfig#getSkipPostBusEventTypeList()] 2021-07-07T16:04:51,615+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.billing.server.event.bulk.subscription.aggregate] on [org.killbill.billing.util.config.definition.EventConfig#isAggregateBulkSubscriptionEvents()] 2021-07-07T16:04:51,618+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.catalog.loader.threads.pool.nb] on [org.killbill.billing.util.config.definition.CatalogConfig#getCatalogThreadNb()] 2021-07-07T16:04:51,619+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [SpyCarAdvanced.xml] for [org.killbill.catalog.uri] on [org.killbill.billing.util.config.definition.CatalogConfig#getCatalogURI()] 2021-07-07T16:04:51,621+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [killbill-currency-plugin] for [org.killbill.currency.provider.default] on [org.killbill.billing.util.config.definition.CurrencyConfig#getDefaultCurrencyProvider()] 2021-07-07T16:04:51,624+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [AGGREGATE] for [org.killbill.invoice.item.result.behavior.mode] on [org.killbill.billing.util.config.definition.InvoiceConfig#getItemResultBehaviorMode()] 2021-07-07T16:04:51,625+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [36] for [org.killbill.invoice.maxNumberOfMonthsInFuture] on [org.killbill.billing.util.config.definition.InvoiceConfig#getNumberOfMonthsInFuture()] 2021-07-07T16:04:51,625+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.invoice.disable.usage.zero.amount] on [org.killbill.billing.util.config.definition.InvoiceConfig#isUsageZeroAmountDisabled()] 2021-07-07T16:04:51,626+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2] for [org.killbill.invoice.readMaxRawUsagePreviousPeriod] on [org.killbill.billing.util.config.definition.InvoiceConfig#getMaxRawUsagePreviousPeriod()] 2021-07-07T16:04:51,626+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [23:59:59.999] for [org.killbill.invoice.parent.commit.local.utc.time] on [org.killbill.billing.util.config.definition.InvoiceConfig#getParentAutoCommitUtcTime()] 2021-07-07T16:04:51,626+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.invoice.sanitySafetyBoundEnabled] on [org.killbill.billing.util.config.definition.InvoiceConfig#isSanitySafetyBoundEnabled()] 2021-07-07T16:04:51,627+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.invoice.enabled] on [org.killbill.billing.util.config.definition.InvoiceConfig#isInvoicingSystemEnabled()] 2021-07-07T16:04:51,627+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [] for [org.killbill.invoice.plugin] on [org.killbill.billing.util.config.definition.InvoiceConfig#getInvoicePluginNames()] 2021-07-07T16:04:51,628+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50] for [org.killbill.invoice.globalLock.retries] on [org.killbill.billing.util.config.definition.InvoiceConfig#getMaxGlobalLockRetries()] 2021-07-07T16:04:51,628+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [P200Y] for [org.killbill.invoice.maxInvoiceLimit] on [org.killbill.billing.util.config.definition.InvoiceConfig#getMaxInvoiceLimit()] 2021-07-07T16:04:51,702+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0s] for [org.killbill.invoice.dryRunNotificationSchedule] on [org.killbill.billing.util.config.definition.InvoiceConfig#getDryRunNotificationSchedule()] 2021-07-07T16:04:51,702+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.invoice.emailNotificationsEnabled] on [org.killbill.billing.util.config.definition.InvoiceConfig#isEmailNotificationsEnabled()] 2021-07-07T16:04:51,704+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0s] for [org.killbill.invoice.rescheduleIntervalOnLock] on [org.killbill.billing.util.config.definition.InvoiceConfig#getRescheduleIntervalOnLock()] 2021-07-07T16:04:51,704+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [15] for [org.killbill.invoice.maxDailyNumberOfItemsSafetyBound] on [org.killbill.billing.util.config.definition.InvoiceConfig#getMaxDailyNumberOfItemsSafetyBound()] 2021-07-07T16:04:51,705+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.invoice.parkAccountsWithUnknownUsage] on [org.killbill.billing.util.config.definition.InvoiceConfig#shouldParkAccountsWithUnknownUsage()] 2021-07-07T16:04:51,709+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [org/killbill/billing/util/template/translation/InvoiceTranslation] for [org.killbill.template.bundlePath] on [org.killbill.billing.util.template.translation.TranslatorConfig#getInvoiceTemplateBundlePath()] 2021-07-07T16:04:51,710+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [org/killbill/billing/util/email/templates/HtmlInvoiceTemplate.mustache] for [org.killbill.manualPayTemplate.name] on [org.killbill.billing.util.template.translation.TranslatorConfig#getManualPayTemplateName()] 2021-07-07T16:04:51,710+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [org/killbill/billing/util/template/translation/CatalogTranslation] for [org.killbill.catalog.bundlePath] on [org.killbill.billing.util.template.translation.TranslatorConfig#getCatalogBundlePath()] 2021-07-07T16:04:51,711+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [org.killbill.billing.invoice.template.formatters.DefaultInvoiceFormatterFactory] for [org.killbill.template.invoiceFormatterFactoryClass] on [org.killbill.billing.util.template.translation.TranslatorConfig#getInvoiceFormatterFactoryClass()] 2021-07-07T16:04:51,711+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [org/killbill/billing/util/email/templates/HtmlInvoiceTemplate.mustache] for [org.killbill.template.name] on [org.killbill.billing.util.template.translation.TranslatorConfig#getTemplateName()] 2021-07-07T16:04:51,712+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [en_US] for [org.killbill.default.locale] on [org.killbill.billing.util.template.translation.TranslatorConfig#getDefaultLocale()] 2021-07-07T16:04:51,716+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [NoOverdueConfig.xml] for [org.killbill.overdue.uri] on [org.killbill.billing.overdue.OverdueProperties#getConfigURI()] 2021-07-07T16:04:51,766+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.okta.url] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaUrl()] 2021-07-07T16:04:51,767+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [ldap://127.0.0.1:389] for [org.killbill.security.ldap.url] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPUrl()] 2021-07-07T16:04:51,768+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [classpath:shiro.ini] for [org.killbill.security.shiroResourcePath] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroResourcePath()] 2021-07-07T16:04:51,768+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [200000] for [org.killbill.security.shiroNbHashIterations] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroNbHashIterations()] 2021-07-07T16:04:51,768+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [admin = *:* finance = invoice:*, payment:* support = entitlement:*, invoice:item_adjust] for [org.killbill.security.ldap.permissionsByGroup] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPPermissionsByGroup()] 2021-07-07T16:04:51,769+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.security.ldap.disableSSLCheck] on [org.killbill.billing.util.config.definition.SecurityConfig#disableShiroLDAPSSLCheck()] 2021-07-07T16:04:51,769+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.userDnTemplate] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPUserDnTemplate()] 2021-07-07T16:04:51,770+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.systemPassword] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSystemPassword()] 2021-07-07T16:04:51,770+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [memberOf] for [org.killbill.security.ldap.groupNameId] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPGroupNameID()] 2021-07-07T16:04:51,771+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.okta.apiToken] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaAPIToken()] 2021-07-07T16:04:51,771+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [memberOf=uid={0}] for [org.killbill.security.ldap.groupSearchFilter] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPGroupSearchFilter()] 2021-07-07T16:04:51,771+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.systemUsername] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSystemUsername()] 2021-07-07T16:04:51,772+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.security.ldap.followReferrals] on [org.killbill.billing.util.config.definition.SecurityConfig#followShiroLDAPReferrals()] 2021-07-07T16:04:51,772+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.searchBase] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSearchBase()] 2021-07-07T16:04:51,773+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.dnSearchTemplate] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPDnSearchTemplate()] 2021-07-07T16:04:51,773+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [admin = *:* finance = invoice:*, payment:* support = entitlement:*, invoice:item_adjust] for [org.killbill.security.okta.permissionsByGroup] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaPermissionsByGroup()] 2021-07-07T16:04:51,773+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [simple] for [org.killbill.security.ldap.authenticationMechanism] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPAuthenticationMechanism()] 2021-07-07T16:04:51,919+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.cache.config.redis.connectionMinimumIdleSize] on [org.killbill.billing.util.config.definition.RedisCacheConfig#getConnectionMinimumIdleSize()] 2021-07-07T16:04:51,919+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.cache.config.redis.password] on [org.killbill.billing.util.config.definition.RedisCacheConfig#getPassword()] 2021-07-07T16:04:51,920+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [redis://127.0.0.1:6379] for [org.killbill.cache.config.redis.url] on [org.killbill.billing.util.config.definition.RedisCacheConfig#getUrl()] 2021-07-07T16:04:51,921+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.cache.config.redis] on [org.killbill.billing.util.config.definition.RedisCacheConfig#isRedisCachingEnabled()] 2021-07-07T16:04:51,921+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.okta.url] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaUrl()] 2021-07-07T16:04:51,922+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [ldap://127.0.0.1:389] for [org.killbill.security.ldap.url] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPUrl()] 2021-07-07T16:04:51,922+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [classpath:shiro.ini] for [org.killbill.security.shiroResourcePath] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroResourcePath()] 2021-07-07T16:04:51,923+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [200000] for [org.killbill.security.shiroNbHashIterations] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroNbHashIterations()] 2021-07-07T16:04:51,923+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [admin = *:* finance = invoice:*, payment:* support = entitlement:*, invoice:item_adjust] for [org.killbill.security.ldap.permissionsByGroup] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPPermissionsByGroup()] 2021-07-07T16:04:51,924+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.security.ldap.disableSSLCheck] on [org.killbill.billing.util.config.definition.SecurityConfig#disableShiroLDAPSSLCheck()] 2021-07-07T16:04:51,924+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.userDnTemplate] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPUserDnTemplate()] 2021-07-07T16:04:51,925+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.systemPassword] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSystemPassword()] 2021-07-07T16:04:51,925+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [memberOf] for [org.killbill.security.ldap.groupNameId] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPGroupNameID()] 2021-07-07T16:04:51,926+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.okta.apiToken] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaAPIToken()] 2021-07-07T16:04:51,926+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [memberOf=uid={0}] for [org.killbill.security.ldap.groupSearchFilter] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPGroupSearchFilter()] 2021-07-07T16:04:51,927+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.systemUsername] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSystemUsername()] 2021-07-07T16:04:51,927+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.security.ldap.followReferrals] on [org.killbill.billing.util.config.definition.SecurityConfig#followShiroLDAPReferrals()] 2021-07-07T16:04:51,928+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.searchBase] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSearchBase()] 2021-07-07T16:04:51,928+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.dnSearchTemplate] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPDnSearchTemplate()] 2021-07-07T16:04:51,929+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [admin = *:* finance = invoice:*, payment:* support = entitlement:*, invoice:item_adjust] for [org.killbill.security.okta.permissionsByGroup] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaPermissionsByGroup()] 2021-07-07T16:04:51,929+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [simple] for [org.killbill.security.ldap.authenticationMechanism] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPAuthenticationMechanism()] 2021-07-07T16:04:51,931+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1h] for [org.killbill.rbac.globalSessionTimeout] on [org.killbill.billing.util.config.definition.RbacConfig#getGlobalSessionTimeout()] 2021-07-07T16:04:51,954+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50] for [org.killbill.payment.globalLock.retries] on [org.killbill.billing.util.config.definition.PaymentConfig#getMaxGlobalLockRetries()] 2021-07-07T16:04:51,955+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [] for [org.killbill.payment.invoice.plugin] on [org.killbill.billing.util.config.definition.PaymentConfig#getPaymentControlPluginNames()] 2021-07-07T16:04:51,956+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30s] for [org.killbill.payment.plugin.timeout] on [org.killbill.billing.util.config.definition.PaymentConfig#getPaymentPluginTimeout()] 2021-07-07T16:04:51,958+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m,1h,1d,1d,1d,1d,1d] for [org.killbill.payment.janitor.unknown.retries] on [org.killbill.billing.util.config.definition.PaymentConfig#getUnknownTransactionsRetries()] 2021-07-07T16:04:51,958+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1h, 1d] for [org.killbill.payment.janitor.pending.retries] on [org.killbill.billing.util.config.definition.PaymentConfig#getPendingTransactionsRetries()] 2021-07-07T16:04:51,959+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.payment.plugin.threads.nb] on [org.killbill.billing.util.config.definition.PaymentConfig#getPaymentPluginThreadNb()] 2021-07-07T16:04:51,959+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1h] for [org.killbill.payment.janitor.rate] on [org.killbill.billing.util.config.definition.PaymentConfig#getJanitorRunningRate()] 2021-07-07T16:04:51,959+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2] for [org.killbill.payment.failure.retry.multiplier] on [org.killbill.billing.util.config.definition.PaymentConfig#getPluginFailureRetryMultiplier()] 2021-07-07T16:04:51,960+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [8] for [org.killbill.payment.failure.retry.max.attempts] on [org.killbill.billing.util.config.definition.PaymentConfig#getPluginFailureRetryMaxAttempts()] 2021-07-07T16:04:51,960+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [__external_payment__] for [org.killbill.payment.provider.default] on [org.killbill.billing.util.config.definition.PaymentConfig#getDefaultPaymentProvider()] 2021-07-07T16:04:51,961+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [8,8,8] for [org.killbill.payment.retry.days] on [org.killbill.billing.util.config.definition.PaymentConfig#getPaymentFailureRetryDays()] 2021-07-07T16:04:51,961+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [12h] for [org.killbill.payment.janitor.attempts.delay] on [org.killbill.billing.util.config.definition.PaymentConfig#getIncompleteAttemptsTimeSpanDelay()] 2021-07-07T16:04:51,961+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [300] for [org.killbill.payment.failure.retry.start.sec] on [org.killbill.billing.util.config.definition.PaymentConfig#getPluginFailureInitialRetryInSec()] 2021-07-07T16:04:51,968+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.okta.url] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaUrl()] 2021-07-07T16:04:51,968+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [ldap://127.0.0.1:389] for [org.killbill.security.ldap.url] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPUrl()] 2021-07-07T16:04:51,969+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [classpath:shiro.ini] for [org.killbill.security.shiroResourcePath] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroResourcePath()] 2021-07-07T16:04:51,969+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [200000] for [org.killbill.security.shiroNbHashIterations] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroNbHashIterations()] 2021-07-07T16:04:51,970+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [admin = *:* finance = invoice:*, payment:* support = entitlement:*, invoice:item_adjust] for [org.killbill.security.ldap.permissionsByGroup] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPPermissionsByGroup()] 2021-07-07T16:04:51,970+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.security.ldap.disableSSLCheck] on [org.killbill.billing.util.config.definition.SecurityConfig#disableShiroLDAPSSLCheck()] 2021-07-07T16:04:51,971+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.userDnTemplate] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPUserDnTemplate()] 2021-07-07T16:04:51,971+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.systemPassword] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSystemPassword()] 2021-07-07T16:04:51,971+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [memberOf] for [org.killbill.security.ldap.groupNameId] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPGroupNameID()] 2021-07-07T16:04:51,972+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.okta.apiToken] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaAPIToken()] 2021-07-07T16:04:51,972+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [memberOf=uid={0}] for [org.killbill.security.ldap.groupSearchFilter] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPGroupSearchFilter()] 2021-07-07T16:04:51,973+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.systemUsername] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSystemUsername()] 2021-07-07T16:04:51,974+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.security.ldap.followReferrals] on [org.killbill.billing.util.config.definition.SecurityConfig#followShiroLDAPReferrals()] 2021-07-07T16:04:51,974+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.searchBase] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPSearchBase()] 2021-07-07T16:04:51,975+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.security.ldap.dnSearchTemplate] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPDnSearchTemplate()] 2021-07-07T16:04:51,975+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [admin = *:* finance = invoice:*, payment:* support = entitlement:*, invoice:item_adjust] for [org.killbill.security.okta.permissionsByGroup] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroOktaPermissionsByGroup()] 2021-07-07T16:04:51,976+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [simple] for [org.killbill.security.ldap.authenticationMechanism] on [org.killbill.billing.util.config.definition.SecurityConfig#getShiroLDAPAuthenticationMechanism()] 2021-07-07T16:04:51,978+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5s] for [org.killbill.tenant.broadcast.rate] on [org.killbill.billing.util.config.definition.TenantConfig#getTenantBroadcastServiceRunningRate()] 2021-07-07T16:04:51,980+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.jaxrs.location.full.url] on [org.killbill.billing.util.config.definition.JaxrsConfig#isJaxrsLocationFullUrl()] 2021-07-07T16:04:51,983+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.jaxrs.location.useForwardHeaders] on [org.killbill.billing.util.config.definition.JaxrsConfig#isJaxrsLocationUseForwardHeaders()] 2021-07-07T16:04:51,983+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.jaxrs.location.host] on [org.killbill.billing.util.config.definition.JaxrsConfig#getJaxrsLocationHost()] 2021-07-07T16:04:51,983+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.jaxrs.threads.pool.nb] on [org.killbill.billing.util.config.definition.JaxrsConfig#getJaxrsThreadNb()] 2021-07-07T16:04:51,984+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [30s] for [org.killbill.jaxrs.timeout] on [org.killbill.billing.util.config.definition.JaxrsConfig#getJaxrsTimeout()] 2021-07-07T16:04:51,986+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [15m,30m,2h,12h,1d] for [org.killbill.billing.server.notifications.retries] on [org.killbill.billing.util.config.definition.NotificationConfig#getPushNotificationsRetries()] 2021-07-07T16:04:52,831+0000 lvl='INFO', log='HikariDataSource', th='main', xff='', rId='', tok='', aRId='', tRId='', main - Starting... 2021-07-07T16:04:52,895+0000 lvl='INFO', log='HikariDataSource', th='main', xff='', rId='', tok='', aRId='', tRId='', main - Start completed. 2021-07-07T16:04:53,242+0000 lvl='INFO', log='PersistentBusProvider', th='main', xff='', rId='', tok='', aRId='', tRId='', Creating Persistent bus for bus_events 2021-07-07T16:04:53,293+0000 lvl='INFO', log='PersistentBusProvider', th='main', xff='', rId='', tok='', aRId='', tRId='', Creating Persistent bus for bus_ext_events 2021-07-07T16:04:53,322+0000 lvl='INFO', log='HikariDataSource', th='main', xff='', rId='', tok='', aRId='', tRId='', shiro - Starting... 2021-07-07T16:04:53,325+0000 lvl='INFO', log='HikariDataSource', th='main', xff='', rId='', tok='', aRId='', tRId='', shiro - Start completed. 2021-07-07T16:04:54,007+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-immutable will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,008+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache account-immutable will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,197+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-immutable' 2021-07-07T16:04:54,197+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-immutable' created in EhcacheManager. 2021-07-07T16:04:54,220+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-immutable 2021-07-07T16:04:54,221+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-immutable 2021-07-07T16:04:54,226+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-bcd will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,226+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache account-bcd will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,229+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-bcd' 2021-07-07T16:04:54,229+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-bcd' created in EhcacheManager. 2021-07-07T16:04:54,230+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-bcd 2021-07-07T16:04:54,230+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-bcd 2021-07-07T16:04:54,231+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache record-id will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,232+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache record-id will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,234+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'record-id' 2021-07-07T16:04:54,234+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'record-id' created in EhcacheManager. 2021-07-07T16:04:54,235+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=record-id 2021-07-07T16:04:54,235+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=record-id 2021-07-07T16:04:54,237+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-record-id will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,238+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache account-record-id will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,241+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-record-id' 2021-07-07T16:04:54,241+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-record-id' created in EhcacheManager. 2021-07-07T16:04:54,241+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-record-id 2021-07-07T16:04:54,242+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-record-id 2021-07-07T16:04:54,243+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-record-id will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,243+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-record-id will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,246+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-record-id' 2021-07-07T16:04:54,246+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-record-id' created in EhcacheManager. 2021-07-07T16:04:54,246+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-record-id 2021-07-07T16:04:54,247+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-record-id 2021-07-07T16:04:54,248+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache object-id will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,248+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache object-id will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,250+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'object-id' 2021-07-07T16:04:54,250+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'object-id' created in EhcacheManager. 2021-07-07T16:04:54,251+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=object-id 2021-07-07T16:04:54,251+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=object-id 2021-07-07T16:04:54,253+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-catalog will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,254+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-catalog will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,256+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-catalog' 2021-07-07T16:04:54,257+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-catalog' created in EhcacheManager. 2021-07-07T16:04:54,257+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-catalog 2021-07-07T16:04:54,257+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-catalog 2021-07-07T16:04:54,259+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-config will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,259+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-config will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,261+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-config' 2021-07-07T16:04:54,261+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-config' created in EhcacheManager. 2021-07-07T16:04:54,262+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-config 2021-07-07T16:04:54,262+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-config 2021-07-07T16:04:54,264+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-overdue-config will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,264+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-overdue-config will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,266+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-overdue-config' 2021-07-07T16:04:54,266+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-overdue-config' created in EhcacheManager. 2021-07-07T16:04:54,267+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-overdue-config 2021-07-07T16:04:54,267+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-overdue-config 2021-07-07T16:04:54,269+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-kv will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,270+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-kv will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,272+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-kv' 2021-07-07T16:04:54,272+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-kv' created in EhcacheManager. 2021-07-07T16:04:54,273+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-kv 2021-07-07T16:04:54,273+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-kv 2021-07-07T16:04:54,275+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,275+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,277+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant' 2021-07-07T16:04:54,277+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant' created in EhcacheManager. 2021-07-07T16:04:54,278+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant 2021-07-07T16:04:54,278+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant 2021-07-07T16:04:54,279+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache overridden-plan will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,280+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache overridden-plan will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,282+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'overridden-plan' 2021-07-07T16:04:54,282+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'overridden-plan' created in EhcacheManager. 2021-07-07T16:04:54,282+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=overridden-plan 2021-07-07T16:04:54,283+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=overridden-plan 2021-07-07T16:04:54,284+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-payment-state-machine-config will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,285+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-payment-state-machine-config will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,287+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-payment-state-machine-config' 2021-07-07T16:04:54,288+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-payment-state-machine-config' created in EhcacheManager. 2021-07-07T16:04:54,288+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-payment-state-machine-config 2021-07-07T16:04:54,289+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=tenant-payment-state-machine-config 2021-07-07T16:04:54,290+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-id-from-bundle-id will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,291+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache account-id-from-bundle-id will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,293+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-id-from-bundle-id' 2021-07-07T16:04:54,293+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-id-from-bundle-id' created in EhcacheManager. 2021-07-07T16:04:54,293+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-id-from-bundle-id 2021-07-07T16:04:54,294+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=account-id-from-bundle-id 2021-07-07T16:04:54,295+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache bundle-id-from-subscription-id will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,296+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache bundle-id-from-subscription-id will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,297+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'bundle-id-from-subscription-id' 2021-07-07T16:04:54,298+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'bundle-id-from-subscription-id' created in EhcacheManager. 2021-07-07T16:04:54,298+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=bundle-id-from-subscription-id 2021-07-07T16:04:54,299+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=bundle-id-from-subscription-id 2021-07-07T16:04:54,302+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache with name org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 does not yet exist. Creating now. 2021-07-07T16:04:54,303+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:54,304+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:54,306+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1' 2021-07-07T16:04:54,306+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1' created in EhcacheManager. 2021-07-07T16:04:54,306+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 2021-07-07T16:04:54,307+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 2021-07-07T16:04:54,308+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='main', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1] 2021-07-07T16:04:54,352+0000 lvl='INFO', log='BusOptimizerNoop', th='main', xff='', rId='', tok='', aRId='', tRId='', Feature BusOptimizer is OFF 2021-07-07T16:04:54,372+0000 lvl='INFO', log='XMLLoader', th='main', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.catalog.StandaloneCatalog from xml file at: EmptyCatalog.xml 2021-07-07T16:04:54,505+0000 lvl='INFO', log='InvoiceOptimizerNoop', th='main', xff='', rId='', tok='', aRId='', tRId='', Feature InvoiceOptimizer is OFF 2021-07-07T16:04:54,511+0000 lvl='INFO', log='XMLLoader', th='main', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.overdue.config.DefaultOverdueConfig from xml file at: NoOverdueConfig.xml 2021-07-07T16:04:54,534+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering service='__EXTERNAL_PAYMENT__' 2021-07-07T16:04:54,535+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering service='__DEFAULT_PAYMENT_CONTROL__' 2021-07-07T16:04:54,535+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering service='__INVOICE_PAYMENT_CONTROL_PLUGIN__' 2021-07-07T16:04:54,538+0000 lvl='INFO', log='XMLLoader', th='main', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.automaton.DefaultStateMachineConfig from xml file at: jar:file:/E:/Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-payment-0.22.23.jar!/org/killbill/billing/payment/retry/RetryStates.xml 2021-07-07T16:04:54,568+0000 lvl='INFO', log='HikariDataSource', th='main', xff='', rId='', tok='', aRId='', tRId='', osgi - Starting... 2021-07-07T16:04:54,569+0000 lvl='INFO', log='HikariDataSource', th='main', xff='', rId='', tok='', aRId='', tRId='', osgi - Start completed. 2021-07-07T16:04:55,143+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache with name org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 does not yet exist. Creating now. 2021-07-07T16:04:55,144+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:55,145+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:55,147+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2' 2021-07-07T16:04:55,147+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2' created in EhcacheManager. 2021-07-07T16:04:55,148+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 2021-07-07T16:04:55,149+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 2021-07-07T16:04:55,150+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='main', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2] 2021-07-07T16:04:55,151+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache with name org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 does not yet exist. Creating now. 2021-07-07T16:04:55,151+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 will be supplemented by template defaultCacheConfiguration 2021-07-07T16:04:55,152+0000 lvl='INFO', log='ConfigurationMerger', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 will use expiry configuration from template defaultCacheConfiguration 2021-07-07T16:04:55,153+0000 lvl='INFO', log='EhcacheLoggingListener', th='main', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2' 2021-07-07T16:04:55,154+0000 lvl='INFO', log='EhcacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Cache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2' created in EhcacheManager. 2021-07-07T16:04:55,154+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 2021-07-07T16:04:55,155+0000 lvl='INFO', log='Eh107CacheManager', th='main', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./E./Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.23.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 2021-07-07T16:04:55,156+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='main', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2] 2021-07-07T16:04:55,214+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage BOOT 2021-07-07T16:04:55,216+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler init for service nodes-service 2021-07-07T16:04:55,374+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage LOAD_CATALOG 2021-07-07T16:04:55,374+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler loadCatalog for service catalog-service 2021-07-07T16:04:55,376+0000 lvl='INFO', log='XMLLoader', th='main', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.catalog.StandaloneCatalog from xml file at: SpyCarAdvanced.xml 2021-07-07T16:04:55,431+0000 lvl='INFO', log='DefaultCatalogService', th='main', xff='', rId='', tok='', aRId='', tRId='', Successfully loaded the default catalog SpyCarAdvanced.xml 2021-07-07T16:04:55,431+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler loadConfig for service overdue-service 2021-07-07T16:04:55,432+0000 lvl='INFO', log='XMLLoader', th='main', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.overdue.config.DefaultOverdueConfig from xml file at: NoOverdueConfig.xml 2021-07-07T16:04:55,442+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage INIT_BUS 2021-07-07T16:04:55,442+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initBus for service bus-service 2021-07-07T16:04:55,449+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='main', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_events Inserting 0 entries into inflightQ during initialization 2021-07-07T16:04:55,450+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='main', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_events Initialized with queueId=1, mode=STICKY_EVENTS 2021-07-07T16:04:55,450+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initBus for service external-bus-service 2021-07-07T16:04:55,451+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='main', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_ext_events Inserting 0 entries into inflightQ during initialization 2021-07-07T16:04:55,452+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='main', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_ext_events Initialized with queueId=2, mode=STICKY_EVENTS 2021-07-07T16:04:55,452+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage INIT_PLUGIN 2021-07-07T16:04:55,452+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service osgi-service 2021-07-07T16:04:55,453+0000 lvl='INFO', log='DefaultOSGIService', th='main', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively E:\var\tmp\felix\osgi-cache\bundle0 2021-07-07T16:04:55,454+0000 lvl='INFO', log='DefaultOSGIService', th='main', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively E:\var\tmp\felix\osgi-cache 2021-07-07T16:04:55,682+0000 lvl='WARN', log='JRubyJarHelper', th='main', xff='', rId='', tok='', aRId='', tRId='', Unable to find the JRuby bundle at /var/tmp/bundles/platform/jruby.jar, ruby plugins won't be started! 2021-07-07T16:04:55,683+0000 lvl='WARN', log='Logger', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration root dir /var/tmp/bundles/platform/ is not a valid directory 2021-07-07T16:04:55,684+0000 lvl='WARN', log='PluginFinder', th='main', xff='', rId='', tok='', aRId='', tRId='', File non existent: Skipping parsing of plugin_identifiers.json 2021-07-07T16:04:55,684+0000 lvl='WARN', log='PluginFinder', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration root dir /var/tmp/bundles/plugins/ruby is not a valid directory 2021-07-07T16:04:55,685+0000 lvl='WARN', log='PluginFinder', th='main', xff='', rId='', tok='', aRId='', tRId='', Configuration root dir /var/tmp/bundles/plugins/java is not a valid directory 2021-07-07T16:04:55,685+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage INIT_SERVICE 2021-07-07T16:04:55,685+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service security-service 2021-07-07T16:04:55,688+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service config-service 2021-07-07T16:04:55,688+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service broadcast-service 2021-07-07T16:04:55,698+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service extBusEvent-listener-service 2021-07-07T16:04:55,701+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service tenant-service 2021-07-07T16:04:55,740+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service catalog-service 2021-07-07T16:04:55,740+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service subscription-service 2021-07-07T16:04:55,741+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service entitlement-service 2021-07-07T16:04:55,742+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service invoice-service 2021-07-07T16:04:55,743+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service invoice-listener-service 2021-07-07T16:04:55,743+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service payment-service 2021-07-07T16:04:55,744+0000 lvl='INFO', log='XMLLoader', th='main', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.automaton.DefaultStateMachineConfig from xml file at: jar:file:/E:/Tools/apache-tomcat-9.0.48-windows-x64/apache-tomcat-9.0.48/webapps/ROOT/WEB-INF/lib/killbill-payment-0.22.23.jar!/org/killbill/billing/payment/PaymentStates.xml 2021-07-07T16:04:55,763+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service overdue-service 2021-07-07T16:04:55,764+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service jaxrs-service 2021-07-07T16:04:55,764+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler registerForNotifications for service beatrix-service 2021-07-07T16:04:55,765+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler registerForNotifications for service server-service 2021-07-07T16:04:55,765+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage START_PLUGIN 2021-07-07T16:04:55,765+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service osgi-service 2021-07-07T16:04:55,772+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage START_SERVICE 2021-07-07T16:04:55,772+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service nodes-service 2021-07-07T16:04:55,793+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service broadcast-service 2021-07-07T16:04:55,795+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service extBusEvent-listener-service 2021-07-07T16:04:55,795+0000 lvl='INFO', log='DBBackedQueue', th='main', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-notifications Initialized mode=STICKY_POLLING 2021-07-07T16:04:55,796+0000 lvl='INFO', log='DefaultReaper', th='main', xff='', rId='', tok='', aRId='', tRId='', NotificationReaper: Starting... reapThresholdMillis=600000, schedulePeriodMillis=180000 2021-07-07T16:04:55,798+0000 lvl='INFO', log='DefaultQueueLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', notifications: Starting... 2021-07-07T16:04:55,800+0000 lvl='INFO', log='DefaultQueueLifecycle', th='notifications-lifecycle-th-1', xff='', rId='', tok='', aRId='', tRId='', notifications: Thread notifications-lifecycle-th-1-dispatcher [48] starting 2021-07-07T16:04:55,801+0000 lvl='INFO', log='DefaultQueueLifecycle', th='notifications-lifecycle-th-2', xff='', rId='', tok='', aRId='', tRId='', notifications: Thread notifications-lifecycle-th-2-completion [49] starting 2021-07-07T16:04:55,801+0000 lvl='INFO', log='DefaultQueueLifecycle', th='notifications-lifecycle-th-3', xff='', rId='', tok='', aRId='', tRId='', notifications: Thread notifications-lifecycle-th-3-completion [50] starting 2021-07-07T16:04:55,801+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service tenant-service 2021-07-07T16:04:55,803+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service subscription-service 2021-07-07T16:04:55,804+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service entitlement-service 2021-07-07T16:04:55,805+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service invoice-service 2021-07-07T16:04:55,805+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service invoice-listener-service 2021-07-07T16:04:55,806+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service payment-service 2021-07-07T16:04:55,807+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service overdue-service 2021-07-07T16:04:55,807+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service server-service 2021-07-07T16:04:55,807+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage START_BUS 2021-07-07T16:04:55,808+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler startBus for service bus-service 2021-07-07T16:04:55,808+0000 lvl='INFO', log='DefaultReaper', th='main', xff='', rId='', tok='', aRId='', tRId='', BusReaper: Starting... reapThresholdMillis=600000, schedulePeriodMillis=180000 2021-07-07T16:04:55,808+0000 lvl='INFO', log='DefaultQueueLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', bus_events: Starting... 2021-07-07T16:04:55,809+0000 lvl='INFO', log='DefaultLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler startBus for service external-bus-service 2021-07-07T16:04:55,809+0000 lvl='INFO', log='DefaultReaper', th='main', xff='', rId='', tok='', aRId='', tRId='', BusReaper: Starting... reapThresholdMillis=600000, schedulePeriodMillis=180000 2021-07-07T16:04:55,809+0000 lvl='INFO', log='DefaultQueueLifecycle', th='bus_events-lifecycle-th-1', xff='', rId='', tok='', aRId='', tRId='', bus_events: Thread bus_events-lifecycle-th-1-dispatcher [54] starting 2021-07-07T16:04:55,810+0000 lvl='INFO', log='DefaultQueueLifecycle', th='main', xff='', rId='', tok='', aRId='', tRId='', bus_ext_events: Starting... 2021-07-07T16:04:55,810+0000 lvl='INFO', log='DefaultQueueLifecycle', th='bus_events-lifecycle-th-2', xff='', rId='', tok='', aRId='', tRId='', bus_events: Thread bus_events-lifecycle-th-2-completion [55] starting 2021-07-07T16:04:55,810+0000 lvl='INFO', log='DefaultQueueLifecycle', th='bus_events-lifecycle-th-3', xff='', rId='', tok='', aRId='', tRId='', bus_events: Thread bus_events-lifecycle-th-3-completion [56] starting 2021-07-07T16:04:55,812+0000 lvl='INFO', log='DefaultQueueLifecycle', th='bus_ext_events-lifecycle-th-1', xff='', rId='', tok='', aRId='', tRId='', bus_ext_events: Thread bus_ext_events-lifecycle-th-1-dispatcher [58] starting 2021-07-07T16:04:55,812+0000 lvl='INFO', log='DefaultQueueLifecycle', th='bus_ext_events-lifecycle-th-2', xff='', rId='', tok='', aRId='', tRId='', bus_ext_events: Thread bus_ext_events-lifecycle-th-2-completion [59] starting 2021-07-07T16:04:55,812+0000 lvl='INFO', log='DefaultQueueLifecycle', th='bus_ext_events-lifecycle-th-3', xff='', rId='', tok='', aRId='', tRId='', bus_ext_events: Thread bus_ext_events-lifecycle-th-3-completion [60] starting 2021-07-07T16:04:55,969+0000 lvl='INFO', log='Reflections', th='main', xff='', rId='', tok='', aRId='', tRId='', Reflections took 115 ms to scan 1 urls, producing 306 keys and 665 values 2021-07-07T16:04:58,919+0000 lvl='INFO', log='KillbillGuiceFilter', th='main', xff='', rId='', tok='', aRId='', tRId='', Kill Bill server has started 2021-07-07T16:04:58,920+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning value [https://raw.github.com/killbill/killbill/master/profiles/killbill/src/main/resources/update-checker/killbill-server-update-list.properties] for [org.killbill.server.updateCheck.url] on [org.killbill.billing.server.config.UpdateCheckConfig#updateCheckURL()] 2021-07-07T16:04:58,920+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.server.updateCheck.skip] on [org.killbill.billing.server.config.UpdateCheckConfig#shouldSkipUpdateCheck()] 2021-07-07T16:04:58,921+0000 lvl='INFO', log='ConfigurationObjectFactory', th='main', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3000] for [org.killbill.server.updateCheck.connectTimeout] on [org.killbill.billing.server.config.UpdateCheckConfig#updateCheckConnectionTimeout()] 2021-07-07T16:04:58,923+0000 lvl='INFO', log='UpdateChecker', th='main', xff='', rId='', tok='', aRId='', tRId='', For Kill Bill Commercial Support, visit http://thebillingproject.com or send an email to support@thebillingproject.com 2021-07-07T16:04:58,924+0000 lvl='WARN', log='KillbillHealthcheck', th='main', xff='', rId='', tok='', aRId='', tRId='', Putting host in rotation 2021-07-07T16:07:08,577+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-08T00:07:08.568000 #7388] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-07-08 00:07:08 +0800 2021-07-07T16:07:08,716+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-08T00:07:08.716000 #7388] INFO -- : Processing by Kaui::SessionsController#new as HTML 2021-07-07T16:07:08,843+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='b4409933-1666-4454-9d1d-f000208d204d', aId='', tId='', I, [2021-07-08T00:07:08.842000 #7388] INFO -- : Completed 500 Internal Server Error in 122ms 2021-07-07T16:07:08,850+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='b4409933-1666-4454-9d1d-f000208d204d', aId='', tId='', F, [2021-07-08T00:07:08.850000 #7388] FATAL -- : 2021-07-07T16:07:08,851+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='b4409933-1666-4454-9d1d-f000208d204d', aId='', tId='', F, [2021-07-08T00:07:08.851000 #7388] FATAL -- : ActiveRecord::JDBCError (Public Key Retrieval is not allowed): 2021-07-07T16:07:08,851+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='b4409933-1666-4454-9d1d-f000208d204d', aId='', tId='', F, [2021-07-08T00:07:08.851000 #7388] FATAL -- : 2021-07-07T16:07:08,852+0000 lvl='INFO', log='sessions#new', th='http-nio-8080-exec-1', xff='', rId='b4409933-1666-4454-9d1d-f000208d204d', aId='', tId='', F, [2021-07-08T00:07:08.851000 #7388] FATAL -- : arjdbc/jdbc/RubyJdbcConnection.java:557:in `initialize' gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/abstract/core.rb:19:in `initialize' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract_mysql_adapter.rb:69:in `initialize' gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/abstract/statement_cache.rb:21:in `initialize' gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/mysql/adapter.rb:39:in `initialize' gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/jdbc/callbacks.rb:12:in `new' gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/jdbc/connection_methods.rb:8:in `jdbc_connection' gems/gems/activerecord-jdbc-adapter-51.8-java/lib/arjdbc/mysql/connection_methods.rb:99:in `mysql_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:759:in `new_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:803:in `checkout_new_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:782:in `try_to_checkout_new_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:743:in `acquire_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:500:in `checkout' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:374:in `connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_adapters/abstract/connection_pool.rb:931:in `retrieve_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:116:in `retrieve_connection' gems/gems/activerecord-5.1.0/lib/active_record/connection_handling.rb:88:in `connection' gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:451:in `load_schema!' gems/gems/activerecord-5.1.0/lib/active_record/attributes.rb:233:in `load_schema!' gems/gems/activerecord-5.1.0/lib/active_record/attribute_decorators.rb:50:in `load_schema!' gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:446:in `load_schema' gems/gems/activerecord-5.1.0/lib/active_record/model_schema.rb:349:in `attribute_types' gems/gems/activerecord-5.1.0/lib/active_record/attribute_methods.rb:179:in `has_attribute?' gems/gems/activerecord-5.1.0/lib/active_record/inheritance.rb:55:in `new' gems/gems/devise-4.8.0/app/controllers/devise/sessions_controller.rb:11:in `new' gems/gems/actionpack-5.1.0/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action' gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:186:in `process_action' gems/gems/actionpack-5.1.0/lib/action_controller/metal/rendering.rb:30:in `process_action' gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:20:in `block in process_action' gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:131:in `run_callbacks' gems/gems/actionpack-5.1.0/lib/abstract_controller/callbacks.rb:19:in `process_action' gems/gems/actionpack-5.1.0/lib/action_controller/metal/rescue.rb:20:in `process_action' gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action' gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `block in instrument' gems/gems/activesupport-5.1.0/lib/active_support/notifications/instrumenter.rb:21:in `instrument' gems/gems/activesupport-5.1.0/lib/active_support/notifications.rb:166:in `instrument' gems/gems/actionpack-5.1.0/lib/action_controller/metal/instrumentation.rb:30:in `process_action' gems/gems/actionpack-5.1.0/lib/action_controller/metal/params_wrapper.rb:252:in `process_action' gems/gems/activerecord-5.1.0/lib/active_record/railties/controller_runtime.rb:22:in `process_action' gems/gems/actionpack-5.1.0/lib/abstract_controller/base.rb:124:in `process' gems/gems/actionview-5.1.0/lib/action_view/rendering.rb:30:in `process' gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:189:in `dispatch' gems/gems/actionpack-5.1.0/lib/action_controller/metal.rb:253:in `dispatch' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:49:in `dispatch' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:31:in `serve' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:16:in `block in Constraints' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve' gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve' org/jruby/RubyArray.java:1735:in `each' gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call' gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call' org/jruby/RubyKernel.java:1830:in `public_send' gems/gems/railties-5.1.0/lib/rails/railtie.rb:185:in `method_missing' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:17:in `block in Constraints' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/mapper.rb:46:in `serve' gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:46:in `block in serve' org/jruby/RubyArray.java:1735:in `each' gems/gems/actionpack-5.1.0/lib/action_dispatch/journey/router.rb:33:in `serve' gems/gems/actionpack-5.1.0/lib/action_dispatch/routing/route_set.rb:832:in `call' gems/gems/warden-1.2.9/lib/warden/manager.rb:36:in `block in call' org/jruby/RubyKernel.java:1138:in `catch' gems/gems/warden-1.2.9/lib/warden/manager.rb:34:in `call' gems/gems/rack-2.2.3/lib/rack/etag.rb:27:in `call' gems/gems/rack-2.2.3/lib/rack/conditional_get.rb:27:in `call' gems/gems/rack-2.2.3/lib/rack/head.rb:12:in `call' gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:266:in `context' gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:260:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/cookies.rb:613:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call' gems/gems/activesupport-5.1.0/lib/active_support/callbacks.rb:97:in `run_callbacks' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/callbacks.rb:24:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call' gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:36:in `call_app' gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `block in call' gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `block in tagged' gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:26:in `tagged' gems/gems/activesupport-5.1.0/lib/active_support/tagged_logging.rb:69:in `tagged' gems/gems/railties-5.1.0/lib/rails/rack/logger.rb:24:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/remote_ip.rb:79:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/request_id.rb:25:in `call' gems/gems/rack-2.2.3/lib/rack/method_override.rb:24:in `call' gems/gems/rack-2.2.3/lib/rack/runtime.rb:22:in `call' gems/gems/activesupport-5.1.0/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/executor.rb:12:in `call' gems/gems/actionpack-5.1.0/lib/action_dispatch/middleware/static.rb:125:in `call' gems/gems/rack-2.2.3/lib/rack/sendfile.rb:110:in `call' gems/gems/railties-5.1.0/lib/rails/engine.rb:522:in `call' uri:classloader:/rack/handler/servlet.rb:22:in `call'