16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/8.5.65 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Mar 30 2021 12:28:40 UTC 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 8.5.65.0 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Windows Server 2016 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 10.0 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: C:\software\jdk1.8.0_291\jre 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 1.8.0_291-b10 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: C:\Tomcat\apache-tomcat-8.5.65 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: C:\Tomcat\apache-tomcat-8.5.65 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=C:\Tomcat\apache-tomcat-8.5.65\conf\logging.properties 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs= 16-Jul-2021 15:25:49.961 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=C:\Tomcat\apache-tomcat-8.5.65 16-Jul-2021 15:25:49.977 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=C:\Tomcat\apache-tomcat-8.5.65 16-Jul-2021 15:25:49.977 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=C:\Tomcat\apache-tomcat-8.5.65\temp 16-Jul-2021 15:25:49.977 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.2.27] using APR version [1.7.0]. 16-Jul-2021 15:25:49.977 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true]. 16-Jul-2021 15:25:49.977 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 16-Jul-2021 15:25:49.992 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.1.1k 25 Mar 2021] 16-Jul-2021 15:25:50.242 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 16-Jul-2021 15:25:50.305 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read 16-Jul-2021 15:25:50.321 INFO [main] org.apache.catalina.startup.Catalina.load Initialization processed in 895 ms 16-Jul-2021 15:25:50.430 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 16-Jul-2021 15:25:50.430 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/8.5.65] 16-Jul-2021 15:25:50.461 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [C:\Tomcat\apache-tomcat-8.5.65\webapps\kaui.war] 16-Jul-2021 15:25:58.426 INFO [localhost-startStop-1] 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. 15:25:58,535 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 15:25:58,535 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy] 15:25:58,535 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/classes/logback.xml] 15:25:58,676 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 15:25:58,692 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:25:58,707 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 15:25:58,723 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 15:25:58,832 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender] 15:25:58,848 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [MAIN] 15:25:58,879 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@820788558 - Will use gz compression 15:25:58,879 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@820788558 - Will use the pattern ./logs/kaui-%d{yyyy-MM-dd}.%i.out for the active file 15:25:58,895 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@17bfc3d0 - The date pattern is 'yyyy-MM-dd' from file name pattern './logs/kaui-%d{yyyy-MM-dd}.%i.out.gz'. 15:25:58,895 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@17bfc3d0 - Roll-over at midnight. 15:25:58,895 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@17bfc3d0 - Setting initial period to Fri Jul 16 15:19:15 CDT 2021 15:25:58,895 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@17bfc3d0 - SizeAndTimeBasedFNATP is deprecated. Use SizeAndTimeBasedRollingPolicy instead 15:25:58,895 |-WARN in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@17bfc3d0 - For more information see http://logback.qos.ch/manual/appenders.html#SizeAndTimeBasedRollingPolicy 15:25:58,895 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@820788558 - Cleaning on start up 15:25:58,910 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 15:25:58,910 |-INFO in c.q.l.core.rolling.helper.TimeBasedArchiveRemover - first clean up after appender initialization 15:25:58,910 |-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. 15:25:58,910 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[MAIN] - Active log file name: ./logs/kaui.out 15:25:58,910 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[MAIN] - File property is set to [./logs/kaui.out] 15:25:58,910 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO 15:25:58,910 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [MAIN] to Logger[ROOT] 15:25:58,910 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT] 15:25:58,910 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 15:25:58,910 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6acd8b08 - Registering current configuration as safe fallback point 2021-07-16T20:25:59,442+0000 lvl='INFO', log='', th='localhost-startStop-1', 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-16T20:25:59,457+0000 lvl='INFO', log='', th='localhost-startStop-1', xff='', rId='', aId='', tId='', using a shared (threadsafe!) runtime C:/Tomcat/apache-tomcat-8.5.65/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' 16-Jul-2021 15:26:43.092 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [C:\Tomcat\apache-tomcat-8.5.65\webapps\kaui.war] has finished in [52,631] ms 16-Jul-2021 15:26:43.092 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [C:\Tomcat\apache-tomcat-8.5.65\webapps\ROOT.war] 16-Jul-2021 15:26:54.444 INFO [localhost-startStop-1] 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-16T20:26:55,069+0000 lvl='INFO', log='DefaultKillbillConfigSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Overwrite of user.timezone system property with America/Chicago may break database serialization of date. Kill Bill will overwrite to GMT 2021-07-16T20:26:55,069+0000 lvl='WARN', log='DefaultKillbillConfigSource', th='localhost-startStop-1', 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-16T20:26:55,147+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [local] for [org.killbill.server.region] on [org.killbill.billing.server.config.KillbillServerConfig#getRegion()] 2021-07-16T20:26:55,147+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,147+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,147+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0s] for [org.killbill.server.shutdownDelay] on [org.killbill.billing.server.config.KillbillServerConfig#getShutdownDelay()] 2021-07-16T20:26:55,163+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.server.multitenant] on [org.killbill.billing.server.config.KillbillServerConfig#isMultiTenancyEnabled()] 2021-07-16T20:26:55,163+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,163+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,163+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,163+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,366+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,366+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,381+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,381+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,381+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.metrics.graphite] on [org.killbill.billing.server.config.MetricsGraphiteConfig#isGraphiteReportingEnabled()] 2021-07-16T20:26:55,381+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,381+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,381+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,397+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,397+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,397+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:55,397+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.metrics.influxDb] on [org.killbill.billing.server.config.MetricsInfluxDbConfig#isInfluxDbReportingEnabled()] 2021-07-16T20:26:55,413+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,299+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.dao.readOnly] on [org.killbill.commons.jdbi.guice.DaoConfig#isReadOnly()] 2021-07-16T20:26:57,299+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [killbill] for [org.killbill.dao.user] on [org.killbill.commons.jdbi.guice.DaoConfig#getUsername()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [100] for [org.killbill.dao.maxActive] on [org.killbill.commons.jdbi.guice.DaoConfig#getMaxActive()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.connectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionTimeout()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [********] for [org.killbill.dao.password] on [org.killbill.commons.jdbi.guice.DaoConfig#getPassword()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.dao.cachePrepStmts] on [org.killbill.commons.jdbi.guice.DaoConfig#isPreparedStatementsCacheEnabled()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.dataSourceClassName] on [org.killbill.commons.jdbi.guice.DaoConfig#getDataSourceClassName()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.driverClassName] on [org.killbill.commons.jdbi.guice.DaoConfig#getDriverClassName()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [5m] for [org.killbill.dao.idleConnectionTestPeriod] on [org.killbill.commons.jdbi.guice.DaoConfig#getIdleConnectionTestPeriod()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [HIKARICP] for [org.killbill.dao.poolingType] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionPoolingType()] 2021-07-16T20:26:57,315+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.healthCheckConnectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckConnectionTimeout()] 2021-07-16T20:26:57,330+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.dao.initializationFailFast] on [org.killbill.commons.jdbi.guice.DaoConfig#isInitializationFailFast()] 2021-07-16T20:26:57,330+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,330+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [0m] for [org.killbill.dao.maxConnectionAge] on [org.killbill.commons.jdbi.guice.DaoConfig#getMaxConnectionAge()] 2021-07-16T20:26:57,330+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [500] for [org.killbill.dao.prepStmtCacheSize] on [org.killbill.commons.jdbi.guice.DaoConfig#getPreparedStatementsCacheSize()] 2021-07-16T20:26:57,330+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.connectionInitSql] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionInitSql()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60s] for [org.killbill.dao.leakDetectionThreshold] on [org.killbill.commons.jdbi.guice.DaoConfig#getLeakDetectionThreshold()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [60m] for [org.killbill.dao.idleMaxAge] on [org.killbill.commons.jdbi.guice.DaoConfig#getIdleMaxAge()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [jdbc:mysql://127.0.0.1:3306/killbill] for [org.killbill.dao.url] on [org.killbill.commons.jdbi.guice.DaoConfig#getJdbcUrl()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [1] for [org.killbill.dao.minIdle] on [org.killbill.commons.jdbi.guice.DaoConfig#getMinIdle()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [DEBUG] for [org.killbill.dao.logLevel] on [org.killbill.commons.jdbi.guice.DaoConfig#getLogLevel()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [true] for [org.killbill.dao.useServerPrepStmts] on [org.killbill.commons.jdbi.guice.DaoConfig#isServerSidePreparedStatementsEnabled()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [2048] for [org.killbill.dao.prepStmtCacheSqlLimit] on [org.killbill.commons.jdbi.guice.DaoConfig#getPreparedStatementsCacheSqlLimit()] 2021-07-16T20:26:57,346+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50ms] for [org.killbill.dao.healthCheckExpected99thPercentile] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckExpected99thPercentile()] 2021-07-16T20:26:57,362+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,362+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,362+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,362+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,362+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,377+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,393+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,393+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,393+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,393+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,393+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,393+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:26:57,408+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.healthCheckConnectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckConnectionTimeout()] 2021-07-16T20:26:57,424+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.connectionInitSql] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionInitSql()] 2021-07-16T20:26:57,424+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50ms] for [org.killbill.dao.healthCheckExpected99thPercentile] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckExpected99thPercentile()] 2021-07-16T20:27:02,627+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,627+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,643+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,658+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,658+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,658+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,658+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,658+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,675+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,683+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,748+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,748+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,748+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10m] for [org.killbill.notificationq.${instanceName}.reapThreshold] on [org.killbill.notificationq.api.NotificationQueueConfig#getReapThreshold()] 2021-07-16T20:27:02,748+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,748+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [false] for [org.killbill.notificationq.${instanceName}.inMemory] on [org.killbill.notificationq.api.NotificationQueueConfig#isInMemory()] 2021-07-16T20:27:02,748+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3m] for [org.killbill.notificationq.${instanceName}.reapSchedule] on [org.killbill.notificationq.api.NotificationQueueConfig#getReapSchedule()] 2021-07-16T20:27:02,764+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [notifications] for [org.killbill.notificationq.${instanceName}.tableName] on [org.killbill.notificationq.api.NotificationQueueConfig#getTableName()] 2021-07-16T20:27:02,764+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,764+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,764+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,764+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,764+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,779+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.notificationq.${instanceName}.claimed] on [org.killbill.notificationq.api.NotificationQueueConfig#getMaxEntriesClaimed()] 2021-07-16T20:27:02,779+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,779+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,779+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10] for [org.killbill.notificationq.${instanceName}.maxReDispatchCount] on [org.killbill.notificationq.api.NotificationQueueConfig#getMaxReDispatchCount()] 2021-07-16T20:27:02,779+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [3000] for [org.killbill.notificationq.${instanceName}.sleep] on [org.killbill.notificationq.api.NotificationQueueConfig#getPollingSleepTimeMs()] 2021-07-16T20:27:02,779+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [killbill] for [org.killbill.billing.osgi.dao.user] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getUsername()] 2021-07-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [********] for [org.killbill.billing.osgi.dao.password] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getPassword()] 2021-07-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,795+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,811+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,811+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,811+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,811+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,811+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [jdbc:mysql://127.0.0.1:3306/killbill] for [org.killbill.billing.osgi.dao.url] on [org.killbill.billing.osgi.glue.OSGIDataSourceConfig#getJdbcUrl()] 2021-07-16T20:27:02,826+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,842+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,842+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,842+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,842+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [10s] for [org.killbill.dao.healthCheckConnectionTimeout] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckConnectionTimeout()] 2021-07-16T20:27:02,842+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning null default value for [org.killbill.dao.connectionInitSql] on [org.killbill.commons.jdbi.guice.DaoConfig#getConnectionInitSql()] 2021-07-16T20:27:02,842+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [50ms] for [org.killbill.dao.healthCheckExpected99thPercentile] on [org.killbill.commons.jdbi.guice.DaoConfig#getHealthCheckExpected99thPercentile()] 2021-07-16T20:27:02,858+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,858+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,858+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,873+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,873+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,873+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,873+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning value [D:/KillBill_Files/KPM_Bundles] for [org.killbill.osgi.bundle.install.dir] on [org.killbill.billing.osgi.config.OSGIConfig#getRootInstallationDir()] 2021-07-16T20:27:02,905+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,905+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,905+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,905+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,905+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,905+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,936+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,936+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,936+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,936+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,936+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,936+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,951+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,951+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,951+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,951+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:02,951+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,092+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,092+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [] for [org.killbill.invoice.plugin] on [org.killbill.billing.util.config.definition.InvoiceConfig#getInvoicePluginNames()] 2021-07-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,108+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,123+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,139+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Assigning default value [NoOverdueConfig.xml] for [org.killbill.overdue.uri] on [org.killbill.billing.overdue.OverdueProperties#getConfigURI()] 2021-07-16T20:27:03,233+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,233+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,233+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,233+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,233+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,233+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,248+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,264+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,264+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,545+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,545+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,545+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,545+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,545+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,545+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,561+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,576+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,592+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,592+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,592+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,639+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,639+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,639+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,639+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,654+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,654+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,654+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,654+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,654+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,654+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,670+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,670+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,670+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,686+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,701+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,717+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:03,733+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:05,154+0000 lvl='INFO', log='HikariDataSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', main - Starting... 2021-07-16T20:27:05,279+0000 lvl='INFO', log='HikariDataSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', main - Start completed. 2021-07-16T20:27:05,980+0000 lvl='INFO', log='PersistentBusProvider', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Creating Persistent bus for bus_events 2021-07-16T20:27:06,074+0000 lvl='INFO', log='PersistentBusProvider', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Creating Persistent bus for bus_ext_events 2021-07-16T20:27:06,136+0000 lvl='INFO', log='HikariDataSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', shiro - Starting... 2021-07-16T20:27:06,152+0000 lvl='INFO', log='HikariDataSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', shiro - Start completed. 2021-07-16T20:27:07,449+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-immutable will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,449+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache account-immutable will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,824+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-immutable' 2021-07-16T20:27:07,824+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-immutable' created in EhcacheManager. 2021-07-16T20:27:07,871+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-immutable 2021-07-16T20:27:07,871+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-immutable 2021-07-16T20:27:07,871+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-bcd will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,871+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache account-bcd will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,886+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-bcd' 2021-07-16T20:27:07,886+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-bcd' created in EhcacheManager. 2021-07-16T20:27:07,886+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-bcd 2021-07-16T20:27:07,886+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-bcd 2021-07-16T20:27:07,886+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache record-id will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,886+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache record-id will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,902+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'record-id' 2021-07-16T20:27:07,902+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'record-id' created in EhcacheManager. 2021-07-16T20:27:07,902+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=record-id 2021-07-16T20:27:07,902+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=record-id 2021-07-16T20:27:07,902+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-record-id will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,902+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache account-record-id will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,917+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-record-id' 2021-07-16T20:27:07,917+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-record-id' created in EhcacheManager. 2021-07-16T20:27:07,917+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-record-id 2021-07-16T20:27:07,917+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-record-id 2021-07-16T20:27:07,917+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-record-id will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,917+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-record-id will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,933+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-record-id' 2021-07-16T20:27:07,933+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-record-id' created in EhcacheManager. 2021-07-16T20:27:07,933+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-record-id 2021-07-16T20:27:07,933+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-record-id 2021-07-16T20:27:07,933+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache object-id will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,933+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache object-id will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,949+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'object-id' 2021-07-16T20:27:07,949+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'object-id' created in EhcacheManager. 2021-07-16T20:27:07,949+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=object-id 2021-07-16T20:27:07,949+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=object-id 2021-07-16T20:27:07,949+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-catalog will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,949+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-catalog will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,964+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-catalog' 2021-07-16T20:27:07,964+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-catalog' created in EhcacheManager. 2021-07-16T20:27:07,964+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-catalog 2021-07-16T20:27:07,964+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-catalog 2021-07-16T20:27:07,964+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-config will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,964+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-config will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,980+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-config' 2021-07-16T20:27:07,980+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-config' created in EhcacheManager. 2021-07-16T20:27:07,980+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-config 2021-07-16T20:27:07,980+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-config 2021-07-16T20:27:07,980+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-overdue-config will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,980+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-overdue-config will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:07,996+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-overdue-config' 2021-07-16T20:27:07,996+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-overdue-config' created in EhcacheManager. 2021-07-16T20:27:07,996+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-overdue-config 2021-07-16T20:27:07,996+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-overdue-config 2021-07-16T20:27:07,996+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-kv will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:07,996+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-kv will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:08,011+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-kv' 2021-07-16T20:27:08,011+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-kv' created in EhcacheManager. 2021-07-16T20:27:08,011+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-kv 2021-07-16T20:27:08,011+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-kv 2021-07-16T20:27:08,011+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:08,011+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:08,011+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant' 2021-07-16T20:27:08,011+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant' created in EhcacheManager. 2021-07-16T20:27:08,011+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant 2021-07-16T20:27:08,027+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant 2021-07-16T20:27:08,027+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache overridden-plan will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:08,027+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache overridden-plan will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:08,027+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'overridden-plan' 2021-07-16T20:27:08,027+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'overridden-plan' created in EhcacheManager. 2021-07-16T20:27:08,042+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=overridden-plan 2021-07-16T20:27:08,042+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=overridden-plan 2021-07-16T20:27:08,042+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache tenant-payment-state-machine-config will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:08,042+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache tenant-payment-state-machine-config will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:08,042+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'tenant-payment-state-machine-config' 2021-07-16T20:27:08,042+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'tenant-payment-state-machine-config' created in EhcacheManager. 2021-07-16T20:27:08,058+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-payment-state-machine-config 2021-07-16T20:27:08,058+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=tenant-payment-state-machine-config 2021-07-16T20:27:08,058+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache account-id-from-bundle-id will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:08,058+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache account-id-from-bundle-id will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:08,058+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'account-id-from-bundle-id' 2021-07-16T20:27:08,058+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'account-id-from-bundle-id' created in EhcacheManager. 2021-07-16T20:27:08,074+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-id-from-bundle-id 2021-07-16T20:27:08,074+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=account-id-from-bundle-id 2021-07-16T20:27:08,074+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache bundle-id-from-subscription-id will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:08,074+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache bundle-id-from-subscription-id will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:08,074+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'bundle-id-from-subscription-id' 2021-07-16T20:27:08,074+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'bundle-id-from-subscription-id' created in EhcacheManager. 2021-07-16T20:27:08,074+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=bundle-id-from-subscription-id 2021-07-16T20:27:08,089+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=bundle-id-from-subscription-id 2021-07-16T20:27:08,089+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='localhost-startStop-1', 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-16T20:27:08,089+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', 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-16T20:27:08,089+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', 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-16T20:27:08,105+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1' 2021-07-16T20:27:08,105+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1' created in EhcacheManager. 2021-07-16T20:27:08,105+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 2021-07-16T20:27:08,105+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1 2021-07-16T20:27:08,105+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [org.killbill.billing.util.security.shiro.realm.KillBillJdbcRealm.authorizationCache.1] 2021-07-16T20:27:08,183+0000 lvl='INFO', log='BusOptimizerNoop', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Feature BusOptimizer is OFF 2021-07-16T20:27:08,183+0000 lvl='INFO', log='XMLLoader', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.catalog.StandaloneCatalog from xml file at: EmptyCatalog.xml 2021-07-16T20:27:08,433+0000 lvl='INFO', log='InvoiceOptimizerNoop', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Feature InvoiceOptimizer is OFF 2021-07-16T20:27:08,433+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering service='__EXTERNAL_PAYMENT__' 2021-07-16T20:27:08,433+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering service='__DEFAULT_PAYMENT_CONTROL__' 2021-07-16T20:27:08,433+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering service='__INVOICE_PAYMENT_CONTROL_PLUGIN__' 2021-07-16T20:27:08,449+0000 lvl='INFO', log='XMLLoader', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.automaton.DefaultStateMachineConfig from xml file at: jar:file:/C:/Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-payment-0.22.24.jar!/org/killbill/billing/payment/retry/RetryStates.xml 2021-07-16T20:27:09,331+0000 lvl='INFO', log='HikariDataSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', osgi - Starting... 2021-07-16T20:27:09,331+0000 lvl='INFO', log='HikariDataSource', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', osgi - Start completed. 2021-07-16T20:27:09,347+0000 lvl='INFO', log='XMLLoader', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.overdue.config.DefaultOverdueConfig from xml file at: NoOverdueConfig.xml 2021-07-16T20:27:09,600+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache with name org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 does not yet exist. Creating now. 2021-07-16T20:27:09,600+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:09,600+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:09,616+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2' 2021-07-16T20:27:09,616+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2' created in EhcacheManager. 2021-07-16T20:27:09,616+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 2021-07-16T20:27:09,616+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2 2021-07-16T20:27:09,616+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [org.killbill.billing.server.security.KillbillJdbcTenantRealm.authenticationCache.2] 2021-07-16T20:27:09,616+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache with name org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 does not yet exist. Creating now. 2021-07-16T20:27:09,616+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 will be supplemented by template defaultCacheConfiguration 2021-07-16T20:27:09,616+0000 lvl='INFO', log='ConfigurationMerger', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:27:09,631+0000 lvl='INFO', log='EhcacheLoggingListener', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2' 2021-07-16T20:27:09,631+0000 lvl='INFO', log='EhcacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Cache 'org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2' created in EhcacheManager. 2021-07-16T20:27:09,631+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 2021-07-16T20:27:09,631+0000 lvl='INFO', log='Eh107CacheManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2 2021-07-16T20:27:09,631+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [org.killbill.billing.server.security.KillbillJdbcTenantRealm.authorizationCache.2] 2021-07-16T20:27:09,744+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage BOOT 2021-07-16T20:27:09,761+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler init for service nodes-service 2021-07-16T20:27:10,015+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage LOAD_CATALOG 2021-07-16T20:27:10,015+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler loadCatalog for service catalog-service 2021-07-16T20:27:10,015+0000 lvl='INFO', log='XMLLoader', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.catalog.StandaloneCatalog from xml file at: SpyCarAdvanced.xml 2021-07-16T20:27:10,112+0000 lvl='INFO', log='DefaultCatalogService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Successfully loaded the default catalog SpyCarAdvanced.xml 2021-07-16T20:27:10,112+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler loadConfig for service overdue-service 2021-07-16T20:27:10,112+0000 lvl='INFO', log='XMLLoader', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.billing.overdue.config.DefaultOverdueConfig from xml file at: NoOverdueConfig.xml 2021-07-16T20:27:10,128+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage INIT_BUS 2021-07-16T20:27:10,128+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initBus for service bus-service 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_events Inserting 0 entries into inflightQ during initialization 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_events Initialized with queueId=1, mode=STICKY_EVENTS 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initBus for service external-bus-service 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_ext_events Inserting 0 entries into inflightQ during initialization 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DBBackedQueueWithInflightQueue', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-bus_ext_events Initialized with queueId=2, mode=STICKY_EVENTS 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage INIT_PLUGIN 2021-07-16T20:27:10,143+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service osgi-service 2021-07-16T20:27:10,159+0000 lvl='INFO', log='DefaultOSGIService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively C:\var\tmp\felix\osgi-cache\bundle0 2021-07-16T20:27:10,159+0000 lvl='INFO', log='DefaultOSGIService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively C:\var\tmp\felix\osgi-cache\bundle1\version0.0 2021-07-16T20:27:10,159+0000 lvl='INFO', log='DefaultOSGIService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively C:\var\tmp\felix\osgi-cache\bundle1 2021-07-16T20:27:10,174+0000 lvl='INFO', log='DefaultOSGIService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively C:\var\tmp\felix\osgi-cache\bundle2\version0.0 2021-07-16T20:27:10,174+0000 lvl='INFO', log='DefaultOSGIService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively C:\var\tmp\felix\osgi-cache\bundle2 2021-07-16T20:27:10,174+0000 lvl='INFO', log='DefaultOSGIService', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Deleted recursively C:\var\tmp\felix\osgi-cache 2021-07-16T20:27:10,643+0000 lvl='WARN', log='JRubyJarHelper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Unable to find the JRuby bundle at D:/KillBill_Files/KPM_Bundles/platform/jruby.jar, ruby plugins won't be started! 2021-07-16T20:27:10,643+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Installing Java OSGI bundle from D:\KillBill_Files\KPM_Bundles\platform\killbill-platform-osgi-bundles-kpm-0.40.7.jar 2021-07-16T20:27:11,053+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Installing Java OSGI bundle from D:\KillBill_Files\KPM_Bundles\platform\killbill-platform-osgi-bundles-logger-0.40.7.jar 2021-07-16T20:27:11,528+0000 lvl='WARN', log='PluginFinder', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Configuration root dir D:/KillBill_Files/KPM_Bundles/plugins/ruby is not a valid directory 2021-07-16T20:27:11,528+0000 lvl='INFO', log='PluginFinder', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Adding plugin stripe-plugin-7.3.0 2021-07-16T20:27:11,528+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Installing Java bundle for plugin stripe-plugin from D:\KillBill_Files\KPM_Bundles\plugins\java\stripe-plugin\7.3.0\stripe-plugin-7.3.0.jar 2021-07-16T20:27:11,927+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage INIT_SERVICE 2021-07-16T20:27:11,927+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service security-service 2021-07-16T20:27:11,927+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service config-service 2021-07-16T20:27:11,927+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service broadcast-service 2021-07-16T20:27:11,959+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service extBusEvent-listener-service 2021-07-16T20:27:11,959+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service tenant-service 2021-07-16T20:27:12,037+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service catalog-service 2021-07-16T20:27:12,037+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service subscription-service 2021-07-16T20:27:12,052+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service entitlement-service 2021-07-16T20:27:12,052+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service invoice-service 2021-07-16T20:27:12,052+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service invoice-listener-service 2021-07-16T20:27:12,068+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service payment-service 2021-07-16T20:27:12,068+0000 lvl='INFO', log='XMLLoader', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Initializing an object of class org.killbill.automaton.DefaultStateMachineConfig from xml file at: jar:file:/C:/Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-payment-0.22.24.jar!/org/killbill/billing/payment/PaymentStates.xml 2021-07-16T20:27:12,146+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service overdue-service 2021-07-16T20:27:12,146+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler initialize for service jaxrs-service 2021-07-16T20:27:12,146+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler registerForNotifications for service beatrix-service 2021-07-16T20:27:12,146+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler registerForNotifications for service server-service 2021-07-16T20:27:12,163+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage START_PLUGIN 2021-07-16T20:27:12,165+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service osgi-service 2021-07-16T20:27:12,167+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Starting bundle file:D:\KillBill_Files\KPM_Bundles\platform\killbill-platform-osgi-bundles-kpm-0.40.7.jar 2021-07-16T20:27:13,424+0000 lvl='INFO', log='PluginApp', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [dev@servletcontainer]: Server started in 512ms GET /plugins [*/*] [*/*] (/PluginsResource.getAvailablePlugins) listening on: http://localhost:8080/plugins/killbill-kpm/ 2021-07-16T20:27:13,439+0000 lvl='INFO', log='DefaultServletRouter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering OSGI servlet at /killbill-kpm 2021-07-16T20:27:13,439+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Starting bundle file:D:\KillBill_Files\KPM_Bundles\platform\killbill-platform-osgi-bundles-logger-0.40.7.jar 2021-07-16T20:27:13,455+0000 lvl='INFO', log='LogEntriesManager', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Created new cache b47c040e-2189-4cd2-9d37-f321ea59e8e7 (1 active) 2021-07-16T20:27:13,600+0000 lvl='INFO', log='KillbillLogWriter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.killbill-platform-osgi-bundles-logger] ServiceEvent REGISTERED 2021-07-16T20:27:14,143+0000 lvl='INFO', log='PluginApp', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [dev@servletcontainer]: Server started in 415ms GET / [text/event-stream] [*/*] (/anonymous) listening on: http://localhost:8080/plugins/killbill-osgi-logger/ 2021-07-16T20:27:14,159+0000 lvl='INFO', log='DefaultServletRouter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering OSGI servlet at /killbill-osgi-logger 2021-07-16T20:27:14,159+0000 lvl='INFO', log='KillbillLogWriter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.killbill-platform-osgi-bundles-logger] ServiceEvent REGISTERED 2021-07-16T20:27:14,174+0000 lvl='INFO', log='FileInstall', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Starting bundle file:D:\KillBill_Files\KPM_Bundles\plugins\java\stripe-plugin\7.3.0\stripe-plugin-7.3.0.jar 2021-07-16T20:27:14,174+0000 lvl='INFO', log='KillbillLogWriter', th='FelixDispatchQueue', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.killbill-platform-osgi-bundles-logger] BundleEvent STARTED 2021-07-16T20:27:14,174+0000 lvl='INFO', log='KillbillLogWriter', th='FelixDispatchQueue', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] BundleEvent RESOLVED 2021-07-16T20:27:14,174+0000 lvl='INFO', log='KillbillActivatorBase', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', OSGI bundle='org.kill-bill.billing.plugin.java.stripe-plugin' received START command 2021-07-16T20:27:14,574+0000 lvl='INFO', log='DefaultHealthcheckPluginRegistry', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering service='killbill-stripe' 2021-07-16T20:27:14,574+0000 lvl='INFO', log='KillbillLogWriter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] ServiceEvent REGISTERED 2021-07-16T20:27:14,592+0000 lvl='INFO', log='DefaultPaymentProviderPluginRegistry', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering service='killbill-stripe' 2021-07-16T20:27:14,592+0000 lvl='INFO', log='KillbillLogWriter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] ServiceEvent REGISTERED 2021-07-16T20:27:15,181+0000 lvl='INFO', log='PluginApp', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [dev@servletcontainer]: Server started in 490ms GET /healthcheck [*/*] [*/*] (/StripeHealthcheckServlet.check) POST /checkout [*/*] [*/*] (/StripeCheckoutServlet.createSession) listening on: http://localhost:8080/plugins/killbill-stripe/ 2021-07-16T20:27:15,197+0000 lvl='INFO', log='DefaultServletRouter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Registering OSGI servlet at /killbill-stripe 2021-07-16T20:27:15,197+0000 lvl='INFO', log='KillbillLogWriter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] ServiceEvent REGISTERED 2021-07-16T20:27:15,197+0000 lvl='INFO', log='KillbillLogWriter', th='FelixDispatchQueue', xff='', rId='', tok='', aRId='', tRId='', [org.kill-bill.billing.plugin.java.stripe-plugin] BundleEvent STARTED 2021-07-16T20:27:15,197+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage START_SERVICE 2021-07-16T20:27:15,197+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service nodes-service 2021-07-16T20:27:15,228+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service broadcast-service 2021-07-16T20:27:15,244+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service extBusEvent-listener-service 2021-07-16T20:27:15,244+0000 lvl='INFO', log='DBBackedQueue', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', DBBackedQueue-notifications Initialized mode=STICKY_POLLING 2021-07-16T20:27:15,244+0000 lvl='INFO', log='DefaultReaper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', NotificationReaper: Starting... reapThresholdMillis=600000, schedulePeriodMillis=180000 2021-07-16T20:27:15,259+0000 lvl='INFO', log='DefaultQueueLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', notifications: Starting... 2021-07-16T20:27:15,275+0000 lvl='INFO', log='DefaultQueueLifecycle', th='notifications-lifecycle-th-1', xff='', rId='', tok='', aRId='', tRId='', notifications: Thread notifications-lifecycle-th-1-dispatcher [53] starting 2021-07-16T20:27:15,275+0000 lvl='INFO', log='DefaultQueueLifecycle', th='notifications-lifecycle-th-2', xff='', rId='', tok='', aRId='', tRId='', notifications: Thread notifications-lifecycle-th-2-completion [54] starting 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service tenant-service 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultQueueLifecycle', th='notifications-lifecycle-th-3', xff='', rId='', tok='', aRId='', tRId='', notifications: Thread notifications-lifecycle-th-3-completion [55] starting 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service subscription-service 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service entitlement-service 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service invoice-service 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service invoice-listener-service 2021-07-16T20:27:15,291+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service payment-service 2021-07-16T20:27:15,306+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service overdue-service 2021-07-16T20:27:15,306+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler start for service server-service 2021-07-16T20:27:15,306+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle firing stage START_BUS 2021-07-16T20:27:15,306+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler startBus for service bus-service 2021-07-16T20:27:15,306+0000 lvl='INFO', log='DefaultReaper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', BusReaper: Starting... reapThresholdMillis=600000, schedulePeriodMillis=180000 2021-07-16T20:27:15,322+0000 lvl='INFO', log='DefaultQueueLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', bus_events: Starting... 2021-07-16T20:27:15,338+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 [59] starting 2021-07-16T20:27:15,338+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 [60] starting 2021-07-16T20:27:15,338+0000 lvl='INFO', log='DefaultLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Killbill lifecycle calling handler startBus for service external-bus-service 2021-07-16T20:27:15,338+0000 lvl='INFO', log='DefaultReaper', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', BusReaper: Starting... reapThresholdMillis=600000, schedulePeriodMillis=180000 2021-07-16T20:27:15,338+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 [61] starting 2021-07-16T20:27:15,353+0000 lvl='INFO', log='DefaultQueueLifecycle', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', bus_ext_events: Starting... 2021-07-16T20:27:15,353+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 [63] starting 2021-07-16T20:27:15,369+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 [64] starting 2021-07-16T20:27:15,369+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 [65] starting 2021-07-16T20:27:15,665+0000 lvl='INFO', log='Reflections', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Reflections took 222 ms to scan 1 urls, producing 306 keys and 665 values 2021-07-16T20:27:20,875+0000 lvl='INFO', log='KillbillGuiceFilter', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Kill Bill server has started 2021-07-16T20:27:20,875+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:20,875+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:20,875+0000 lvl='INFO', log='ConfigurationObjectFactory', th='localhost-startStop-1', 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-16T20:27:20,891+0000 lvl='INFO', log='UpdateChecker', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', For Kill Bill Commercial Support, visit http://thebillingproject.com or send an email to support@thebillingproject.com 2021-07-16T20:27:20,891+0000 lvl='WARN', log='KillbillHealthcheck', th='localhost-startStop-1', xff='', rId='', tok='', aRId='', tRId='', Putting host in rotation 2021-07-16T20:28:48,295+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:48.278000 #13092] INFO -- : Started GET "/kaui/users/sign_in" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:28:48 -0500 2021-07-16T20:28:48,528+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:48.528000 #13092] INFO -- : Processing by Kaui::SessionsController#new as HTML 2021-07-16T20:28:48,712+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:48.712000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:28:48,836+0000 lvl='INFO', log='AbstractValidatingSessionManager', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Enabling session validation scheduler... 2021-07-16T20:28:48,850+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Cache with name shiro-activeSessionCache does not yet exist. Creating now. 2021-07-16T20:28:48,852+0000 lvl='INFO', log='ConfigurationMerger', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Configuration of cache shiro-activeSessionCache will be supplemented by template defaultCacheConfiguration 2021-07-16T20:28:48,852+0000 lvl='INFO', log='ConfigurationMerger', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Cache shiro-activeSessionCache will use expiry configuration from template defaultCacheConfiguration 2021-07-16T20:28:48,865+0000 lvl='INFO', log='EhcacheLoggingListener', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Added Ehcache 'shiro-activeSessionCache' 2021-07-16T20:28:48,866+0000 lvl='INFO', log='EhcacheManager', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Cache 'shiro-activeSessionCache' created in EhcacheManager. 2021-07-16T20:28:48,866+0000 lvl='INFO', log='Eh107CacheManager', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheConfiguration,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=shiro-activeSessionCache 2021-07-16T20:28:48,867+0000 lvl='INFO', log='Eh107CacheManager', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Registering Ehcache MBean javax.cache:type=CacheStatistics,CacheManager=jar.file./C./Tomcat/apache-tomcat-8.5.65/webapps/ROOT/WEB-INF/lib/killbill-util-0.22.24.jar!/ehcache.xml,Cache=shiro-activeSessionCache 2021-07-16T20:28:48,883+0000 lvl='INFO', log='EhcacheShiroManagerProvider$EhcacheShiroManagerWrapper', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Added EhcacheShiro named [shiro-activeSessionCache] 2021-07-16T20:28:49,123+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', 1 * Server has received a request on thread http-nio-8080-exec-2 1 > GET http://127.0.0.1:8080/1.0/kb/security/subject 1 > accept: application/json 1 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 1 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 1 > host: 127.0.0.1:8080 1 > user-agent: killbill/3.2.0; 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-16T20:28:49,303+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-2', xff='', rId='a0b3e8ef-6585-48bf-8910-4096b4423b4f', tok='', aRId='', tRId='', 1 * Server responded with a response on thread http-nio-8080-exec-2 1 < 200 1 < Content-Type: application/json 2021-07-16T20:28:49,363+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:49.363000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='648.0' 2021-07-16T20:28:49,480+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:49.480000 #13092] INFO -- : Redirected to http://localhost:8080/kaui/tenants 2021-07-16T20:28:49,482+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:49.482000 #13092] INFO -- : Filter chain halted as :require_no_authentication rendered or redirected 2021-07-16T20:28:49,484+0000 lvl='INFO', log='', th='http-nio-8080-exec-1', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:49.484000 #13092] INFO -- : Completed 302 Found in 954ms (ActiveRecord: 16.0ms) 2021-07-16T20:28:49,562+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:49.562000 #13092] INFO -- : Started GET "/kaui/tenants" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:28:49 -0500 2021-07-16T20:28:49,573+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:49.572000 #13092] INFO -- : Processing by Kaui::TenantsController#index as HTML 2021-07-16T20:28:49,598+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:49.597000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:28:49,626+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-5', xff='', rId='', tok='', aRId='', tRId='', 2 * Server has received a request on thread http-nio-8080-exec-5 2 > GET http://127.0.0.1:8080/1.0/kb/security/subject 2 > accept: application/json 2 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 2 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 2 > host: 127.0.0.1:8080 2 > user-agent: killbill/3.2.0; 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-16T20:28:49,631+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-5', xff='', rId='fbfd1909-a421-4d4e-8374-0f14cb30dfbc', tok='', aRId='', tRId='', 2 * Server responded with a response on thread http-nio-8080-exec-5 2 < 200 2 < Content-Type: application/json 2021-07-16T20:28:49,639+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:49.639000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='40.0' 2021-07-16T20:28:49,892+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:49.892000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/tenants/index.html.erb within kaui/layouts/kaui_application 2021-07-16T20:28:49,960+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:49.960000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/tenants/_form.html.erb (54.0ms) [cache miss] 2021-07-16T20:28:49,961+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:49.961000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/tenants/index.html.erb within kaui/layouts/kaui_application (68.0ms) 2021-07-16T20:28:49,974+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:49.974000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_header.html.erb 2021-07-16T20:28:52,776+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.775000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_header.html.erb (2800.0ms) 2021-07-16T20:28:52,784+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.784000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_navbar.html.erb 2021-07-16T20:28:52,830+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.830000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/permissions' 2021-07-16T20:28:52,855+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-7', xff='', rId='', tok='', aRId='', tRId='', 3 * Server has received a request on thread http-nio-8080-exec-7 3 > GET http://127.0.0.1:8080/1.0/kb/security/permissions 3 > accept: application/json 3 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 3 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 3 > host: 127.0.0.1:8080 3 > user-agent: killbill/3.2.0; 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-16T20:28:52,928+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-7', xff='', rId='57ce0be4-9c42-4118-b107-a343555c43c1', tok='', aRId='', tRId='', 3 * Server responded with a response on thread http-nio-8080-exec-7 3 < 200 3 < Content-Type: application/json 2021-07-16T20:28:52,934+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.933000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='102.0' 2021-07-16T20:28:52,941+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.941000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_navbar.html.erb (155.0ms) 2021-07-16T20:28:52,947+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.947000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_flash.html.erb 2021-07-16T20:28:52,954+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.954000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_flash.html.erb (6.0ms) 2021-07-16T20:28:52,959+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.959000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_footer.html.erb 2021-07-16T20:28:52,964+0000 lvl='INFO', log='tenants#index', th='http-nio-8080-exec-4', xff='', rId='58289a7b-1fb8-412b-866d-ae15603c425e', aId='', tId='', I, [2021-07-16T15:28:52.964000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_footer.html.erb (2.0ms) 2021-07-16T20:28:52,967+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:52.967000 #13092] INFO -- : Completed 200 OK in 3394ms (Views: 3086.7ms | ActiveRecord: 26.0ms) 2021-07-16T20:28:53,529+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:53.529000 #13092] INFO -- : Started GET "/kaui/main/available_engines.json" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:28:53 -0500 2021-07-16T20:28:53,546+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:53.546000 #13092] INFO -- : Processing by MainController#available_engines as JSON 2021-07-16T20:28:53,547+0000 lvl='INFO', log='', th='http-nio-8080-exec-9', xff='', rId='', aId='', tId='', I, [2021-07-16T15:28:53.547000 #13092] INFO -- : Parameters: {"main"=>{}} 2021-07-16T20:28:53,563+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-9', xff='', rId='6e09cb91-42f6-4aab-85b4-ab65ea6041df', aId='', tId='', I, [2021-07-16T15:28:53.563000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:28:53,586+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='', tok='', aRId='', tRId='', 4 * Server has received a request on thread http-nio-8080-exec-10 4 > GET http://127.0.0.1:8080/1.0/kb/security/subject 4 > accept: application/json 4 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 4 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 4 > host: 127.0.0.1:8080 4 > user-agent: killbill/3.2.0; 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-16T20:28:53,591+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='07b5f7fd-3b18-42b6-8c06-2efc4199258b', tok='', aRId='', tRId='', 4 * Server responded with a response on thread http-nio-8080-exec-10 4 < 200 4 < Content-Type: application/json 2021-07-16T20:28:53,602+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-9', xff='', rId='6e09cb91-42f6-4aab-85b4-ab65ea6041df', aId='', tId='', I, [2021-07-16T15:28:53.602000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='35.0' 2021-07-16T20:28:53,606+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-9', xff='', rId='6e09cb91-42f6-4aab-85b4-ab65ea6041df', aId='', tId='', I, [2021-07-16T15:28:53.606000 #13092] INFO -- : Filter chain halted as :check_for_redirect_to_tenant_screen rendered or redirected 2021-07-16T20:28:53,613+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-9', xff='', rId='6e09cb91-42f6-4aab-85b4-ab65ea6041df', aId='', tId='', I, [2021-07-16T15:28:53.613000 #13092] INFO -- : Completed 200 OK in 65ms (Views: 1.0ms | ActiveRecord: 2.0ms) 2021-07-16T20:31:14,638+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.638000 #13092] INFO -- : Started POST "/kaui/tenants/select_tenant" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:14 -0500 2021-07-16T20:31:14,643+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.643000 #13092] INFO -- : Processing by Kaui::TenantsController#select_tenant as HTML 2021-07-16T20:31:14,649+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.649000 #13092] INFO -- : Parameters: {"utf8"=>"?", "authenticity_token"=>"V+cEh26KW1iGRf3uwTx7sgUZLCSH85QE88MGMahKnp19NIxZPjnj4UbQXOYtgmx2GcVaYUnJFW3Q3mmO88MVYw==", "kb_tenant_id"=>"5b04e2f1-6b6b-466e-a98b-f8e669601351", "commit"=>"Save"} 2021-07-16T20:31:14,663+0000 lvl='INFO', log='tenants#select_tenant', th='http-nio-8080-exec-5', xff='', rId='37218aff-772f-434b-a000-57b7aa61860a', aId='', tId='', I, [2021-07-16T15:31:14.663000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:14,686+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-6', xff='', rId='', tok='', aRId='', tRId='', 5 * Server has received a request on thread http-nio-8080-exec-6 5 > GET http://127.0.0.1:8080/1.0/kb/security/subject 5 > accept: application/json 5 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 5 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 5 > host: 127.0.0.1:8080 5 > user-agent: killbill/3.2.0; 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-16T20:31:14,691+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-6', xff='', rId='6733c26b-0f49-43e7-a4c3-b2518b89ea95', tok='', aRId='', tRId='', 5 * Server responded with a response on thread http-nio-8080-exec-6 5 < 200 5 < Content-Type: application/json 2021-07-16T20:31:14,699+0000 lvl='INFO', log='tenants#select_tenant', th='http-nio-8080-exec-5', xff='', rId='37218aff-772f-434b-a000-57b7aa61860a', aId='', tId='', I, [2021-07-16T15:31:14.699000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='34.0' 2021-07-16T20:31:14,716+0000 lvl='INFO', log='tenants#select_tenant', th='http-nio-8080-exec-5', xff='', rId='37218aff-772f-434b-a000-57b7aa61860a', aId='', tId='', I, [2021-07-16T15:31:14.715000 #13092] INFO -- : Redirected to http://localhost:8080/kaui/home 2021-07-16T20:31:14,718+0000 lvl='INFO', log='', th='http-nio-8080-exec-5', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.718000 #13092] INFO -- : Completed 302 Found in 68ms (ActiveRecord: 5.0ms) 2021-07-16T20:31:14,743+0000 lvl='INFO', log='', th='http-nio-8080-exec-8', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.743000 #13092] INFO -- : Started GET "/kaui/home" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:14 -0500 2021-07-16T20:31:14,753+0000 lvl='INFO', log='', th='http-nio-8080-exec-8', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.753000 #13092] INFO -- : Processing by Kaui::HomeController#index as HTML 2021-07-16T20:31:14,767+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.767000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:14,784+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-4', xff='', rId='', tok='', aRId='', tRId='', 6 * Server has received a request on thread http-nio-8080-exec-4 6 > GET http://127.0.0.1:8080/1.0/kb/security/subject 6 > accept: application/json 6 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 6 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 6 > host: 127.0.0.1:8080 6 > user-agent: killbill/3.2.0; 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-16T20:31:14,788+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-4', xff='', rId='a5e2b875-16c4-45c1-8c83-2f051a34aa6b', tok='', aRId='', tRId='', 6 * Server responded with a response on thread http-nio-8080-exec-4 6 < 200 6 < Content-Type: application/json 2021-07-16T20:31:14,797+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.797000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='29.0' 2021-07-16T20:31:14,826+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.825000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/home/index.html.erb within kaui/layouts/kaui_application 2021-07-16T20:31:14,855+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.855000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/home/_advanced_search_modal.html.erb (12.0ms) [cache miss] 2021-07-16T20:31:14,856+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.856000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/home/index.html.erb within kaui/layouts/kaui_application (30.0ms) 2021-07-16T20:31:14,864+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.863000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_header.html.erb 2021-07-16T20:31:14,876+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.875000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_header.html.erb (11.0ms) 2021-07-16T20:31:14,878+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.878000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_navbar.html.erb 2021-07-16T20:31:14,889+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.889000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/permissions' 2021-07-16T20:31:14,905+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', 7 * Server has received a request on thread http-nio-8080-exec-2 7 > GET http://127.0.0.1:8080/1.0/kb/security/permissions 7 > accept: application/json 7 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 7 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 7 > host: 127.0.0.1:8080 7 > user-agent: killbill/3.2.0; 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-16T20:31:14,909+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-2', xff='', rId='1aa10436-e638-4626-beea-e5ff81377df9', tok='', aRId='', tRId='', 7 * Server responded with a response on thread http-nio-8080-exec-2 7 < 200 7 < Content-Type: application/json 2021-07-16T20:31:14,913+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.912000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='19.0' 2021-07-16T20:31:14,941+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.937000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_navbar.html.erb (46.0ms) 2021-07-16T20:31:14,943+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.942000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_flash.html.erb 2021-07-16T20:31:14,949+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.949000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_flash.html.erb (1.0ms) 2021-07-16T20:31:14,950+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.950000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_footer.html.erb 2021-07-16T20:31:14,951+0000 lvl='INFO', log='home#index', th='http-nio-8080-exec-8', xff='', rId='449f2df3-ae5c-434a-a280-061964253b46', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:14.951000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_footer.html.erb (0.0ms) 2021-07-16T20:31:14,953+0000 lvl='INFO', log='', th='http-nio-8080-exec-8', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:14.953000 #13092] INFO -- : Completed 200 OK in 199ms (Views: 129.5ms | ActiveRecord: 7.0ms) 2021-07-16T20:31:15,444+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:15.444000 #13092] INFO -- : Started GET "/kaui/main/available_engines.json" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:15 -0500 2021-07-16T20:31:15,451+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:15.451000 #13092] INFO -- : Processing by MainController#available_engines as JSON 2021-07-16T20:31:15,452+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:15.452000 #13092] INFO -- : Parameters: {"main"=>{}} 2021-07-16T20:31:15,468+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-3', xff='', rId='5ccb74a3-1fe3-4472-ac8b-91a69cef87f3', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:15.468000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:15,485+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-1', xff='', rId='', tok='', aRId='', tRId='', 8 * Server has received a request on thread http-nio-8080-exec-1 8 > GET http://127.0.0.1:8080/1.0/kb/security/subject 8 > accept: application/json 8 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 8 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 8 > host: 127.0.0.1:8080 8 > user-agent: killbill/3.2.0; 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-16T20:31:15,489+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-1', xff='', rId='172fdb84-1ffc-4a9f-a82d-eb23518463fc', tok='', aRId='', tRId='', 8 * Server responded with a response on thread http-nio-8080-exec-1 8 < 200 8 < Content-Type: application/json 2021-07-16T20:31:15,499+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-3', xff='', rId='5ccb74a3-1fe3-4472-ac8b-91a69cef87f3', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:15.499000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='30.0' 2021-07-16T20:31:15,593+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-3', xff='', rId='5ccb74a3-1fe3-4472-ac8b-91a69cef87f3', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:15.592000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/nodesInfo' 2021-07-16T20:31:15,932+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-7', xff='', rId='', tok='', aRId='', tRId='8', 9 * Server has received a request on thread http-nio-8080-exec-7 9 > GET http://127.0.0.1:8080/1.0/kb/nodesInfo 9 > accept: application/json 9 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 9 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 9 > host: 127.0.0.1:8080 9 > user-agent: killbill/3.2.0; 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] 9 > x-killbill-apikey: lanstore 9 > x-killbill-apisecret: ******** 2021-07-16T20:31:16,017+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-7', xff='', rId='fb180471-542e-4b99-84b9-ee3a414bc8bc', tok='', aRId='', tRId='8', 9 * Server responded with a response on thread http-nio-8080-exec-7 9 < 200 9 < Content-Type: application/json 2021-07-16T20:31:16,028+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-3', xff='', rId='5ccb74a3-1fe3-4472-ac8b-91a69cef87f3', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:16.028000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='432.0' 2021-07-16T20:31:16,035+0000 lvl='INFO', log='', th='http-nio-8080-exec-3', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:16.035000 #13092] INFO -- : Completed 200 OK in 581ms (Views: 1.0ms | ActiveRecord: 15.0ms) 2021-07-16T20:31:17,850+0000 lvl='INFO', log='', th='http-nio-8080-exec-4', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:17.850000 #13092] INFO -- : Started GET "/kaui/kpm" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:17 -0500 2021-07-16T20:31:17,898+0000 lvl='INFO', log='', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:17.897000 #13092] INFO -- : Processing by KPM::NodesInfoController#index as HTML 2021-07-16T20:31:17,915+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:17.915000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:17,930+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='', tok='', aRId='', tRId='', 10 * Server has received a request on thread http-nio-8080-exec-10 10 > GET http://127.0.0.1:8080/1.0/kb/security/subject 10 > accept: application/json 10 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 10 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 10 > host: 127.0.0.1:8080 10 > user-agent: killbill/3.2.0; 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-16T20:31:17,932+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='c84fde23-58c6-47c3-8af6-6602c4720f42', tok='', aRId='', tRId='', 10 * Server responded with a response on thread http-nio-8080-exec-10 10 < 200 10 < Content-Type: application/json 2021-07-16T20:31:17,936+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:17.935000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='19.0' 2021-07-16T20:31:17,977+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:17.976000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/nodesInfo' 2021-07-16T20:31:18,155+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-9', xff='', rId='', tok='', aRId='', tRId='8', 11 * Server has received a request on thread http-nio-8080-exec-9 11 > GET http://127.0.0.1:8080/1.0/kb/nodesInfo 11 > accept: application/json 11 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 11 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 11 > host: 127.0.0.1:8080 11 > user-agent: killbill/3.2.0; 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] 11 > x-killbill-apikey: lanstore 11 > x-killbill-apisecret: ******** 2021-07-16T20:31:18,166+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-9', xff='', rId='1e3a4d21-5ae2-432b-973b-f46c9184fdf2', tok='', aRId='', tRId='8', 11 * Server responded with a response on thread http-nio-8080-exec-9 11 < 200 11 < Content-Type: application/json 2021-07-16T20:31:18,170+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.170000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='191.0' 2021-07-16T20:31:18,190+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.189000 #13092] INFO -- : Rendering gems/gems/killbill-kpm-ui-2.0.2/app/views/kpm/nodes_info/index.html.erb within kaui/layouts/kaui_application 2021-07-16T20:31:18,237+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.237000 #13092] INFO -- : Rendered gems/gems/killbill-kpm-ui-2.0.2/app/views/kpm/nodes_info/_nodes_table.html.erb (23.0ms) [cache miss] 2021-07-16T20:31:18,255+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.254000 #13092] INFO -- : Rendered gems/gems/killbill-kpm-ui-2.0.2/app/views/kpm/nodes_info/_logs_table.html.erb (6.0ms) [cache miss] 2021-07-16T20:31:18,256+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.256000 #13092] INFO -- : Rendered gems/gems/killbill-kpm-ui-2.0.2/app/views/kpm/nodes_info/index.html.erb within kaui/layouts/kaui_application (66.0ms) 2021-07-16T20:31:18,257+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.257000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_header.html.erb 2021-07-16T20:31:18,265+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.265000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_header.html.erb (6.0ms) 2021-07-16T20:31:18,270+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.270000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_navbar.html.erb 2021-07-16T20:31:18,278+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.278000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/permissions' 2021-07-16T20:31:18,289+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-1', xff='', rId='', tok='', aRId='', tRId='', 12 * Server has received a request on thread http-nio-8080-exec-1 12 > GET http://127.0.0.1:8080/1.0/kb/security/permissions 12 > accept: application/json 12 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 12 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 12 > host: 127.0.0.1:8080 12 > user-agent: killbill/3.2.0; 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-16T20:31:18,293+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-1', xff='', rId='61ae818b-e21f-473f-8712-2dadde40074f', tok='', aRId='', tRId='', 12 * Server responded with a response on thread http-nio-8080-exec-1 12 < 200 12 < Content-Type: application/json 2021-07-16T20:31:18,305+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.305000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='20.0' 2021-07-16T20:31:18,313+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.312000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_navbar.html.erb (41.0ms) 2021-07-16T20:31:18,317+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.317000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_flash.html.erb 2021-07-16T20:31:18,319+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.319000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_flash.html.erb (1.0ms) 2021-07-16T20:31:18,320+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.320000 #13092] INFO -- : Rendering gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_footer.html.erb 2021-07-16T20:31:18,321+0000 lvl='INFO', log='nodes_info#index', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='b63cf748-f627-47b6-b8cf-c2896adab16e', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.321000 #13092] INFO -- : Rendered gems/gems/kaui-2.1.0/app/views/kaui/layouts/kaui_footer.html.erb (1.0ms) 2021-07-16T20:31:18,325+0000 lvl='INFO', log='', th='Ruby-0-Thread-1: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.325000 #13092] INFO -- : Completed 200 OK in 427ms (Views: 138.6ms | ActiveRecord: 16.0ms) 2021-07-16T20:31:18,533+0000 lvl='INFO', log='', th='http-nio-8080-exec-7', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.532000 #13092] INFO -- : Started GET "/kaui/main/available_engines.json" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:18 -0500 2021-07-16T20:31:18,539+0000 lvl='INFO', log='', th='http-nio-8080-exec-7', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.539000 #13092] INFO -- : Processing by MainController#available_engines as JSON 2021-07-16T20:31:18,539+0000 lvl='INFO', log='', th='http-nio-8080-exec-7', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.539000 #13092] INFO -- : Parameters: {"main"=>{}} 2021-07-16T20:31:18,561+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-7', xff='', rId='d0df9456-7210-4385-8cdc-355fe06af98c', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.560000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:18,571+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-5', xff='', rId='', tok='', aRId='', tRId='', 13 * Server has received a request on thread http-nio-8080-exec-5 13 > GET http://127.0.0.1:8080/1.0/kb/security/subject 13 > accept: application/json 13 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 13 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 13 > host: 127.0.0.1:8080 13 > user-agent: killbill/3.2.0; 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-16T20:31:18,574+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-5', xff='', rId='9c9540bf-b9a8-4704-be97-8afee326d8f8', tok='', aRId='', tRId='', 13 * Server responded with a response on thread http-nio-8080-exec-5 13 < 200 13 < Content-Type: application/json 2021-07-16T20:31:18,577+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-7', xff='', rId='d0df9456-7210-4385-8cdc-355fe06af98c', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.577000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='15.0' 2021-07-16T20:31:18,606+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-7', xff='', rId='d0df9456-7210-4385-8cdc-355fe06af98c', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.606000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/nodesInfo' 2021-07-16T20:31:18,775+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='', tok='', aRId='', tRId='8', 14 * Server has received a request on thread http-nio-8080-exec-10 14 > GET http://127.0.0.1:8080/1.0/kb/nodesInfo 14 > accept: application/json 14 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 14 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 14 > host: 127.0.0.1:8080 14 > user-agent: killbill/3.2.0; 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] 14 > x-killbill-apikey: lanstore 14 > x-killbill-apisecret: ******** 2021-07-16T20:31:18,780+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='4a1ae0ef-789c-4a0f-8a3d-1063e8f3e8ac', tok='', aRId='', tRId='8', 14 * Server responded with a response on thread http-nio-8080-exec-10 14 < 200 14 < Content-Type: application/json 2021-07-16T20:31:18,785+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-7', xff='', rId='d0df9456-7210-4385-8cdc-355fe06af98c', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.785000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='178.0' 2021-07-16T20:31:18,791+0000 lvl='INFO', log='', th='http-nio-8080-exec-7', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.791000 #13092] INFO -- : Completed 200 OK in 251ms (Views: 0.9ms | ActiveRecord: 7.0ms) 2021-07-16T20:31:18,816+0000 lvl='INFO', log='main#available_engines', th='http-nio-8080-exec-9', xff='', rId='6e09cb91-42f6-4aab-85b4-ab65ea6041df', aId='', tId='', I, [2021-07-16T15:31:18.816000 #13092] INFO -- : Started GET "/kaui/kpm/nodes_info/refresh.js?kb_host=http%3A%2F%2F127.0.0.1%3A8080" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:18 -0500 2021-07-16T20:31:18,833+0000 lvl='INFO', log='', th='Ruby-0-Thread-2: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.833000 #13092] INFO -- : Processing by KPM::NodesInfoController#refresh as JS 2021-07-16T20:31:18,834+0000 lvl='INFO', log='', th='Ruby-0-Thread-2: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.834000 #13092] INFO -- : Parameters: {"kb_host"=>"http://127.0.0.1:8080"} 2021-07-16T20:31:18,862+0000 lvl='INFO', log='nodes_info#refresh', th='Ruby-0-Thread-2: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='dabd02d7-252c-4c25-978b-85c23f6b008d', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.862000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:18,868+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-8', xff='', rId='', tok='', aRId='', tRId='', 15 * Server has received a request on thread http-nio-8080-exec-8 15 > GET http://127.0.0.1:8080/1.0/kb/security/subject 15 > accept: application/json 15 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 15 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 15 > host: 127.0.0.1:8080 15 > user-agent: killbill/3.2.0; 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-16T20:31:18,872+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-8', xff='', rId='fbf4cf05-c98c-4c8e-a5a7-13fbd169ef56', tok='', aRId='', tRId='', 15 * Server responded with a response on thread http-nio-8080-exec-8 15 < 200 15 < Content-Type: application/json 2021-07-16T20:31:18,876+0000 lvl='INFO', log='nodes_info#refresh', th='Ruby-0-Thread-2: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='dabd02d7-252c-4c25-978b-85c23f6b008d', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:18.875000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='11.0' 2021-07-16T20:31:18,918+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:18.917000 #13092] INFO -- : Will retry connection after 0.924 seconds 2021-07-16T20:31:19,851+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:19.851000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:31:19,894+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-6', xff='', rId='', tok='', aRId='', tRId='', 16 * Server has received a request on thread http-nio-8080-exec-6 16 > GET http://127.0.0.1/plugins/killbill-osgi-logger 16 > accept: text/event-stream 16 > cache-control: no-cache 16 > host: 127.0.0.1 2021-07-16T20:31:19,963+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-6', xff='', rId='823d2522-319f-4da9-aacd-534bbe381117', tok='', aRId='', tRId='', 16 * Server responded with a response on thread http-nio-8080-exec-6 16 < 200 16 < Content-Type: text/event-stream 2021-07-16T20:31:19,965+0000 lvl='INFO', log='LogEntriesManager', th='http-nio-8080-exec-4', xff='', rId='', tok='', aRId='', tRId='', Created new cache c6d51bc1-1d62-4512-b04d-f99f6ce3d8dd (2 active) 2021-07-16T20:31:20,047+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:20.047000 #13092] INFO -- : Will retry connection after 1.571 seconds 2021-07-16T20:31:21,627+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:21.627000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:31:21,627+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-5', xff='', rId='', tok='', aRId='', tRId='', 17 * Server has received a request on thread http-nio-8080-exec-5 17 > GET http://127.0.0.1/plugins/killbill-osgi-logger 17 > accept: text/event-stream 17 > cache-control: no-cache 17 > host: 127.0.0.1 2021-07-16T20:31:21,627+0000 lvl='INFO', log='LogEntriesManager', th='http-nio-8080-exec-3', xff='', rId='', tok='', aRId='', tRId='', Created new cache 06eb2ce8-7c75-48b5-a0b3-9d70dc755852 (3 active) 2021-07-16T20:31:21,627+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-5', xff='', rId='f38c730f-1f6a-46b2-996c-9878dbd19fbc', tok='', aRId='', tRId='', 17 * Server responded with a response on thread http-nio-8080-exec-5 17 < 200 17 < Content-Type: text/event-stream 2021-07-16T20:31:21,627+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:21.627000 #13092] INFO -- : Will retry connection after 3.135 seconds 2021-07-16T20:31:24,789+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:24.789000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:31:24,789+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='', tok='', aRId='', tRId='', 18 * Server has received a request on thread http-nio-8080-exec-10 18 > GET http://127.0.0.1/plugins/killbill-osgi-logger 18 > accept: text/event-stream 18 > cache-control: no-cache 18 > host: 127.0.0.1 2021-07-16T20:31:24,789+0000 lvl='INFO', log='LogEntriesManager', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', Created new cache 3450f245-dbae-4bc4-9048-7ce218c1a74f (4 active) 2021-07-16T20:31:24,789+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-10', xff='', rId='f4a123b6-b37a-421a-a03b-b756ba7a526d', tok='', aRId='', tRId='', 18 * Server responded with a response on thread http-nio-8080-exec-10 18 < 200 18 < Content-Type: text/event-stream 2021-07-16T20:31:48,951+0000 lvl='INFO', log='', th='Ruby-0-Thread-3: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:48.951000 #13092] INFO -- : Stream connection closed 2021-07-16T20:31:48,992+0000 lvl='INFO', log='', th='Ruby-0-Thread-2: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:48.992000 #13092] INFO -- : Completed 200 OK in 30151ms (ActiveRecord: 23.0ms) 2021-07-16T20:31:50,572+0000 lvl='INFO', log='', th='http-nio-8080-exec-8', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:50.572000 #13092] INFO -- : Started GET "/kaui/kpm/nodes_info/refresh.js?kb_host=http%3A%2F%2F127.0.0.1%3A8080" for 0:0:0:0:0:0:0:1 at 2021-07-16 15:31:50 -0500 2021-07-16T20:31:50,590+0000 lvl='INFO', log='', th='Ruby-0-Thread-4: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:50.590000 #13092] INFO -- : Processing by KPM::NodesInfoController#refresh as JS 2021-07-16T20:31:50,590+0000 lvl='INFO', log='', th='Ruby-0-Thread-4: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:50.590000 #13092] INFO -- : Parameters: {"kb_host"=>"http://127.0.0.1:8080"} 2021-07-16T20:31:50,605+0000 lvl='INFO', log='nodes_info#refresh', th='Ruby-0-Thread-4: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='22927882-7b3f-4a02-91ef-9a546e3e545c', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:50.605000 #13092] INFO -- KillBillClient: Request method='GET', uri='http://127.0.0.1:8080/1.0/kb/security/subject' 2021-07-16T20:31:50,621+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-1', xff='', rId='', tok='', aRId='', tRId='', 19 * Server has received a request on thread http-nio-8080-exec-1 19 > GET http://127.0.0.1:8080/1.0/kb/security/subject 19 > accept: application/json 19 > accept-encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 19 > cookie: JSESSIONID=be6c8f56-b454-40f9-a8d3-74031bd2b9c6 19 > host: 127.0.0.1:8080 19 > user-agent: killbill/3.2.0; 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-16T20:31:50,621+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-1', xff='', rId='0531e63b-1138-4a51-85c7-1e2088652781', tok='', aRId='', tRId='', 19 * Server responded with a response on thread http-nio-8080-exec-1 19 < 200 19 < Content-Type: application/json 2021-07-16T20:31:50,621+0000 lvl='INFO', log='nodes_info#refresh', th='Ruby-0-Thread-4: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/actionpack-5.1.0/lib/action_controller/metal/live.rb:286', xff='', rId='22927882-7b3f-4a02-91ef-9a546e3e545c', aId='', tId='5b04e2f1-6b6b-466e-a98b-f8e669601351', I, [2021-07-16T15:31:50.621000 #13092] INFO -- KillBillClient: Response code='200', reason='OK', latency='16.0' 2021-07-16T20:31:50,668+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:50.668000 #13092] INFO -- : Will retry connection after 0.822 seconds 2021-07-16T20:31:51,504+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:51.504000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:31:51,504+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', W, [2021-07-16T15:31:51.504000 #13092] WARN -- : Unexpected error from event source: # 2021-07-16T20:31:51,504+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:51.504000 #13092] INFO -- : Will retry connection after 1.115 seconds 2021-07-16T20:31:52,630+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:52.630000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:31:52,630+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-3', xff='', rId='', tok='', aRId='', tRId='', 20 * Server has received a request on thread http-nio-8080-exec-3 20 > GET http://127.0.0.1/plugins/killbill-osgi-logger 20 > accept: text/event-stream 20 > cache-control: no-cache 20 > host: 127.0.0.1 20 > last-event-id: 5b11b123-7088-42af-a19e-aa5ab7f02442 2021-07-16T20:31:52,630+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-3', xff='', rId='6d1e6315-485c-413d-9b9f-f16a515e1a9f', tok='', aRId='', tRId='', 20 * Server responded with a response on thread http-nio-8080-exec-3 20 < 200 20 < Content-Type: text/event-stream 2021-07-16T20:31:52,630+0000 lvl='INFO', log='LogEntriesManager', th='http-nio-8080-exec-10', xff='', rId='', tok='', aRId='', tRId='', Created new cache 1bdfda67-5aa7-49bb-882e-237f3d32fb01 (5 active) 2021-07-16T20:31:52,645+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:52.645000 #13092] INFO -- : Will retry connection after 3.631 seconds 2021-07-16T20:31:56,280+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:56.280000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:31:56,280+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-2', xff='', rId='', tok='', aRId='', tRId='', 21 * Server has received a request on thread http-nio-8080-exec-2 21 > GET http://127.0.0.1/plugins/killbill-osgi-logger 21 > accept: text/event-stream 21 > cache-control: no-cache 21 > host: 127.0.0.1 21 > last-event-id: 5b11b123-7088-42af-a19e-aa5ab7f02442 2021-07-16T20:31:56,280+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-2', xff='', rId='456dbc50-bb50-456c-98d4-94a16d89f696', tok='', aRId='', tRId='', 21 * Server responded with a response on thread http-nio-8080-exec-2 21 < 200 21 < Content-Type: text/event-stream 2021-07-16T20:31:56,280+0000 lvl='INFO', log='LogEntriesManager', th='http-nio-8080-exec-7', xff='', rId='', tok='', aRId='', tRId='', Created new cache 3f680723-bfe0-47a7-a8aa-5c0f01da8950 (6 active) 2021-07-16T20:31:56,280+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:31:56.280000 #13092] INFO -- : Will retry connection after 4.806 seconds 2021-07-16T20:32:01,111+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:32:01.111000 #13092] INFO -- : Connecting to event stream at http://127.0.0.1:8080/plugins/killbill-osgi-logger 2021-07-16T20:32:01,111+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-9', xff='', rId='', tok='', aRId='', tRId='', 22 * Server has received a request on thread http-nio-8080-exec-9 22 > GET http://127.0.0.1/plugins/killbill-osgi-logger 22 > accept: text/event-stream 22 > cache-control: no-cache 22 > host: 127.0.0.1 22 > last-event-id: 5b11b123-7088-42af-a19e-aa5ab7f02442 2021-07-16T20:32:01,111+0000 lvl='INFO', log='LoggingFeature', th='http-nio-8080-exec-9', xff='', rId='e1a7f49b-814e-45e4-b580-e1b26530a014', tok='', aRId='', tRId='', 22 * Server responded with a response on thread http-nio-8080-exec-9 22 < 200 22 < Content-Type: text/event-stream 2021-07-16T20:32:01,111+0000 lvl='INFO', log='LogEntriesManager', th='http-nio-8080-exec-1', xff='', rId='', tok='', aRId='', tRId='', Created new cache ddf1f6d8-e0d4-416c-b1d0-f6bf9fa4d6d8 (7 active) 2021-07-16T20:32:01,111+0000 lvl='INFO', log='', th='Ruby-0-Thread-5: C:/Tomcat/apache-tomcat-8.5.65/webapps/kaui/WEB-INF/gems/gems/ld-eventsource-1.0.3/lib/ld-eventsource/client.rb:119', xff='', rId='', aId='', tId='', I, [2021-07-16T15:32:01.111000 #13092] INFO -- : Will retry connection after 12.239 seconds