· 6 years ago · Sep 11, 2019, 10:24 AM
1> Task :web:bootRun
2 ______ _____ ______ ______ ______ ______ __ __ ______
3/\ ___\ /\ __-. /\ ___\ /\ == \ /\ __ \ /\ ___\ /\ \/ / /\ ___\
4\ \ \____ \ \ \/\ \ \ \ \____ \ \ __< \ \ \/\ \ \ \ \____ \ \ _"-. \ \___ \
5 \ \_____\ \ \____- \ \_____\ \ \_\ \_\ \ \_____\ \ \_____\ \ \_\ \_\ \/\_____\
6 \/_____/ \/____/ \/_____/ \/_/ /_/ \/_____/ \/_____/ \/_/\/_/ \/_____/
7
8 Welcome to cdc-converter version 0.0.243-SNAPSHOT-00e8359
92019-09-11 11:19:06.656 INFO 13672 --- [ main] c.s.microservices.cdcconverter.web.Main : Starting Main on igloo with PID 13672 (C:\dev\cdc-converter\web\build\classes\java\main started by penguin in C:\dev\cdc-converter)
102019-09-11 11:19:06.693 INFO 13672 --- [ main] c.s.microservices.cdcconverter.web.Main : The following profiles are active: local
112019-09-11 11:19:09.177 INFO 13672 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data repositories in DEFAULT mode.
122019-09-11 11:19:09.420 INFO 13672 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 209ms. Found 3 repository interfaces.
132019-09-11 11:19:11.561 INFO 13672 --- [ main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$746c4c15] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
142019-09-11 11:19:11.642 INFO 13672 --- [ main] trationDelegate$BeanPostProcessorChecker : Bean 'org.apache.camel.spring.boot.CamelAutoConfiguration' of type [org.apache.camel.spring.boot.CamelAutoConfiguration$$EnhancerBySpringCGLIB$$96961234] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
152019-09-11 11:19:11.668 INFO 13672 --- [ main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.hateoas.config.HateoasConfiguration' of type [org.springframework.hateoas.config.HateoasConfiguration$$EnhancerBySpringCGLIB$$f3ec9947] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
162019-09-11 11:19:12.839 INFO 13672 --- [ main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port(s): 9090 (http)
172019-09-11 11:19:12.918 INFO 13672 --- [ main] o.apache.catalina.core.StandardService : Starting service [Tomcat]
182019-09-11 11:19:12.919 INFO 13672 --- [ main] org.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/9.0.22]
192019-09-11 11:19:13.244 INFO 13672 --- [ main] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring embedded WebApplicationContext
202019-09-11 11:19:13.245 INFO 13672 --- [ main] o.s.web.context.ContextLoader : Root WebApplicationContext: initialization completed in 6434 ms
212019-09-11 11:19:13.854 INFO 13672 --- [ main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Starting...
222019-09-11 11:19:14.883 INFO 13672 --- [ main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Start completed.
232019-09-11 11:19:15.034 INFO 13672 --- [ main] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [
24 name: default
25 ...]
262019-09-11 11:19:15.181 INFO 13672 --- [ main] org.hibernate.Version : HHH000412: Hibernate Core {5.3.10.Final}
272019-09-11 11:19:15.183 INFO 13672 --- [ main] org.hibernate.cfg.Environment : HHH000206: hibernate.properties not found
282019-09-11 11:19:15.637 INFO 13672 --- [ main] o.hibernate.annotations.common.Version : HCANN000001: Hibernate Commons Annotations {5.0.4.Final}
292019-09-11 11:19:16.004 INFO 13672 --- [ main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL95Dialect
302019-09-11 11:19:16.074 INFO 13672 --- [ main] o.h.e.j.e.i.LobCreatorBuilderImpl : HHH000422: Disabling contextual LOB creation as connection was null
312019-09-11 11:19:16.089 INFO 13672 --- [ main] org.hibernate.type.BasicTypeRegistry : HHH000270: Type registration [java.util.UUID] overrides previous : org.hibernate.type.UUIDBinaryType@3f838072
322019-09-11 11:19:18.056 INFO 13672 --- [ main] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
332019-09-11 11:19:18.412 INFO 13672 --- [ main] o.s.a.r.c.CachingConnectionFactory : Attempting to connect to: [big-starfish.rmq.cloudamqp.com:5672]
342019-09-11 11:19:18.575 INFO 13672 --- [ main] o.s.a.r.c.CachingConnectionFactory : Created new connection: rabbitConnectionFactory#43e6f05f:0/SimpleConnection@1b4bc7b9 [delegate=amqp://xpmhifty@35.176.231.189:5672/xpmhifty, localPort= 56250]
352019-09-11 11:19:19.522 INFO 13672 --- [ main] o.a.k.connect.json.JsonConverterConfig : JsonConverterConfig values:
36 converter.type = key
37 schemas.cache.size = 1000
38 schemas.enable = true
39
402019-09-11 11:19:19.527 INFO 13672 --- [ main] o.a.k.connect.json.JsonConverterConfig : JsonConverterConfig values:
41 converter.type = value
42 schemas.cache.size = 1000
43 schemas.enable = false
44
452019-09-11 11:19:19.696 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : Starting SqlServerConnectorTask with configuration:
462019-09-11 11:19:19.700 INFO 13672 --- [ main] c.s.m.cdcconverter.common.AnotherThing : Name: cdc-converter
472019-09-11 11:19:19.700 INFO 13672 --- [ main] c.s.m.cdcconverter.common.AnotherThing : Version: 0.0.243-00e8359
482019-09-11 11:19:19.700 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : connector.class = io.debezium.connector.sqlserver.SqlServerConnector
492019-09-11 11:19:19.701 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : offset.flush.interval.ms = 10000
502019-09-11 11:19:19.703 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.user = sa
512019-09-11 11:19:19.703 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.dbname = testDB
522019-09-11 11:19:19.703 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : offset.storage = com.spacebarmedia.microservices.cdcconverter.services.cdc.PostgreSQLOffsetBackingStore
532019-09-11 11:19:19.704 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.hostname = 127.0.0.1
542019-09-11 11:19:19.704 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.password = ********
552019-09-11 11:19:19.705 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : name = sql-server-connector
562019-09-11 11:19:19.705 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.server.name = local
572019-09-11 11:19:19.705 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.port = 1433
582019-09-11 11:19:19.705 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.history = com.spacebarmedia.microservices.cdcconverter.services.cdc.PostgreSQLDatabaseHistory
592019-09-11 11:19:19.706 INFO 13672 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : table.whitelist = dbo.customers,dbo.orders,dbo.products_on_hand
602019-09-11 11:19:20.472 INFO 13672 --- [pool-3-thread-1] io.debezium.util.Threads : Requested thread factory for connector SqlServerConnector, id = local named = error-handler
612019-09-11 11:19:20.488 INFO 13672 --- [pool-3-thread-1] io.debezium.util.Threads : Requested thread factory for connector SqlServerConnector, id = local named = change-event-source-coordinator
622019-09-11 11:19:20.494 INFO 13672 --- [pool-3-thread-1] io.debezium.util.Threads : Creating thread debezium-sqlserverconnector-local-change-event-source-coordinator
632019-09-11 11:19:20.517 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : No previous offset has been found
642019-09-11 11:19:20.518 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : According to the connector configuration both schema and data will be snapshotted
652019-09-11 11:19:20.519 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 1 - Preparing
662019-09-11 11:19:20.521 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 2 - Determining captured tables
672019-09-11 11:19:20.654 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 3 - Locking captured tables
682019-09-11 11:19:20.659 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Executing schema locking
692019-09-11 11:19:20.659 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Locking table testDB.dbo.customers
702019-09-11 11:19:20.661 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Locking table testDB.dbo.orders
712019-09-11 11:19:20.663 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Locking table testDB.dbo.products_on_hand
722019-09-11 11:19:20.665 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 4 - Determining snapshot offset
732019-09-11 11:19:20.683 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 5 - Reading structure of captured tables
742019-09-11 11:19:20.685 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Reading structure of schema 'testDB'
752019-09-11 11:19:20.748 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 6 - Persisting schema history
762019-09-11 11:19:21.153 WARN 13672 --- [ main] aWebConfiguration$JpaWebMvcConfiguration : spring.jpa.open-in-view is enabled by default. Therefore, database queries may be performed during view rendering. Explicitly configure spring.jpa.open-in-view to disable this warning
772019-09-11 11:19:22.079 INFO 13672 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Schema locks released.
782019-09-11 11:19:22.079 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 7 - Snapshotting data
792019-09-11 11:19:22.080 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Exporting data from table 'testDB.dbo.customers'
802019-09-11 11:19:22.081 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : For table 'testDB.dbo.customers' using select statement: 'SELECT * FROM [dbo].[customers]'
812019-09-11 11:19:22.155 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Finished exporting 7 records for table 'testDB.dbo.customers'; total duration '00:00:00.073'
822019-09-11 11:19:22.156 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Exporting data from table 'testDB.dbo.orders'
832019-09-11 11:19:22.156 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : For table 'testDB.dbo.orders' using select statement: 'SELECT * FROM [dbo].[orders]'
842019-09-11 11:19:22.167 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Finished exporting 4 records for table 'testDB.dbo.orders'; total duration '00:00:00.01'
852019-09-11 11:19:22.167 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Exporting data from table 'testDB.dbo.products_on_hand'
862019-09-11 11:19:22.167 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : For table 'testDB.dbo.products_on_hand' using select statement: 'SELECT * FROM [dbo].[products_on_hand]'
872019-09-11 11:19:22.173 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Finished exporting 9 records for table 'testDB.dbo.products_on_hand'; total duration '00:00:00.005'
882019-09-11 11:19:22.181 INFO 13672 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 8 - Finalizing
892019-09-11 11:19:22.246 INFO 13672 --- [rce-coordinator] .c.s.SqlServerStreamingChangeEventSource : CDC is enabled for table Capture instance "dbo_products" [sourceTableId=testDB.dbo.products, changeTableId=testDB.cdc.dbo_products_CT, startLsn=00000023:00000ba8:0061, changeTableObjectId=1269579561, stopLsn=NULL] but the table is not whitelisted by connector
902019-09-11 11:19:22.247 INFO 13672 --- [rce-coordinator] .c.s.SqlServerStreamingChangeEventSource : Last position recorded in offsets is 00000026:000005a8:0001(NULL)
912019-09-11 11:19:22.517 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162132 (2019-09-11T10:19:22.132Z)
922019-09-11 11:19:22.644 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162147 (2019-09-11T10:19:22.147Z)
932019-09-11 11:19:22.678 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162151 (2019-09-11T10:19:22.151Z)
942019-09-11 11:19:22.697 INFO 13672 --- [ main] pertySourcedRequestMappingHandlerMapping : Mapped URL path [/v2/api-docs] onto method [public org.springframework.http.ResponseEntity<springfox.documentation.spring.web.json.Json> springfox.documentation.swagger2.web.Swagger2Controller.getDocumentation(java.lang.String,javax.servlet.http.HttpServletRequest)]
952019-09-11 11:19:22.711 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162151 (2019-09-11T10:19:22.151Z)
962019-09-11 11:19:22.743 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162152 (2019-09-11T10:19:22.152Z)
972019-09-11 11:19:22.779 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162152 (2019-09-11T10:19:22.152Z)
982019-09-11 11:19:22.839 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162153 (2019-09-11T10:19:22.153Z)
992019-09-11 11:19:22.883 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162163 (2019-09-11T10:19:22.163Z)
1002019-09-11 11:19:22.918 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162165 (2019-09-11T10:19:22.165Z)
1012019-09-11 11:19:22.952 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162166 (2019-09-11T10:19:22.166Z)
1022019-09-11 11:19:22.987 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162166 (2019-09-11T10:19:22.166Z)
1032019-09-11 11:19:23.021 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162171 (2019-09-11T10:19:22.171Z)
1042019-09-11 11:19:23.055 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162171 (2019-09-11T10:19:22.171Z)
1052019-09-11 11:19:23.090 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162171 (2019-09-11T10:19:22.171Z)
1062019-09-11 11:19:23.133 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162171 (2019-09-11T10:19:22.171Z)
1072019-09-11 11:19:23.181 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162172 (2019-09-11T10:19:22.172Z)
1082019-09-11 11:19:23.229 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162172 (2019-09-11T10:19:22.172Z)
1092019-09-11 11:19:23.279 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162172 (2019-09-11T10:19:22.172Z)
1102019-09-11 11:19:23.320 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162172 (2019-09-11T10:19:22.172Z)
1112019-09-11 11:19:23.362 INFO 13672 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000026:000005a8:0001 commit_lsn at 1568197162172 (2019-09-11T10:19:22.172Z)
1122019-09-11 11:19:23.533 INFO 13672 --- [ main] o.s.s.concurrent.ThreadPoolTaskExecutor : Initializing ExecutorService 'applicationTaskExecutor'
1132019-09-11 11:19:24.352 INFO 13672 --- [ main] o.a.c.i.converter.DefaultTypeConverter : Type converters loaded (core: 195, classpath: 1)
1142019-09-11 11:19:26.666 INFO 13672 --- [ main] d.s.w.p.DocumentationPluginsBootstrapper : Context refreshed
1152019-09-11 11:19:26.715 INFO 13672 --- [ main] d.s.w.p.DocumentationPluginsBootstrapper : Found 1 custom documentation plugin(s)
1162019-09-11 11:19:26.761 INFO 13672 --- [ main] s.d.s.w.s.ApiListingReferenceScanner : Scanning for api listing references
1172019-09-11 11:19:26.930 INFO 13672 --- [ main] c.s.m.c.s.cdc.ChangeDataCaptureService : Application ready...
1182019-09-11 11:19:26.936 INFO 13672 --- [ main] o.a.camel.spring.boot.RoutesCollector : Loading additional Camel XML routes from: classpath:camel/*.xml
1192019-09-11 11:19:26.936 INFO 13672 --- [ main] o.a.camel.spring.boot.RoutesCollector : Loading additional Camel XML rests from: classpath:camel-rest/*.xml
1202019-09-11 11:19:26.953 INFO 13672 --- [ main] o.a.camel.spring.SpringCamelContext : Apache Camel 2.24.0 (CamelContext: camel-1) is starting
1212019-09-11 11:19:26.955 INFO 13672 --- [ main] o.a.c.m.ManagedManagementStrategy : JMX is enabled
1222019-09-11 11:19:27.162 INFO 13672 --- [ main] o.a.camel.spring.SpringCamelContext : StreamCaching is not in use. If using streams then its recommended to enable stream caching. See more details at http://camel.apache.org/stream-caching.html
1232019-09-11 11:19:27.166 INFO 13672 --- [ main] o.a.camel.spring.SpringCamelContext : Total 0 routes, of which 0 are started
1242019-09-11 11:19:27.168 INFO 13672 --- [ main] o.a.camel.spring.SpringCamelContext : Apache Camel 2.24.0 (CamelContext: camel-1) started in 0.214 seconds
1252019-09-11 11:19:27.244 INFO 13672 --- [ main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port(s): 9090 (http) with context path ''
1262019-09-11 11:19:27.299 INFO 13672 --- [ main] c.s.microservices.cdcconverter.web.Main : Started Main in 21.83 seconds (JVM running for 23.016)