· 6 years ago · Sep 11, 2019, 10:20 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:02:55.117 INFO 37340 --- [ main] c.s.microservices.cdcconverter.web.Main : Starting Main on igloo with PID 37340 (C:\dev\cdc-converter\web\build\classes\java\main started by penguin in C:\dev\cdc-converter)
102019-09-11 11:02:55.122 INFO 37340 --- [ main] c.s.microservices.cdcconverter.web.Main : The following profiles are active: local
112019-09-11 11:02:57.970 INFO 37340 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data repositories in DEFAULT mode.
122019-09-11 11:02:58.159 INFO 37340 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 165ms. Found 3 repository interfaces.
132019-09-11 11:03:00.175 INFO 37340 --- [ main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$a7883cc0] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
142019-09-11 11:03:00.308 INFO 37340 --- [ main] trationDelegate$BeanPostProcessorChecker : Bean 'org.apache.camel.spring.boot.CamelAutoConfiguration' of type [org.apache.camel.spring.boot.CamelAutoConfiguration$$EnhancerBySpringCGLIB$$c9b202df] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
152019-09-11 11:03:00.336 INFO 37340 --- [ main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.hateoas.config.HateoasConfiguration' of type [org.springframework.hateoas.config.HateoasConfiguration$$EnhancerBySpringCGLIB$$270889f2] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
162019-09-11 11:03:01.622 INFO 37340 --- [ main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port(s): 9090 (http)
172019-09-11 11:03:01.708 INFO 37340 --- [ main] o.apache.catalina.core.StandardService : Starting service [Tomcat]
182019-09-11 11:03:01.710 INFO 37340 --- [ main] org.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/9.0.22]
192019-09-11 11:03:02.265 INFO 37340 --- [ main] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring embedded WebApplicationContext
202019-09-11 11:03:02.266 INFO 37340 --- [ main] o.s.web.context.ContextLoader : Root WebApplicationContext: initialization completed in 7025 ms
212019-09-11 11:03:02.848 INFO 37340 --- [ main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Starting...
222019-09-11 11:03:03.862 INFO 37340 --- [ main] com.zaxxer.hikari.HikariDataSource : HikariPool-1 - Start completed.
232019-09-11 11:03:04.114 INFO 37340 --- [ main] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [
24 name: default
25 ...]
262019-09-11 11:03:04.259 INFO 37340 --- [ main] org.hibernate.Version : HHH000412: Hibernate Core {5.3.10.Final}
272019-09-11 11:03:04.262 INFO 37340 --- [ main] org.hibernate.cfg.Environment : HHH000206: hibernate.properties not found
282019-09-11 11:03:04.719 INFO 37340 --- [ main] o.hibernate.annotations.common.Version : HCANN000001: Hibernate Commons Annotations {5.0.4.Final}
292019-09-11 11:03:05.211 INFO 37340 --- [ main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL95Dialect
302019-09-11 11:03:05.286 INFO 37340 --- [ main] o.h.e.j.e.i.LobCreatorBuilderImpl : HHH000422: Disabling contextual LOB creation as connection was null
312019-09-11 11:03:05.297 INFO 37340 --- [ main] org.hibernate.type.BasicTypeRegistry : HHH000270: Type registration [java.util.UUID] overrides previous : org.hibernate.type.UUIDBinaryType@6055e899
322019-09-11 11:03:07.077 INFO 37340 --- [ main] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
332019-09-11 11:03:07.483 INFO 37340 --- [ main] o.s.a.r.c.CachingConnectionFactory : Attempting to connect to: [big-starfish.rmq.cloudamqp.com:5672]
342019-09-11 11:03:07.640 INFO 37340 --- [ main] o.s.a.r.c.CachingConnectionFactory : Created new connection: rabbitConnectionFactory#5d72da0b:0/SimpleConnection@3d5b2c1e [delegate=amqp://xpmhifty@35.176.231.189:5672/xpmhifty, localPort= 55725]
352019-09-11 11:03:08.603 INFO 37340 --- [ 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:03:08.607 INFO 37340 --- [ 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:03:08.744 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : Starting SqlServerConnectorTask with configuration:
462019-09-11 11:03:08.746 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : connector.class = io.debezium.connector.sqlserver.SqlServerConnector
472019-09-11 11:03:08.747 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : offset.flush.interval.ms = 10000
482019-09-11 11:03:08.747 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.user = sa
492019-09-11 11:03:08.747 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.dbname = testDB
502019-09-11 11:03:08.748 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : offset.storage = com.spacebarmedia.microservices.cdcconverter.services.cdc.PostgreSQLOffsetBackingStore
512019-09-11 11:03:08.748 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.hostname = 127.0.0.1
522019-09-11 11:03:08.749 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.password = ********
532019-09-11 11:03:08.750 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : name = sql-server-connector
542019-09-11 11:03:08.750 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.server.name = local
552019-09-11 11:03:08.751 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.port = 1433
562019-09-11 11:03:08.751 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : database.history = com.spacebarmedia.microservices.cdcconverter.services.cdc.PostgreSQLDatabaseHistory
572019-09-11 11:03:08.752 INFO 37340 --- [pool-3-thread-1] i.d.connector.common.BaseSourceTask : table.whitelist = dbo.customers,dbo.orders,dbo.products_on_hand
582019-09-11 11:03:08.758 INFO 37340 --- [ main] c.s.m.cdcconverter.common.AnotherThing : Name: cdc-converter
592019-09-11 11:03:08.758 INFO 37340 --- [ main] c.s.m.cdcconverter.common.AnotherThing : Version: 0.0.243-00e8359
602019-09-11 11:03:09.462 INFO 37340 --- [pool-3-thread-1] io.debezium.util.Threads : Requested thread factory for connector SqlServerConnector, id = local named = error-handler
612019-09-11 11:03:09.476 INFO 37340 --- [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:03:09.480 INFO 37340 --- [pool-3-thread-1] io.debezium.util.Threads : Creating thread debezium-sqlserverconnector-local-change-event-source-coordinator
632019-09-11 11:03:09.506 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : No previous offset has been found
642019-09-11 11:03:09.506 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : According to the connector configuration both schema and data will be snapshotted
652019-09-11 11:03:09.507 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 1 - Preparing
662019-09-11 11:03:09.508 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 2 - Determining captured tables
672019-09-11 11:03:09.605 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 3 - Locking captured tables
682019-09-11 11:03:09.608 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Executing schema locking
692019-09-11 11:03:09.609 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Locking table testDB.dbo.customers
702019-09-11 11:03:09.611 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Locking table testDB.dbo.orders
712019-09-11 11:03:09.612 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Locking table testDB.dbo.products_on_hand
722019-09-11 11:03:09.614 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 4 - Determining snapshot offset
732019-09-11 11:03:09.630 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 5 - Reading structure of captured tables
742019-09-11 11:03:09.631 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Reading structure of schema 'testDB'
752019-09-11 11:03:09.689 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 6 - Persisting schema history
762019-09-11 11:03:10.215 WARN 37340 --- [ 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:03:11.217 INFO 37340 --- [rce-coordinator] d.c.s.SqlServerSnapshotChangeEventSource : Schema locks released.
782019-09-11 11:03:11.217 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 7 - Snapshotting data
792019-09-11 11:03:11.219 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Exporting data from table 'testDB.dbo.customers'
802019-09-11 11:03:11.220 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : For table 'testDB.dbo.customers' using select statement: 'SELECT * FROM [dbo].[customers]'
812019-09-11 11:03:11.245 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Finished exporting 7 records for table 'testDB.dbo.customers'; total duration '00:00:00.023'
822019-09-11 11:03:11.246 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Exporting data from table 'testDB.dbo.orders'
832019-09-11 11:03:11.246 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : For table 'testDB.dbo.orders' using select statement: 'SELECT * FROM [dbo].[orders]'
842019-09-11 11:03:11.253 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Finished exporting 4 records for table 'testDB.dbo.orders'; total duration '00:00:00.007'
852019-09-11 11:03:11.253 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Exporting data from table 'testDB.dbo.products_on_hand'
862019-09-11 11:03:11.253 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : For table 'testDB.dbo.products_on_hand' using select statement: 'SELECT * FROM [dbo].[products_on_hand]'
872019-09-11 11:03:11.257 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Finished exporting 9 records for table 'testDB.dbo.products_on_hand'; total duration '00:00:00.004'
882019-09-11 11:03:11.261 INFO 37340 --- [rce-coordinator] rizedRelationalSnapshotChangeEventSource : Snapshot step 8 - Finalizing
892019-09-11 11:03:11.299 INFO 37340 --- [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:03:11.300 INFO 37340 --- [rce-coordinator] .c.s.SqlServerStreamingChangeEventSource : Last position recorded in offsets is 00000025:00000f20:0004(NULL)
912019-09-11 11:03:11.457 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191224 (2019-09-11T10:03:11.224Z)
922019-09-11 11:03:11.651 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191237 (2019-09-11T10:03:11.237Z)
932019-09-11 11:03:11.688 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191241 (2019-09-11T10:03:11.241Z)
942019-09-11 11:03:11.723 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191241 (2019-09-11T10:03:11.241Z)
952019-09-11 11:03:11.730 INFO 37340 --- [ 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)]
962019-09-11 11:03:11.760 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191242 (2019-09-11T10:03:11.242Z)
972019-09-11 11:03:11.802 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191242 (2019-09-11T10:03:11.242Z)
982019-09-11 11:03:11.841 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191242 (2019-09-11T10:03:11.242Z)
992019-09-11 11:03:11.940 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191250 (2019-09-11T10:03:11.250Z)
1002019-09-11 11:03:11.994 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191252 (2019-09-11T10:03:11.252Z)
1012019-09-11 11:03:12.037 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191252 (2019-09-11T10:03:11.252Z)
1022019-09-11 11:03:12.073 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191253 (2019-09-11T10:03:11.253Z)
1032019-09-11 11:03:12.113 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191256 (2019-09-11T10:03:11.256Z)
1042019-09-11 11:03:12.186 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191256 (2019-09-11T10:03:11.256Z)
1052019-09-11 11:03:12.282 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191256 (2019-09-11T10:03:11.256Z)
1062019-09-11 11:03:12.326 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191256 (2019-09-11T10:03:11.256Z)
1072019-09-11 11:03:12.361 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191257 (2019-09-11T10:03:11.257Z)
1082019-09-11 11:03:12.394 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191257 (2019-09-11T10:03:11.257Z)
1092019-09-11 11:03:12.429 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191257 (2019-09-11T10:03:11.257Z)
1102019-09-11 11:03:12.461 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191257 (2019-09-11T10:03:11.257Z)
1112019-09-11 11:03:12.500 INFO 37340 --- [pool-3-thread-1] c.s.m.c.s.cdc.ChangeDataCaptureService : CDC event published with 00000025:00000f20:0004 commit_lsn at 1568196191257 (2019-09-11T10:03:11.257Z)
1122019-09-11 11:03:12.582 INFO 37340 --- [ main] o.s.s.concurrent.ThreadPoolTaskExecutor : Initializing ExecutorService 'applicationTaskExecutor'
1132019-09-11 11:03:13.378 INFO 37340 --- [ main] o.a.c.i.converter.DefaultTypeConverter : Type converters loaded (core: 195, classpath: 1)
1142019-09-11 11:03:15.816 INFO 37340 --- [ main] d.s.w.p.DocumentationPluginsBootstrapper : Context refreshed
1152019-09-11 11:03:15.896 INFO 37340 --- [ main] d.s.w.p.DocumentationPluginsBootstrapper : Found 1 custom documentation plugin(s)
1162019-09-11 11:03:15.971 INFO 37340 --- [ main] s.d.s.w.s.ApiListingReferenceScanner : Scanning for api listing references
1172019-09-11 11:03:16.152 INFO 37340 --- [ main] c.s.m.c.s.cdc.ChangeDataCaptureService : Application ready...
1182019-09-11 11:03:16.157 INFO 37340 --- [ main] o.a.camel.spring.boot.RoutesCollector : Loading additional Camel XML routes from: classpath:camel/*.xml
1192019-09-11 11:03:16.158 INFO 37340 --- [ main] o.a.camel.spring.boot.RoutesCollector : Loading additional Camel XML rests from: classpath:camel-rest/*.xml
1202019-09-11 11:03:16.175 INFO 37340 --- [ main] o.a.camel.spring.SpringCamelContext : Apache Camel 2.24.0 (CamelContext: camel-1) is starting
1212019-09-11 11:03:16.178 INFO 37340 --- [ main] o.a.c.m.ManagedManagementStrategy : JMX is enabled
1222019-09-11 11:03:16.460 INFO 37340 --- [ 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:03:16.470 INFO 37340 --- [ main] o.a.camel.spring.SpringCamelContext : Total 0 routes, of which 0 are started
1242019-09-11 11:03:16.473 INFO 37340 --- [ main] o.a.camel.spring.SpringCamelContext : Apache Camel 2.24.0 (CamelContext: camel-1) started in 0.296 seconds
1252019-09-11 11:03:16.528 INFO 37340 --- [ main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port(s): 9090 (http) with context path ''
1262019-09-11 11:03:16.532 INFO 37340 --- [ main] c.s.microservices.cdcconverter.web.Main : Started Main in 22.612 seconds (JVM running for 23.539)