16:31:05,166 INFO [org.jumpmind.symmetric.util.SymmetricUtils] (MSC service thread 1-2) _____ __ _ ____ _____ / ___/ __ _____ __ ___ __ ___ _/ /_ ____(_)___ / __ | / ___/ \__ \ / / / / _ `_ \/ _ `_ \/ _ \/_ __// __/ / __/ / / / / \__ \ ___/ // /_/ / // // / // // / __// / / / / / /_ / /_/ / ___/ / /____/ \__ /_//_//_/_//_//_/\___/ \_/ /_/ /_/\__/ /_____/ /____/ /____/ +-----------------------------------------------------------------+ | Copyright (C) 2007-2020 JumpMind, Inc. | | | | Licensed under the GNU General Public License version 3. | | This software comes with ABSOLUTELY NO WARRANTY. | | See http://www.gnu.org/licenses/gpl.html | +-----------------------------------------------------------------+ 16:31:05,240 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) Initializing connection to database 16:31:05,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) Looking up datasource in jndi. The jndi name is java:/com.sigmaspa.symmetricds 16:31:05,640 INFO [org.jumpmind.symmetric.db.postgresql.PostgreSqlSymmetricDialect] (symmetric-engine-startup-1) The DbDialect being used is org.jumpmind.symmetric.db.postgresql.PostgreSqlSymmetricDialect 16:31:05,686 INFO [org.jumpmind.symmetric.service.impl.ExtensionService] (symmetric-engine-startup-1) Found 0 extension points from the database that will be registered 16:31:05,694 INFO [org.jumpmind.symmetric.io.stage.StagingManager] (symmetric-engine-startup-1) The staging directory was initialized at the following location: C:\Users\MDIRUS~1\AppData\Local\Temp\\sgmserver-1 16:31:06,238 INFO [org.jumpmind.symmetric.service.impl.ExtensionService] (symmetric-engine-startup-1) Found 0 extension points from the database that will be registered 16:31:06,240 INFO [org.jumpmind.symmetric.service.impl.ClientExtensionService] (symmetric-engine-startup-1) Found 10 extension points from spring that will be registered 16:31:06,240 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-NODEGEN--### setSymmetricEngine nodegen-sequence=nodeidgen lowrange-size=1000000000000 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-NODEGEN--...extension-point=class org.jumpmind.symmetric.util.DefaultNodeIdCreator 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-NODEGEN--...extension-point DefaultNodeIdCreator found : remove it! 16:31:06,241 INFO [org.jumpmind.symmetric.service.impl.ExtensionService] (symmetric-engine-startup-1) Registering extension named 'nodeidgen' of type 'INodeIdCreator' 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-RELOADGEN--### setSymmetricEngine on reload-Generator 16:31:06,241 INFO [org.jumpmind.symmetric.service.impl.ExtensionService] (symmetric-engine-startup-1) Registering extension named 'reloadgen' of type 'IDatabaseWriterFilter' 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...setSymmetricEngine IN 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...extension-rev=3.11.5 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...getDatabaseVersion-IN 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...getAllParameters done 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...connection type : jndi 16:31:06,241 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...jndy-name...=java:/com.sigmaspa.symmetricds 16:31:06,246 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...getDatabaseVersion-OUT,REV=4.15 16:31:06,246 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...setSymmetricEngine set node schema version on local props 16:31:06,246 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : setNodeSchemaVersion-IN 16:31:06,275 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ......curr-node=sgmserver:1:1 16:31:06,275 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ......node-id........=1 16:31:06,275 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ......node-group-id..=sgmserver 16:31:06,275 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ......node-external-id....=1 16:31:06,275 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ......node-schema-version.=4.15 16:31:06,275 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...writing db-rev on property instance... 16:31:06,333 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...writing db-rev on curr node schema_version... 16:31:06,337 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : setNodeSchemaVersion-OUT 16:31:06,338 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...setSymmetricEngine OUT-db-rev=4.15 16:31:06,338 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SGM-DB-REV : ...curr-node-group-id=sgmserver 16:31:06,338 INFO [org.jumpmind.symmetric.service.impl.ExtensionService] (symmetric-engine-startup-1) Registering extension named 'setschemaversion' of type 'IParameterFilter' 16:31:06,348 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) Initializing SymmetricDS database 16:31:06,349 INFO [org.jumpmind.symmetric.db.postgresql.PostgreSqlSymmetricDialect] (symmetric-engine-startup-1) Checking if SymmetricDS tables need created or altered 16:31:07,667 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) Done initializing SymmetricDS database 16:31:07,667 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SymmetricDS database version : 3.11.5 16:31:07,667 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SymmetricDS software version : 3.11.5 16:31:07,689 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) Starting registered node [group=sgmserver, id=1, nodeId=1] 16:31:07,691 INFO [org.jumpmind.symmetric.service.impl.ClusterService] (symmetric-engine-startup-1) This node picked a server id of NBMDIRUSCIO2 16:31:07,692 INFO [org.jumpmind.symmetric.service.impl.TriggerRouterService] (symmetric-engine-startup-1) Synchronizing triggers 16:31:10,282 INFO [org.jumpmind.symmetric.service.impl.TriggerRouterService] (symmetric-engine-startup-1) Done synchronizing triggers 16:31:10,293 INFO [org.jumpmind.symmetric.job.RouterJob] (symmetric-engine-startup-1) Starting Routing on periodic schedule: every 5000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,295 INFO [org.jumpmind.symmetric.job.PushJob] (symmetric-engine-startup-1) Starting Push on periodic schedule: every 10000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,295 INFO [org.jumpmind.symmetric.job.PullJob] (symmetric-engine-startup-1) Starting Pull on periodic schedule: every 10000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,295 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job Offline Push not configured for auto start 16:31:10,295 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job Offline Pull not configured for auto start 16:31:10,297 INFO [org.jumpmind.symmetric.job.OutgoingPurgeJob] (symmetric-engine-startup-1) Starting job 'Purge Outgoing' with cron expression: '0 0 */4 * * *' 16:31:10,297 INFO [org.jumpmind.symmetric.job.IncomingPurgeJob] (symmetric-engine-startup-1) Starting job 'Purge Incoming' with cron expression: '0 0 */4 * * *' 16:31:10,298 INFO [org.jumpmind.symmetric.job.StatisticFlushJob] (symmetric-engine-startup-1) Starting job 'Stat Flush' with cron expression: '0 0/5 * * * *' 16:31:10,298 INFO [org.jumpmind.symmetric.job.SyncTriggersJob] (symmetric-engine-startup-1) Starting job 'SyncTriggers' with cron expression: '0 0 0 * * *' 16:31:10,298 INFO [org.jumpmind.symmetric.job.HeartbeatJob] (symmetric-engine-startup-1) Starting Heartbeat on periodic schedule: every 900000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,299 INFO [org.jumpmind.symmetric.job.WatchdogJob] (symmetric-engine-startup-1) Starting Watchdog on periodic schedule: every 3600000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,299 INFO [org.jumpmind.symmetric.job.StageManagementJob] (symmetric-engine-startup-1) Starting job 'Stage Management' with cron expression: '0 0 * * * *' 16:31:10,299 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job Refresh Cache not configured for auto start 16:31:10,299 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job File Sync Tracker not configured for auto start 16:31:10,299 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job File Sync Pull not configured for auto start 16:31:10,299 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job File Sync Push not configured for auto start 16:31:10,299 INFO [org.jumpmind.symmetric.job.InitialLoadExtractorJob] (symmetric-engine-startup-1) Starting Initial Load Extract on periodic schedule: every 10000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,300 INFO [org.jumpmind.symmetric.job.MonitorJob] (symmetric-engine-startup-1) Starting Monitor on periodic schedule: every 60000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,300 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job Report Status not configured for auto start 16:31:10,300 INFO [org.jumpmind.symmetric.job.JobManager] (symmetric-engine-startup-1) Job Log Miner not configured for auto start 16:31:10,300 INFO [org.jumpmind.symmetric.job.InitialLoadJob] (symmetric-engine-startup-1) Starting Initial Load Queue on periodic schedule: every 10000ms with the first run at Wed Mar 04 16:31:11 CET 2020 16:31:10,302 INFO [org.jumpmind.symmetric.AbstractSymmetricEngine] (symmetric-engine-startup-1) SymmetricDS Node STARTED: nodeId=1 groupId=sgmserver type=server subType=null name=sgmserver-1 softwareVersion=3.11.5 databaseName=PostgreSQL databaseVersion=9.5 driverName=PostgreSQL JDBC Driver driverVersion=42.2.10 uptime=0 sec. 16:31:11,321 INFO [org.jumpmind.symmetric.route.DataGapFastDetector] (sgmserver-1-job-1) Full gap analysis is running 16:31:11,335 INFO [org.jumpmind.symmetric.route.DataGapFastDetector] (sgmserver-1-job-1) Querying data in gaps from database took 13 ms 16:31:11,336 INFO [org.jumpmind.symmetric.route.DataGapFastDetector] (sgmserver-1-job-1) Full gap analysis is done after 14 ms 16:31:11,340 ERROR [org.jumpmind.symmetric.job.RouterJob] (sgmserver-1-job-1) Exception while executing job 'Routing': org.jumpmind.db.sql.SqlException: Failed to execute sql: select distinct channel_id from sym_data where ((data_id between ? and ?)) at org.jumpmind.db.sql.AbstractSqlTemplate.translate(AbstractSqlTemplate.java:305) [symmetric-db-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.JdbcSqlReadCursor.(JdbcSqlReadCursor.java:123) [symmetric-jdbc-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.JdbcSqlTemplate.queryForCursor(JdbcSqlTemplate.java:156) [symmetric-jdbc-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.JdbcSqlTemplate.queryForCursor(JdbcSqlTemplate.java:144) [symmetric-jdbc-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.AbstractSqlTemplate.queryForCursor(AbstractSqlTemplate.java:113) [symmetric-db-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.AbstractSqlTemplate.query(AbstractSqlTemplate.java:202) [symmetric-db-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.AbstractSqlTemplate.query(AbstractSqlTemplate.java:198) [symmetric-db-3.11.5.jar:3.11.5] at org.jumpmind.db.sql.AbstractSqlTemplate.query(AbstractSqlTemplate.java:188) [symmetric-db-3.11.5.jar:3.11.5] at org.jumpmind.symmetric.service.impl.RouterService.getReadyChannels(RouterService.java:330) [symmetric-core-3.11.5.jar:3.11.5] at org.jumpmind.symmetric.service.impl.RouterService.routeDataForEachChannel(RouterService.java:272) [symmetric-core-3.11.5.jar:3.11.5] at org.jumpmind.symmetric.service.impl.RouterService.routeData(RouterService.java:217) [symmetric-core-3.11.5.jar:3.11.5] at org.jumpmind.symmetric.job.RouterJob.doJob(RouterJob.java:49) [symmetric-client-3.11.5.jar:3.11.5] at org.jumpmind.symmetric.job.AbstractJob.invoke(AbstractJob.java:227) [symmetric-client-3.11.5.jar:3.11.5] at org.jumpmind.symmetric.job.AbstractJob.run(AbstractJob.java:298) [symmetric-client-3.11.5.jar:3.11.5] at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) [spring-context-5.1.7.RELEASE.jar:5.1.7.RELEASE] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_211] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [rt.jar:1.8.0_211] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [rt.jar:1.8.0_211] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [rt.jar:1.8.0_211] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [rt.jar:1.8.0_211] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [rt.jar:1.8.0_211] at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_211] Caused by: org.postgresql.util.PSQLException: Cannot change transaction isolation level in the middle of a transaction. at org.postgresql.jdbc.PgConnection.setTransactionIsolation(PgConnection.java:920) [postgresql-42.2.10.jar:42.2.10] at org.jboss.jca.adapters.jdbc.BaseWrapperManagedConnection.setJdbcTransactionIsolation(BaseWrapperManagedConnection.java:1052) at org.jboss.jca.adapters.jdbc.WrappedConnection.setTransactionIsolation(WrappedConnection.java:906) at org.jumpmind.db.sql.JdbcSqlReadCursor.(JdbcSqlReadCursor.java:84) [symmetric-jdbc-3.11.5.jar:3.11.5] ... 20 more 16:31:11,433 INFO [org.jumpmind.symmetric.service.impl.NodeCommunicationService] (sgmserver-1-job-3) pull will use 10 threads