13:33:04,694 INFO  [org.apache.activemq.artemis.core.server] #*#*# Starting test: testFailBack()...
#test testFailBack
13:33:04,704 INFO  [org.apache.activemq.artemis.core.server] AMQ221000: live Message Broker is starting with configuration Broker Configuration (clustered=true,journalDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/journal0-L,bindingsDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/bindings0-L,largeMessagesDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/large-msg0-L,pagingDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/page0-L)
13:33:04,711 INFO  [org.apache.activemq.artemis.core.server] AMQ221013: Using NIO Journal
13:33:04,713 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-server]. Adding protocol support for: CORE
13:33:04,715 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-amqp-protocol]. Adding protocol support for: AMQP
13:33:04,717 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-stomp-protocol]. Adding protocol support for: STOMP
13:33:04,718 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-openwire-protocol]. Adding protocol support for: OPENWIRE
13:33:04,720 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-hornetq-protocol]. Adding protocol support for: HORNETQ
13:33:04,722 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-mqtt-protocol]. Adding protocol support for: MQTT
13:33:04,880 INFO  [org.apache.activemq.artemis.core.server] AMQ221011: Server BackupSyncPagingTest/liveServer is now live
13:33:04,885 INFO  [org.apache.activemq.artemis.core.server] AMQ221001: Apache ActiveMQ Artemis Message Broker version 1.1.0 [nodeID=a02574a0-7299-11e5-9a75-e341c557f49c] BackupSyncPagingTest/liveServer
13:33:04,959 WARN  [org.apache.activemq.artemis.core.server] AMQ222038: Starting paging on address 'FailoverTestAddress'; size is currently: 3,952 bytes; max-size-bytes: 2,048
13:33:07,322 INFO  [org.apache.activemq.artemis.core.server] AMQ221000: backup Message Broker is starting with configuration Broker Configuration (clustered=true,journalDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/journal0-B,bindingsDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/bindings0-B,largeMessagesDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/large-msg0-B,pagingDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/page0-B)
13:33:07,352 INFO  [org.apache.activemq.artemis.core.server] AMQ221013: Using NIO Journal
13:33:07,353 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-server]. Adding protocol support for: CORE
13:33:07,354 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-amqp-protocol]. Adding protocol support for: AMQP
13:33:07,356 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-stomp-protocol]. Adding protocol support for: STOMP
13:33:07,357 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-openwire-protocol]. Adding protocol support for: OPENWIRE
13:33:07,358 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-hornetq-protocol]. Adding protocol support for: HORNETQ
13:33:07,359 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-mqtt-protocol]. Adding protocol support for: MQTT
13:33:07,361 INFO  [org.apache.activemq.artemis.core.server] AMQ221109: Apache ActiveMQ Artemis Backup Server version 1.1.0 [a02574a0-7299-11e5-9a75-e341c557f49c] started, waiting live to fail before it gets active
13:33:07,642 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-data-4.amq id = 1, recordID = 1) (size=102,400) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\journal0-L\activemq-data-4.amq
13:33:07,676 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-data-2.amq id = 2, recordID = 2) (size=102,400) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\journal0-L\activemq-data-2.amq
13:33:07,679 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-bindings-3.bindings id = 1, recordID = 1) (size=1,048,576) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\bindings0-L\activemq-bindings-3.bindings
13:33:07,683 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-bindings-2.bindings id = 2, recordID = 2) (size=1,048,576) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\bindings0-L\activemq-bindings-2.bindings
13:33:08,496 INFO  [org.apache.activemq.artemis.core.server] AMQ221024: Backup server ActiveMQServerImpl::BackupSyncPagingTest/backupServers is synchronized with live-server.
13:33:08,511 INFO  [org.apache.activemq.artemis.core.server] AMQ221031: backup announced
13:33:08,557 ERROR [org.apache.activemq.artemis.core.client] AMQ214017: Caught unexpected Throwable: java.lang.IllegalStateException: Server locator is closed (maybe it was garbage collected)
	at org.apache.activemq.artemis.core.client.impl.ServerLocatorImpl.assertOpen(ServerLocatorImpl.java:1759) [artemis-core-client-1.1.0.jar:1.1.0]
	at org.apache.activemq.artemis.core.client.impl.ServerLocatorImpl.createSessionFactory(ServerLocatorImpl.java:767) [artemis-core-client-1.1.0.jar:1.1.0]
	at org.apache.activemq.artemis.core.client.impl.ServerLocatorImpl.connect(ServerLocatorImpl.java:657) [artemis-core-client-1.1.0.jar:1.1.0]
	at org.apache.activemq.artemis.core.client.impl.ServerLocatorImpl.connect(ServerLocatorImpl.java:638) [artemis-core-client-1.1.0.jar:1.1.0]
	at org.apache.activemq.artemis.core.server.cluster.ClusterController$ConnectRunnable.run(ClusterController.java:405) [artemis-server-1.1.0.jar:1.1.0]
	at org.apache.activemq.artemis.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:94) [artemis-core-client-1.1.0.jar:1.1.0]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_60]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_60]
	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_60]

13:33:08,557 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:08,557 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:08,557 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:08,582 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:08,583 INFO  [org.apache.activemq.artemis.core.server] AMQ221002: Apache ActiveMQ Artemis Message Broker version identity=BackupSyncPagingTest/liveServer,version=1.1.0 [a02574a0-7299-11e5-9a75-e341c557f49c] stopped
13:33:08,584 INFO  [org.apache.activemq.artemis.core.server] AMQ221037: ActiveMQServerImpl::BackupSyncPagingTest/backupServers to become 'live'
13:33:08,638 WARN  [org.apache.activemq.artemis.core.client] AMQ212004: Failed to connect to server.
13:33:08,767 INFO  [org.apache.activemq.artemis.core.server] AMQ221011: Server BackupSyncPagingTest/backupServers is now live
13:33:11,131 ERROR [org.apache.activemq.artemis.journal] AMQ144000: Failed to delete file NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-B\a215d300-7299-11e5-9a75-e341c557f49c\000000020.page
13:33:15,669 INFO  [org.apache.activemq.artemis.core.server] AMQ221000: live Message Broker is starting with configuration Broker Configuration (clustered=true,journalDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/journal0-L,bindingsDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/bindings0-L,largeMessagesDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/large-msg0-L,pagingDirectory=W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/page0-L)
13:33:15,684 WARN  [org.apache.activemq.artemis.core.server] AMQ222162: Moving data directory W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/bindings0-L to W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\bindings0-L1
13:33:15,687 WARN  [org.apache.activemq.artemis.core.server] AMQ222162: Moving data directory W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/journal0-L to W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\journal0-L1
13:33:15,691 WARN  [org.apache.activemq.artemis.core.server] AMQ222162: Moving data directory W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/page0-L to W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-L1
13:33:15,695 WARN  [org.apache.activemq.artemis.core.server] AMQ222162: Moving data directory W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168/large-msg0-L to W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\large-msg0-L1
13:33:15,700 INFO  [org.apache.activemq.artemis.core.server] AMQ221013: Using NIO Journal
13:33:15,702 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-server]. Adding protocol support for: CORE
13:33:15,703 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-amqp-protocol]. Adding protocol support for: AMQP
13:33:15,704 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-stomp-protocol]. Adding protocol support for: STOMP
13:33:15,705 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-openwire-protocol]. Adding protocol support for: OPENWIRE
13:33:15,706 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-hornetq-protocol]. Adding protocol support for: HORNETQ
13:33:15,708 INFO  [org.apache.activemq.artemis.core.server] AMQ221043: Protocol module found: [artemis-mqtt-protocol]. Adding protocol support for: MQTT
13:33:15,709 INFO  [org.apache.activemq.artemis.core.server] AMQ221109: Apache ActiveMQ Artemis Backup Server version 1.1.0 [a02574a0-7299-11e5-9a75-e341c557f49c] started, waiting live to fail before it gets active
13:33:15,942 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-data-4.amq id = 1, recordID = 1) (size=102,400) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\journal0-B\activemq-data-4.amq
13:33:15,986 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-data-2.amq id = 8, recordID = 8) (size=102,400) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\journal0-B\activemq-data-2.amq
13:33:15,989 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-bindings-3.bindings id = 1, recordID = 1) (size=1,048,576) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\bindings0-B\activemq-bindings-3.bindings
13:33:15,994 INFO  [org.apache.activemq.artemis.core.server] AMQ221025: Replication: sending JournalFileImpl: (activemq-bindings-2.bindings id = 8, recordID = 8) (size=1,048,576) to backup. NIOSequentialFile W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\bindings0-B\activemq-bindings-2.bindings
13:33:17,178 INFO  [org.apache.activemq.artemis.core.server] AMQ221024: Backup server ActiveMQServerImpl::BackupSyncPagingTest/liveServer is synchronized with live-server.
13:33:17,192 INFO  [org.apache.activemq.artemis.core.server] AMQ221031: backup announced
13:33:21,006 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:21,023 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:21,027 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:21,047 WARN  [org.apache.activemq.artemis.core.client] AMQ212037: Connection failure has been detected: AMQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
13:33:21,049 INFO  [org.apache.activemq.artemis.core.server] AMQ221002: Apache ActiveMQ Artemis Message Broker version identity=BackupSyncPagingTest/backupServers,version=1.1.0 [a02574a0-7299-11e5-9a75-e341c557f49c] stopped
13:33:21,051 WARN  [org.apache.activemq.artemis.core.server] AMQ222163: Server is being completely stopped, since this was a replicated backup there may be journal files that need cleaning up. The Apache ActiveMQ Artemis broker will have to be manually restarted.
13:33:21,052 INFO  [org.apache.activemq.artemis.core.server] AMQ221037: ActiveMQServerImpl::BackupSyncPagingTest/liveServer to become 'live'
13:33:21,070 WARN  [org.apache.activemq.artemis.core.client] AMQ212004: Failed to connect to server.
13:33:21,197 INFO  [org.apache.activemq.artemis.core.server] AMQ221011: Server BackupSyncPagingTest/liveServer is now live
#test tearDown
13:33:21,227 WARN  [org.apache.activemq.artemis.core.client] AMQ212004: Failed to connect to server.
13:33:21,251 INFO  [org.apache.activemq.artemis.core.server] AMQ221002: Apache ActiveMQ Artemis Message Broker version identity=BackupSyncPagingTest/liveServer,version=1.1.0 [a02574a0-7299-11e5-9a75-e341c557f49c] stopped
13:33:21,408 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-B\a215d300-7299-11e5-9a75-e341c557f49c\000000020.page
13:33:21,409 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-B\a215d300-7299-11e5-9a75-e341c557f49c
13:33:21,412 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-B
13:33:21,414 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-L\a7073c60-7299-11e5-9a75-e341c557f49c\000000080.page
13:33:21,416 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-L\a7073c60-7299-11e5-9a75-e341c557f49c
13:33:21,418 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168\page0-L
13:33:21,421 WARN  [org.apache.activemq.artemis.core.server] Failed to clean up file: W:\workspace\artemis-project-testsuite-win\7328b0fd\tests\integration-tests\.\target\tmp\junit7794766437966361168
13:33:21,423 INFO  [org.apache.activemq.artemis.core.server] #*#*# Finished test: testFailBack()...

java.lang.AssertionError: expected=0. Got: property['counter']=97 sendNumber=97 expected:<0> but was:<97>
	at org.junit.Assert.fail(Assert.java:88)
	at org.junit.Assert.failNotEquals(Assert.java:743)
	at org.junit.Assert.assertEquals(Assert.java:118)
	at org.junit.Assert.assertEquals(Assert.java:555)
	at org.apache.activemq.artemis.tests.util.ActiveMQTestBase.receiveMessages(ActiveMQTestBase.java:1584)
	at org.apache.activemq.artemis.tests.integration.cluster.failover.BackupSyncJournalTest.receiveMsgsInRange(BackupSyncJournalTest.java:333)
	at org.apache.activemq.artemis.tests.integration.cluster.failover.BackupSyncJournalTest.testFailBack(BackupSyncJournalTest.java:298)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:47)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:44)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:48)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:55)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:271)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:70)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:238)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:63)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:236)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:53)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:229)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:309)
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:283)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:173)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:128)
	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:203)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:155)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)