[Logo] Terracotta Discussion Forums (LEGACY READ-ONLY ARCHIVE)
  [Search] Search   [Recent Topics] Recent Topics   [Members]  Member Listing   [Groups] Back to home page 
[Register] Register / 
[Login] Login 
[Expert]
Client Cannot Reconnect ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd) not foun  XML
Forum Index -> Terracotta 中文社区
Author Message
zhuxinping

journeyman

Joined: 11/02/2009 19:15:41
Messages: 11
Offline

terracotta 服务器与客户端连接失败问题:

服务器日志:

2010-09-08 16:29:36,769 [main] INFO com.terracottatech.general - New logging session started.
2010-09-08 16:29:36,791 [main] INFO com.terracottatech.console - Terracotta 3.2.1, as of 20100514-160554 (Revision 15117 by cruise@su10mo5 from 3.2.1_2)
2010-09-08 16:29:36,869 [main] INFO com.tc.config.schema.setup.StandardXMLFileConfigurationCreator - Attempting to load configuration from the file at '/usr/local/terracotta/terracotta-3.2.1_2/bin/../tc-config.xml'...
2010-09-08 16:29:36,875 [main] INFO com.tc.config.schema.setup.StandardXMLFileConfigurationCreator - Successfully loaded configuration from the file at '/usr/local/terracotta/terracotta-3.2.1_2/bin/../tc-config.xml'. Config is:

<?xml version="1.0" encoding="UTF-8"?>
<!--

All content copyright Terracotta, Inc., unless otherwise indicated. All rights reserved.



-->
<!-- This is a Terracotta configuration file that has been pre-configured
for use with Tomcat.

For more information, please see the product documentation.
-->
<tc:tc-config xmlns:tc="http://www.terracotta.org/config"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="http://www.terracotta.org/schema/terracotta-5.xsd">

<!-- Tell DSO where the Terracotta server can be found -->
<servers>
<server host="192.168.1.128" name="192.168.1.128">
<data>terracotta/server-data</data>
<logs>terracotta/server-logs</logs>
<statistics>terracotta/cluster-statistics</statistics>
</server>
</servers>

<tc-properties>
<property name="l2.l1reconnect.enabled" value="true"/>
<property name="l2.l1reconnect.timeout.millis" value="3000"/>
</tc-properties>

<!-- Tell DSO where to put the generated client logs -->
<clients>
<logs>terracotta/client-logs</logs>
<modules>
<!-- Include the Terracotta Integration Module for the appropriate version of
your sessions container. You can view the list of supported TIMs with the
tim-get tool in the bin directory:
bin/tim-get.sh list
The TIM version will vary depending on the Terracotta release. To get
the correct version, use the tim-get tool in the bin directory to download the
latest appropriate version with a command like:
bin/tim-get.sh install tim-tomcat-6.0
-->

<module name="tim-ehcache-2.0"/>
<!--

<module name="tim-tomcat-5.5"/>

<module name="tim-quartz-1.7"/>
-->
</modules>
</clients>

<application>
<dso>



<instrumented-classes>

<include>
<class-expression>*..*</class-expression>
</include>


<exclude>org.jivesoftware.stringprep.DecompositionKeys</exclude>


</instrumented-classes>




<roots>
<root>
<field-name>com.scommnet.gumserver.util.cluster.ClusterFactory.localMUVRoom</field-name>
</root>



</roots>

<locks>

<!--
<autolock auto-synchronized="true">
<method-expression>* com.scommnet.gumserver.plugins.puborganize.database.manager.StarServerImpl.*(..)</method-expression>
<lock-level>write</lock-level>
</autolock>
-->

<named-lock>
<lock-name>lockOne</lock-name>
<method-expression>* com.scommnet.gumserver.util.cluster.ClusterFactory.setLocalMUVRoom(..)</method-expression>
<lock-level>write</lock-level>
</named-lock>


</locks>
</dso>
</application>


</tc:tc-config>

2010-09-08 16:29:37,168 [main] INFO com.terracottatech.console - Configuration loaded from the file at '/usr/local/terracotta/terracotta-3.2.1_2/bin/../tc-config.xml'.
2010-09-08 16:29:37,171 [main] INFO com.tc.properties.TCProperties - Loading default properties from tc.properties
2010-09-08 16:29:37,171 [main] INFO com.tc.properties.TCProperties - The property "tc.config.getFromSource.timeout" was read before initialization completed. "tc.config.getFromSource.timeout" = 30000
2010-09-08 16:29:37,171 [main] INFO com.tc.properties.TCProperties - The property "l2.l1reconnect.enabled" was overridden to true from false by the tc-config file
2010-09-08 16:29:37,172 [main] INFO com.tc.properties.TCProperties - The property "l2.l1reconnect.timeout.millis" was overridden to 3000 from 5000 by the tc-config file
2010-09-08 16:29:37,177 [main] INFO com.tc.properties.TCProperties - Loaded TCProperties : TCProperties = { cvt.buffer.randomsuffix.enabled = false, cvt.client.fail.buffer.open = false, cvt.rest.interface.enabled = true, cvt.retriever.notification.interval = 60, cvt.statistics.logging.interval = 900, cvt.store.randomsuffix.enabled = false, ehcache.clusterAllCacheManagers = true, ehcache.concurrency = 128, ehcache.evictor.logging.enabled = false, ehcache.evictor.pool.size = 1, ehcache.global.eviction.enable = true, ehcache.global.eviction.frequency = 10, ehcache.global.eviction.rest.timeMillis = 10, ehcache.global.eviction.segments = 2, ehcache.lock.readLevel = READ, ehcache.lock.writeLevel = WRITE, ehcache.logging.enabled = false, http.defaultservlet.attribute.aliases = false;, http.defaultservlet.attribute.dirallowed = false;, http.defaultservlet.enabled = false;, instrumentation.finalField.fastRead = true, l1.cachemanager.criticalObjectThreshold = -1, l1.cachemanager.criticalThreshold = 90, l1.cachemanager.enabled = true, l1.cachemanager.leastCount = 2, l1.cachemanager.logging.enabled = false, l1.cachemanager.monitorOldGenOnly = true, l1.cachemanager.percentageToEvict = 10, l1.cachemanager.sleepInterval = 3000, l1.cachemanager.threshold = 70, l1.connect.versionMatchCheck.enabled = true, l1.healthcheck.l2.bindAddress = 0.0.0.0, l1.healthcheck.l2.bindPort = 0, l1.healthcheck.l2.ping.enabled = true, l1.healthcheck.l2.ping.idletime = 5000, l1.healthcheck.l2.ping.interval = 1000, l1.healthcheck.l2.ping.probes = 3, l1.healthcheck.l2.socketConnect = true, l1.healthcheck.l2.socketConnectCount = 13, l1.healthcheck.l2.socketConnectTimeout = 5, l1.jvm.check.compatibility = true, l1.l2.config.validation.enabled = true, l1.lock.statistics.gatherInterval = 1, l1.lock.statistics.traceDepth = 0, l1.lockmanager.striped.count = 128, l1.lockmanager.timeout.interval = 60000, l1.max.connect.retries = -1, l1.modules.additional = , l1.modules.default = org.terracotta.modules.excludes-config;bundle-version:=3.2.1,org.terracotta.modules.guimodels-config;bundle-version:=3.2.1,org.terracotta.modules.jdk15-preinst-config;bundle-version:=3.2.1,org.terracotta.modules.standard-config;bundle-version:=3.2.1, l1.modules.repositories = , l1.modules.tc-version-check = , l1.objectmanager.fault.logging.enabled = false, l1.objectmanager.flush.logging.enabled = false, l1.objectmanager.objectid.request.size = 50000, l1.objectmanager.remote.batchLookupTimePeriod = 1, l1.objectmanager.remote.logging.enabled = false, l1.objectmanager.remote.maxDNALRUSize = 60, l1.objectmanager.remote.maxRequestSentImmediately = 4, l1.seda.stage.sink.capacity = -1, l1.serverarray.objectCreationStrategy = round-robin, l1.serverarray.objectCreationStrategy.groupAffinity.groupName = mirror-group1, l1.serverarray.objectCreationStrategy.roundRobin.startIndex = sequential, l1.socket.connect.timeout = 10000, l1.socket.reconnect.waitInterval = 1000, l1.transactionmanager.completedAckFlushTimeout = 5000, l1.transactionmanager.folding.debug = false, l1.transactionmanager.folding.enabled = true, l1.transactionmanager.folding.lock.limit = 0, l1.transactionmanager.folding.object.limit = 0, l1.transactionmanager.logging.enabled = false, l1.transactionmanager.maxBatchSizeInKiloBytes = 128, l1.transactionmanager.maxOutstandingBatchSize = 4, l1.transactionmanager.maxPendingBatches = 88, l1.transactionmanager.maxSleepTimeBeforeHalt = 1024, l1.transactionmanager.strings.compress.enabled = true, l1.transactionmanager.strings.compress.logging.enabled = false, l1.transactionmanager.strings.compress.minSize = 512, l1.transactionmanager.timeoutForAckOnExit = 300, l2.beanshell.enabled = false, l2.beanshell.port = 9929, l2.berkeleydb.je.checkpointer.bytesInterval = 100000000, l2.berkeleydb.je.cleaner.bytesInterval = 20000000, l2.berkeleydb.je.cleaner.detailMaxMemoryPercentage = 5, l2.berkeleydb.je.cleaner.lookAheadCacheSize = 32768, l2.berkeleydb.je.cleaner.minAge = 5, l2.berkeleydb.je.lock.nLockTables = 7, l2.berkeleydb.je.lock.timeout = 180000000, l2.berkeleydb.je.maxMemoryPercent = 25, l2.cachemanager.criticalObjectThreshold = -1, l2.cachemanager.criticalThreshold = 90, l2.cachemanager.enabled = true, l2.cachemanager.leastCount = 2, l2.cachemanager.logging.enabled = false, l2.cachemanager.monitorOldGenOnly = true, l2.cachemanager.percentageToEvict = 10, l2.cachemanager.sleepInterval = 3000, l2.cachemanager.threshold = 70, l2.data.backup.throttle.timeInMillis = 0, l2.healthcheck.l1.ping.enabled = true, l2.healthcheck.l1.ping.idletime = 5000, l2.healthcheck.l1.ping.interval = 1000, l2.healthcheck.l1.ping.probes = 3, l2.healthcheck.l1.socketConnect = true, l2.healthcheck.l1.socketConnectCount = 10, l2.healthcheck.l1.socketConnectTimeout = 5, l2.healthcheck.l2.ping.enabled = true, l2.healthcheck.l2.ping.idletime = 5000, l2.healthcheck.l2.ping.interval = 1000, l2.healthcheck.l2.ping.probes = 3, l2.healthcheck.l2.socketConnect = true, l2.healthcheck.l2.socketConnectCount = 10, l2.healthcheck.l2.socketConnectTimeout = 5, l2.l1reconnect.enabled = true, l2.l1reconnect.maxDelayedAcks = 16, l2.l1reconnect.sendWindow = 32, l2.l1reconnect.sendqueue.cap = 5000, l2.l1reconnect.timeout.millis = 3000, l2.lfu.agingFactor = 1, l2.lfu.debug.enabled = false, l2.lfu.recentlyAccessedIgnorePercentage = 20, l2.lockmanager.greedy.lease.enabled = true, l2.lockmanager.greedy.lease.leaseTimeInMillis = 50, l2.lockmanager.greedy.locks.enabled = true, l2.nha.autoRestart = true, l2.nha.dirtydb.autoDelete = true, l2.nha.dirtydb.rolling = 0, l2.nha.send.timeout.millis = 16000, l2.nha.tcgroupcomm.discovery.interval = 1000, l2.nha.tcgroupcomm.handshake.timeout = 5000, l2.nha.tcgroupcomm.reconnect.enabled = false, l2.nha.tcgroupcomm.reconnect.maxDelayedAcks = 16, l2.nha.tcgroupcomm.reconnect.sendWindow = 32, l2.nha.tcgroupcomm.reconnect.sendqueue.cap = 5000, l2.nha.tcgroupcomm.reconnect.timeout = 5000, l2.objectmanager.cachePolicy = lfu, l2.objectmanager.deleteBatchSize = 5000, l2.objectmanager.dgc.faulting.optimization = true, l2.objectmanager.dgc.throttle.requestsPerThrottle = 1000, l2.objectmanager.dgc.throttle.timeInMillis = 0, l2.objectmanager.dgc.young.enabled = false, l2.objectmanager.dgc.young.frequencyInMillis = 180000, l2.objectmanager.fault.logging.enabled = false, l2.objectmanager.flush.logging.enabled = false, l2.objectmanager.loadObjectID.checkpoint.maxlimit = -1, l2.objectmanager.loadObjectID.checkpoint.maxsleep = 10000, l2.objectmanager.loadObjectID.fastLoad = true, l2.objectmanager.loadObjectID.longsPerDiskEntry = 8, l2.objectmanager.loadObjectID.mapsdatabase.longsPerDiskEntry = 1, l2.objectmanager.maxObjectsInTxnObjGrouping = 5000, l2.objectmanager.maxObjectsToCommit = 5000, l2.objectmanager.maxTxnsInTxnObjectGrouping = 500, l2.objectmanager.objectrequest.logging.enabled = false, l2.objectmanager.objectrequest.split.size = 5, l2.objectmanager.passive.sync.batch.size = 500, l2.objectmanager.passive.sync.throttle.timeInMillis = 0, l2.objectmanager.persistor.logging.enabled = false, l2.objectmanager.persistor.measure.performance = false, l2.objectmanager.request.logging.enabled = false, l2.remotejmx.idletime = 5, l2.remotejmx.maxthreads = 50, l2.seda.commitstage.threads = 4, l2.seda.faultstage.threads = 4, l2.seda.flushstage.threads = 4, l2.seda.gcdeletestage.threads = 1, l2.seda.managedobjectrequeststage.threads = 4, l2.seda.managedobjectresponsestage.threads = 4, l2.seda.stage.sink.capacity = -1, l2.serverarray.2pc.enabled = true, l2.startuplock.retries.enabled = false, l2.transactionmanager.logging.enabled = false, l2.transactionmanager.logging.printBroadcastStats = false, l2.transactionmanager.logging.printCommits = false, l2.transactionmanager.logging.printStats = true, l2.transactionmanager.logging.verbose = false, l2.transactionmanager.passive.throttle.enabled = true, l2.transactionmanager.passive.throttle.maxSleepSeconds = 5, l2.transactionmanager.passive.throttle.threshold = 20000, lock.statistics.enabled = false, logging.longgc.threshold = 8000, logging.maxBackups = 20, logging.maxLogFileSize = 512, memory.monitor.forcebasic = false, net.core.keepalive = false, net.core.tcpnodelay = true, session.debug.hops = false, session.debug.hops.interval = 100, session.debug.invalidate = false, session.debug.sessions = false, session.invalidator.bench.enabled = true, session.invalidator.sleep = 300, session.request.bench.enabled = false, session.request.tracking = false, session.request.tracking.dump = false, session.request.tracking.interval = 2500, session.request.tracking.threshold = 15000, session.vhosts.excluded = , stats.printer.intervalInMillis = 5000, tc.bytebuffer.common.pool.maxcount = 3000, tc.bytebuffer.pooling.enabled = true, tc.bytebuffer.threadlocal.pool.maxcount = 2000, tc.config.getFromSource.timeout = 30000, tc.management.mbeans.enabled = true, tc.management.test.mbeans.enabled = false, tc.stage.monitor.delay = 5000, tc.stage.monitor.enabled = false, tc.transport.handshake.timeout = 10000, tcm.monitor.delay = 5, tcm.monitor.enabled = false }
2010-09-08 16:29:37,317 [main] INFO com.terracottatech.console - Log file: '/usr/local/terracotta/terracotta-3.2.1_2/bin/../terracotta/server-logs/terracotta-server.log'.
2010-09-08 16:29:37,318 [main] INFO com.tc.logging.TCLogging - All Java System Properties for this Terracotta instance:
========================================================================
com.sun.management.jmxremote :
file.encoding : UTF-8
file.encoding.pkg : sun.io
file.separator : /
java.awt.graphicsenv : sun.awt.X11GraphicsEnvironment
java.awt.printerjob : sun.print.PSPrinterJob
java.class.path : ./../lib/tc.jar
java.class.version : 49.0
java.endorsed.dirs : /usr/jdk1.5.0_22/jre/lib/endorsed
java.ext.dirs : /usr/jdk1.5.0_22/jre/lib/ext
java.home : /usr/jdk1.5.0_22/jre
java.io.tmpdir : /tmp
java.library.path : /usr/jdk1.5.0_22/jre/lib/i386/client:/usr/jdk1.5.0_22/jre/lib/i386:/usr/jdk1.5.0_22/jre/../lib/i386
java.rmi.server.randomIDs : true
java.runtime.name : Java(TM) 2 Runtime Environment, Standard Edition
java.runtime.version : 1.5.0_22-b03
java.specification.name : Java Platform API Specification
java.specification.vendor : Sun Microsystems Inc.
java.specification.version : 1.5
java.vendor : Sun Microsystems Inc.
java.vendor.url : http://java.sun.com/
java.vendor.url.bug : http://java.sun.com/cgi-bin/bugreport.cgi
java.version : 1.5.0_22
java.vm.info : mixed mode, sharing
java.vm.name : Java HotSpot(TM) Client VM
java.vm.specification.name : Java Virtual Machine Specification
java.vm.specification.vendor : Sun Microsystems Inc.
java.vm.specification.version: 1.0
java.vm.vendor : Sun Microsystems Inc.
java.vm.version : 1.5.0_22-b03
line.separator :

os.arch : i386
os.name : Linux
os.version : 2.6.18-164.el5
path.separator : :
sun.arch.data.model : 32
sun.boot.class.path : /usr/jdk1.5.0_22/jre/lib/rt.jar:/usr/jdk1.5.0_22/jre/lib/i18n.jar:/usr/jdk1.5.0_22/jre/lib/sunrsasign.jar:/usr/jdk1.5.0_22/jre/lib/jsse.jar:/usr/jdk1.5.0_22/jre/lib/jce.jar:/usr/jdk1.5.0_22/jre/lib/charsets.jar:/usr/jdk1.5.0_22/jre/classes
sun.boot.library.path : /usr/jdk1.5.0_22/jre/lib/i386
sun.cpu.endian : little
sun.cpu.isalist :
sun.desktop : gnome
sun.io.unicode.encoding : UnicodeLittle
sun.java.launcher : SUN_STANDARD
sun.jnu.encoding : UTF-8
sun.management.compiler : HotSpot Client Compiler
sun.os.patch.level : unknown
tc.install-root : ./..
user.country : US
user.dir : /usr/local/terracotta/terracotta-3.2.1_2/bin
user.home : /root
user.language : en
user.name : root
user.timezone : Asia/Shanghai
========================================================================
2010-09-08 16:29:39,735 [main] INFO com.terracottatech.dso - Statistics store: '/usr/local/terracotta/terracotta-3.2.1_2/bin/../terracotta/cluster-statistics'.
2010-09-08 16:29:39,743 [main] INFO com.terracottatech.console - Available Max Runtime Memory: 499MB
2010-09-08 16:29:39,920 [main] INFO com.terracottatech.dso - Standard DSO Server created
2010-09-08 16:29:39,924 [main] INFO com.terracottatech.dso - Creating server nodeID: NodeID[192.168.1.128:9510]
2010-09-08 16:29:39,951 [Statistics Logger] INFO com.terracottatech.dso - memory free : 476780768
2010-09-08 16:29:39,953 [Statistics Logger] INFO com.terracottatech.dso - memory used : 46720800
2010-09-08 16:29:39,953 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 16:29:42,019 [main] INFO com.terracottatech.dso - Statistics buffer: '/usr/local/terracotta/terracotta-3.2.1_2/bin/../terracotta/cluster-statistics'.
2010-09-08 16:29:42,149 [main] INFO com.terracottatech.console - JMX Server started. Available at URL[service:jmx:jmxmp://0.0.0.0:9520]
2010-09-08 16:29:42,327 [main] INFO com.tc.objectserver.persistence.sleepycat.DBEnvironment - Env config = allowCreate=true
cacheSize=0
txnNoSync=true
txnWriteNoSync=false
{je.cleaner.minAge=5, je.cleaner.bytesInterval=20000000, je.env.isTransactional=true, je.cleaner.lookAheadCacheSize=32768, je.env.isReadOnly=false, je.maxMemoryPercent=25, je.lock.nLockTables=7, je.checkpointer.bytesInterval=100000000, je.cleaner.detailMaxMemoryPercentage=5, je.lock.timeout=180000000}
DB Config = allowCreate=true
exclusiveCreate=false
transactional=true
readOnly=false
duplicatesAllowed=false
deferredWrite=false
temporary=false
keyPrefixingEnabled=false
JE Properties = {je.cleaner.detailMaxMemoryPercentage=5, je.cleaner.bytesInterval=20000000, je.maxMemoryPercent=25, je.checkpointer.bytesInterval=100000000, je.cleaner.lookAheadCacheSize=32768, je.lock.nLockTables=7, je.cleaner.minAge=5, je.lock.timeout=180000000}
2010-09-08 16:29:42,761 [main] INFO com.terracottatech.dso - Terracotta persistence version is 2.2, underlying persistence store: ( Sleepycat 4.0.71 )
2010-09-08 16:29:42,874 [main] INFO com.tc.net.core.TCWorkerCommManager - Creating 4 worker comm threads for L2_L1
2010-09-08 16:29:42,887 [main] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl: DSO Server - HealthChecker Started
2010-09-08 16:29:42,888 [main] INFO com.tc.net.protocol.tcm.CommunicationsManager - HealtCheck CallbackPort Listener not requested
2010-09-08 16:29:43,002 [main] INFO com.tc.runtime.TCMemoryManagerImpl - GarbageCollector: Copy
2010-09-08 16:29:43,003 [main] INFO com.tc.runtime.TCMemoryManagerImpl - GarbageCollector: MarkSweepCompact
2010-09-08 16:29:43,312 [main] INFO com.tc.objectserver.dgc.impl.GarbageCollectorThread - Young Gen Time = -1 Full Gen Time = 3600000
2010-09-08 16:29:43,370 [main] INFO com.tc.statistics.StatisticRetrievalAction - "message monitor" statistic is not enabled. Please enable the property "tcm.monitor.delay" to collect this statistics.
2010-09-08 16:29:43,371 [main] INFO com.tc.statistics.retrieval.actions.SRAL2FaultsFromDisk - "l2 faults from disk" statistic is not enabled. Please enable the property "l2.objectmanager.fault.logging.enabled" to collect this statistic.
2010-09-08 16:29:43,377 [main] INFO com.terracottatech.dso - This L2 Node ID = NodeID[CurrentNode]
2010-09-08 16:29:43,379 [main] INFO com.tc.objectserver.tx.ServerTransactionManager - Waiting for txns to complete
2010-09-08 16:29:43,380 [main] INFO com.tc.objectserver.tx.ServerTransactionManager - No more txns in the system.
2010-09-08 16:29:43,381 [main] INFO com.tc.objectserver.tx.ResentTransactionSequencer - Making callback com.tc.objectserver.gtx.GlobalTransactionIDLowWaterMarkProvider$2@16cbd97 pending since in State[ ADD_RESENT ] resent txns size : 0
2010-09-08 16:29:43,382 [main] INFO com.tc.objectserver.handshakemanager.ServerClientHandshakeManager - Starting DSO services...
2010-09-08 16:29:43,383 [main] INFO com.tc.objectserver.persistence.impl.TransactionStoreImpl - shutdownAllClientsExcept() : Removing txns from DB : 0
2010-09-08 16:29:43,383 [main] INFO com.tc.objectserver.impl.ObjectRequestManagerRestartImpl - Processing Pending Lookups = 0
2010-09-08 16:29:43,384 [main] INFO com.tc.objectserver.tx.ResentTransactionSequencer - Unregistering ResentTransactionSequencer since no more resent Transactions : 0
2010-09-08 16:29:43,384 [main] INFO com.tc.objectserver.tx.ResentTransactionSequencer - Adding Pending resent CallBacks to TxnMgr : com.tc.objectserver.gtx.GlobalTransactionIDLowWaterMarkProvider$2@16cbd97
2010-09-08 16:29:43,384 [main] INFO com.tc.objectserver.gtx.GlobalTransactionIDLowWaterMarkProvider - Switching GlobalTransactionID Low Water mark provider since all resent transactions are applied
2010-09-08 16:29:43,406 [main] INFO com.terracottatech.console - Terracotta Server instance has started up as ACTIVE node on 0:0:0:0:0:0:0:0:9510 successfully, and is now ready for work.
2010-09-08 16:29:43,448 [main] INFO com.tc.server.TCServer - Server started as 192.168.1.128
2010-09-08 16:29:43,449 [Update Checker] INFO com.tc.server.UpdateCheckAction - Update Checker: Checking...
2010-09-08 16:29:46,440 [Update Checker] INFO com.tc.server.UpdateCheckAction - Update Checker: Available updates:
2010-09-08 16:29:46,442 [Update Checker] INFO com.tc.server.UpdateCheckAction - Update Checker: * 3.3.0 [http://www.terracotta.org/confluence/display/release/Release+Notes+Terracotta+3.3.0+Ehcache+2.2.0]
2010-09-08 16:29:46,442 [Update Checker] INFO com.tc.server.UpdateCheckAction - Update Checker: Next check at Wed Sep 15 16:29:46 CST 2010
2010-09-08 16:29:48,039 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] WARN com.tc.net.core.CoreNIOServices - java.nio.channels.CancelledKeyException occured
2010-09-08 16:29:48,466 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@30227927: connected: true, closed: false local=192.168.1.128:9510 remote=192.168.1.159:12639 connect=[Wed Sep 08 16:29:48 CST 2010] idle=15ms [28 read, 0 write]
2010-09-08 16:29:48,497 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.core.TCWorkerCommManager - Selecting [L2_L1:TCWorkerComm # 0, FD, wt:0] from [[L2_L1:TCWorkerComm # 0, FD, wt:0], [L2_L1:TCWorkerComm # 1, FD, wt:0], [L2_L1:TCWorkerComm # 2, FD, wt:0], [L2_L1:TCWorkerComm # 3, FD, wt:0]]
2010-09-08 16:29:48,504 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] WARN com.tc.net.core.CoreNIOServices - java.nio.channels.CancelledKeyException occured
2010-09-08 16:29:53,579 [L2_L1:TCWorkerComm # 0_R] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - Health monitoring agent started for 192.168.1.159:12639
2010-09-08 16:29:53,586 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - HealthCheckCallbackPort verification PASSED for 192.168.1.159:12639(callbackport: 16359)
2010-09-08 16:29:53,609 [WorkerThread(client_handshake_stage, 0)] INFO com.tc.objectserver.handshakemanager.ServerClientHandshakeManager - Client connected ClientID[0]
2010-09-08 16:29:53,626 [WorkerThread(jmxremote_tunnel_stage, 0)] INFO com.tc.management.remote.protocol.terracotta.ClientTunnelingEventHandler - L1[ChannelID=[0]] notified us that their JMX server is now available
2010-09-08 16:29:53,671 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] WARN com.tc.net.core.CoreNIOServices - java.nio.channels.CancelledKeyException occured
2010-09-08 16:29:53,763 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@13482579: connected: true, closed: false local=192.168.1.128:9510 remote=192.168.1.130:37065 connect=[Wed Sep 08 16:29:53 CST 2010] idle=0ms [28 read, 0 write]
2010-09-08 16:29:53,763 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] WARN com.tc.net.core.CoreNIOServices - java.nio.channels.CancelledKeyException occured
2010-09-08 16:29:53,777 [L2_L1:TCWorkerComm # 2_R] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - Health monitoring agent started for 192.168.1.130:37065
2010-09-08 16:29:53,780 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - HealthCheckCallbackPort verification PASSED for 192.168.1.130:37065(callbackport: 37064)
2010-09-08 16:29:53,785 [WorkerThread(client_handshake_stage, 0)] INFO com.tc.objectserver.handshakemanager.ServerClientHandshakeManager - Client connected ClientID[1]
2010-09-08 16:29:53,790 [WorkerThread(jmxremote_tunnel_stage, 0)] INFO com.tc.management.remote.protocol.terracotta.ClientTunnelingEventHandler - L1[ChannelID=[1]] notified us that their JMX server is now available
2010-09-08 16:29:54,174 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Cluster Bean,type=Terracotta Cluster,node=192.168.1.159/12639'
2010-09-08 16:29:54,192 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:feature=org.terracotta.modules.tim-ehcache-2.0-1.5.3,name=Ehcache,type=Loader,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:29:54,200 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Runtime Logging Bean,subsystem=Logging,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 16:29:54,208 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:feature=org.terracotta.modules.tim-ehcache-2.0-hibernate-ui-1.5.3,name=Hibernate,type=Loader,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:29:54,249 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta.internal:clients=Clients,name=Terracotta Statistics Emitter,subsystem=Statistics,type=Terracotta Agent,node=192.168.1.159/12639'
2010-09-08 16:29:54,279 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta for Sessions,type=Sessions,node=192.168.1.159/12639'
2010-09-08 16:29:54,325 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=L1 Info Bean,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 16:29:54,353 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Runtime Output Options Bean,subsystem=Logging,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 16:29:54,368 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta.internal:clients=Clients,name=Terracotta Statistics Manager,subsystem=Statistics,type=Terracotta Agent,node=192.168.1.159/12639'
2010-09-08 16:29:54,389 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta.internal:clients=Clients,name=DSO Client Dump Bean,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 16:29:54,428 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Instrumentation Logging Bean,subsystem=Logging,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 16:29:54,994 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta.internal:clients=Clients,name=DSO Client Dump Bean,type=DSO Client,node=192.168.1.130/37065'
2010-09-08 16:29:55,044 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Runtime Logging Bean,subsystem=Logging,type=DSO Client,node=192.168.1.130/37065'
2010-09-08 16:29:55,129 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=L1 Info Bean,type=DSO Client,node=192.168.1.130/37065'
2010-09-08 16:29:55,179 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta for Sessions,type=Sessions,node=192.168.1.130/37065'
2010-09-08 16:29:55,217 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Instrumentation Logging Bean,subsystem=Logging,type=DSO Client,node=192.168.1.130/37065'
2010-09-08 16:29:55,241 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Cluster Bean,type=Terracotta Cluster,node=192.168.1.130/37065'
2010-09-08 16:29:55,259 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:feature=org.terracotta.modules.tim-ehcache-2.0-1.5.3,name=Ehcache,type=Loader,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:29:55,271 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta.internal:clients=Clients,name=Terracotta Statistics Emitter,subsystem=Statistics,type=Terracotta Agent,node=192.168.1.130/37065'
2010-09-08 16:29:55,283 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:feature=org.terracotta.modules.tim-ehcache-2.0-hibernate-ui-1.5.3,name=Hibernate,type=Loader,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:29:55,292 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta.internal:clients=Clients,name=Terracotta Statistics Manager,subsystem=Statistics,type=Terracotta Agent,node=192.168.1.130/37065'
2010-09-08 16:29:55,315 [WorkerThread(jmxremote_connect_stage, 0)] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Runtime Output Options Bean,subsystem=Logging,type=DSO Client,node=192.168.1.130/37065'
2010-09-08 16:30:53,511 [Thread-28] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:name=imCache,type=SampledCacheManager,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:30:53,539 [Thread-28] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=userLoginListCache,type=SampledCache,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:30:53,567 [Thread-28] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=sysPropertyCache,type=SampledCache,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:30:53,576 [Thread-28] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=clusterServerCache,type=SampledCache,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:30:53,584 [Thread-28] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=userPackCache,type=SampledCache,clients=Clients,node=192.168.1.159/12639'
2010-09-08 16:31:41,555 [Thread-29] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:name=imCache,type=SampledCacheManager,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:31:41,655 [Thread-29] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=sysPropertyCache,type=SampledCache,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:31:41,707 [Thread-29] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=userLoginListCache,type=SampledCache,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:31:41,756 [Thread-29] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=clusterServerCache,type=SampledCache,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:31:41,801 [Thread-29] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,name=userPackCache,type=SampledCache,clients=Clients,node=192.168.1.130/37065'
2010-09-08 16:53:29,866 [Statistics Logger] INFO com.terracottatech.dso - memory free : 466193984
2010-09-08 16:53:29,866 [Statistics Logger] INFO com.terracottatech.dso - memory used : 57307584
2010-09-08 16:53:29,867 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 17:02:50,795 [Statistics Logger] INFO com.terracottatech.dso - memory free : 445454888
2010-09-08 17:02:50,795 [Statistics Logger] INFO com.terracottatech.dso - memory used : 78046680
2010-09-08 17:02:50,796 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 17:27:33,146 [Statistics Logger] INFO com.terracottatech.dso - memory free : 473958112
2010-09-08 17:27:33,146 [Statistics Logger] INFO com.terracottatech.dso - memory used : 49543456
2010-09-08 17:27:33,147 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 17:32:01,480 [Statistics Logger] INFO com.terracottatech.dso - memory free : 452336600
2010-09-08 17:32:01,481 [Statistics Logger] INFO com.terracottatech.dso - memory used : 71164968
2010-09-08 17:32:01,481 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 17:59:33,473 [Statistics Logger] INFO com.terracottatech.dso - memory free : 427699952
2010-09-08 17:59:33,473 [Statistics Logger] INFO com.terracottatech.dso - memory used : 95801616
2010-09-08 17:59:33,473 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 17:59:39,944 [Statistics Logger] INFO com.terracottatech.dso - memory free : 427578384
2010-09-08 17:59:39,945 [Statistics Logger] INFO com.terracottatech.dso - memory used : 95923184
2010-09-08 17:59:39,945 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 18:25:58,764 [DGC-Thread] INFO com.tc.objectserver.dgc.impl.MarkAndSweepGarbageCollector - DGC[ 1 ] complete : GarbageCollectionInfo [ Iteration = 1 ] = type = full, startTime = 1283941558735 begin object count = 3882 end object count = 2950 markStageTime = 24 pauseStageTime = 1 totalMarkCycleTime = 29 candiate garabage count = 932 actual garbage count = 932 pre rescue count = 1023 rescue1Time = 2 rescue 1 Count = 932 rescue2Time = 1
2010-09-08 18:33:23,117 [Statistics Logger] INFO com.terracottatech.dso - memory free : 489357032
2010-09-08 18:33:23,117 [Statistics Logger] INFO com.terracottatech.dso - memory used : 34144536
2010-09-08 18:33:23,117 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 18:33:23,117 [Statistics Logger] INFO com.terracottatech.dso - memory free : 488881032
2010-09-08 18:33:23,117 [Statistics Logger] INFO com.terracottatech.dso - memory used : 34620536
2010-09-08 18:33:23,118 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 18:57:36,477 [Statistics Logger] INFO com.terracottatech.dso - memory free : 474306536
2010-09-08 18:57:36,478 [Statistics Logger] INFO com.terracottatech.dso - memory used : 49195032
2010-09-08 18:57:36,478 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 19:01:44,159 [Statistics Logger] INFO com.terracottatech.dso - memory free : 472025048
2010-09-08 19:01:44,161 [Statistics Logger] INFO com.terracottatech.dso - memory used : 51476520
2010-09-08 19:01:44,161 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 19:30:46,589 [Statistics Logger] INFO com.terracottatech.dso - memory free : 454234688
2010-09-08 19:30:46,590 [Statistics Logger] INFO com.terracottatech.dso - memory used : 69266880
2010-09-08 19:30:46,590 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 19:30:46,590 [Statistics Logger] INFO com.terracottatech.dso - memory free : 454234688
2010-09-08 19:30:46,590 [Statistics Logger] INFO com.terracottatech.dso - memory used : 69266880
2010-09-08 19:30:46,590 [Statistics Logger] INFO com.terracottatech.dso - memory max : 523501568
2010-09-08 19:39:38,586 [HealthChecker] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - Socket Connect to 192.168.1.159:12639(callbackport:16359) failed: java.net.SocketException: Network is unreachable
2010-09-08 19:39:38,587 [HealthChecker] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - 192.168.1.159:12639 is DEAD
2010-09-08 19:39:38,587 [HealthChecker] ERROR com.tc.net.protocol.transport.ConnectionHealthCheckerImpl: DSO Server - Declared connection dead ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd) idle time 8023ms
2010-09-08 19:39:38,589 [L2_L1:TCWorkerComm # 0_W] WARN com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@30227927: connected: false, closed: true local=192.168.1.128:9510 remote=192.168.1.159:12639 connect=[Wed Sep 08 16:29:48 CST 2010] idle=1007ms [1705990 read, 1230849 write]. STATUS : DISCONNECTED
2010-09-08 19:39:40,593 [HealthChecker] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - Socket Connect to 192.168.1.130:37065(callbackport:37064) failed: java.net.SocketException: Network is unreachable
2010-09-08 19:39:40,594 [HealthChecker] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - 192.168.1.130:37065 is DEAD
2010-09-08 19:39:40,594 [HealthChecker] ERROR com.tc.net.protocol.transport.ConnectionHealthCheckerImpl: DSO Server - Declared connection dead ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd) idle time 8618ms
2010-09-08 19:39:40,594 [L2_L1:TCWorkerComm # 2_R] WARN com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@13482579: connected: false, closed: true local=192.168.1.128:9510 remote=192.168.1.130:37065 connect=[Wed Sep 08 16:29:53 CST 2010] idle=1003ms [1378650 read, 1887646 write]. STATUS : DISCONNECTED
2010-09-08 19:39:41,599 [ClientConnectionRestoreTimer] INFO com.tc.objectserver.persistence.sleepycat.SleepycatPersistor - Deleted client state for ChannelID=[0]
2010-09-08 19:39:41,600 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta.internal:clients=Clients,name=Terracotta Statistics Manager,subsystem=Statistics,type=Terracotta Agent,node=192.168.1.159/12639'
2010-09-08 19:39:41,601 [WorkerThread(channel_life_cycle_stage, 0)] INFO com.tc.objectserver.handler.ChannelLifeCycleHandler - : Received transport disconnect. Shutting down client ClientID[0]
2010-09-08 19:39:41,601 [WorkerThread(channel_life_cycle_stage, 0)] INFO com.tc.objectserver.persistence.impl.TransactionStoreImpl - shutdownClient() : Removing txns from DB : 0
2010-09-08 19:39:41,625 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,feature=org.terracotta.modules.tim-ehcache-2.0-hibernate-ui-1.5.3,name=Hibernate,type=Loader,node=192.168.1.159/12639'
2010-09-08 19:39:41,626 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,clients=Clients,name=userLoginListCache,type=SampledCache,node=192.168.1.159/12639'
2010-09-08 19:39:41,627 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,clients=Clients,name=clusterServerCache,type=SampledCache,node=192.168.1.159/12639'
2010-09-08 19:39:41,627 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta for Sessions,type=Sessions,node=192.168.1.159/12639'
2010-09-08 19:39:41,627 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Instrumentation Logging Bean,subsystem=Logging,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 19:39:41,628 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,clients=Clients,name=userPackCache,type=SampledCache,node=192.168.1.159/12639'
2010-09-08 19:39:41,628 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,feature=org.terracotta.modules.tim-ehcache-2.0-1.5.3,name=Ehcache,type=Loader,node=192.168.1.159/12639'
2010-09-08 19:39:41,629 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,name=L1 Info Bean,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 19:39:41,629 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta.internal:clients=Clients,name=DSO Client Dump Bean,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 19:39:41,629 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'net.sf.ehcache:SampledCacheManager=imCache,clients=Clients,name=sysPropertyCache,type=SampledCache,node=192.168.1.159/12639'
2010-09-08 19:39:41,629 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Runtime Output Options Bean,subsystem=Logging,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 19:39:41,630 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Runtime Logging Bean,subsystem=Logging,type=DSO Client,node=192.168.1.159/12639'
2010-09-08 19:39:41,630 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta:clients=Clients,name=Terracotta Cluster Bean,type=Terracotta Cluster,node=192.168.1.159/12639'
2010-09-08 19:39:41,630 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'org.terracotta.internal:clients=Clients,name=Terracotta Statistics Emitter,subsystem=Statistics,type=Terracotta Agent,node=192.168.1.159/12639'
2010-09-08 19:39:41,631 [Job_Executor1] INFO com.tc.management.remote.connect.ClientConnectEventHandler - Unregistered Tunneled MBean 'net.sf.ehcache:clients=Clients,name=imCache,type=SampledCacheManager,node=192.168.1.159/12639'
2010-09-08 19:39:41,717 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@6765453: connected: true, closed: false local=192.168.1.128:9510 remote=192.168.1.130:37462 connect=[Wed Sep 08 19:39:41 CST 2010] idle=1ms [28 read, 0 write]
2010-09-08 19:39:41,718 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] WARN com.tc.net.core.CoreNIOServices - java.nio.channels.CancelledKeyException occured
2010-09-08 19:39:41,726 [L2_L1:TCWorkerComm # 2_R] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - Health monitoring agent started for 192.168.1.130:37462
2010-09-08 19:39:41,727 [L2_L1:TCWorkerComm # 2_R] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl. DSO Server - Socket Connect to 192.168.1.130:37462(callbackport:37064) failed: java.net.SocketException: Network is unreachable
2010-09-08 19:39:41,737 [L2_L1:TCWorkerComm # 2_R] INFO com.tc.net.protocol.delivery.OnceAndOnlyOnceProtocolNetworkLayerImpl - Sending OOO handshake fail message to a different session client ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd)
2010-09-08 19:39:41,738 [L2_L1:TCWorkerComm # 2_R] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl: DSO Server - Connection to [192.168.1.130:37462] CLOSED. Health Monitoring for this node is now disabled.
2010-09-08 19:39:41,739 [L2_L1:TCWorkerComm # 2_R] WARN com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@6765453: connected: false, closed: true local=192.168.1.128:9510 remote=192.168.1.130:37462 connect=[Wed Sep 08 19:39:41 CST 2010] idle=1ms [188 read, 80 write]. STATUS : CLOSED
2010-09-08 19:39:41,739 [L2_L1:TCWorkerComm # 2_R] WARN com.tc.net.protocol.transport.ServerMessageTransport - ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd): closing down connection - com.tc.net.core.TCConnectionJDK14@6765453: connected: false, closed: true local=192.168.1.128:9510 remote=192.168.1.130:37462 connect=[Wed Sep 08 19:39:41 CST 2010] idle=1ms [188 read, 80 write]
2010-09-08 19:39:41,740 [L2_L1:TCWorkerComm # 2_R] INFO com.tc.objectserver.persistence.sleepycat.SleepycatPersistor - Deleted client state for ChannelID=[1]
2010-09-08 19:39:41,741 [L2_L1:TCWorkerComm # 2_R] WARN com.tc.net.core.CoreNIOServices - java.nio.channels.CancelledKeyException occured
2010-09-08 19:39:41,741 [L2_L1:TCWorkerComm # 2_W] WARN com.tc.net.core.CoreNIOServices - Exception trying to process interest request: java.nio.channels.ClosedChannelException
2010-09-08 19:39:41,941 [WorkerThread(channel_life_cycle_stage, 0)] INFO com.tc.objectserver.handler.ChannelLifeCycleHandler - : Received transport disconnect. Shutting down client ClientID[1]
2010-09-08 19:39:41,942 [WorkerThread(channel_life_cycle_stage, 0)] INFO com.tc.objectserver.persistence.impl.TransactionStoreImpl - shutdownClient() : Removing txns from DB : 0
2010-09-08 19:39:42,364 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.terracottatech.console - Client Cannot Reconnect ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd) not found. Connection attempts from the Terracotta client at 192.168.1.130:37464 are being rejected by the Terracotta server array. Restart the client to allow it to rejoin the cluster. Many client reconnection failures can be avoided by configuring the Terracotta server array for "permanent-store" and tuning reconnection parameters. For more information, see http://www.terracotta.org/ha
2010-09-08 19:39:42,365 [L2_L1:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:9510)] INFO com.tc.net.protocol.transport.ServerStackProvider - Client Cannot Reconnect ConnectionID(1.f6504bc6f49a42a390a0cad2cda92dbd) not found. Connection attempts from the Terracotta client at 192.168.1.130:37464 are being rejected by the Terracotta server array. Restart the client to allow it to rejoin the cluster. Many client reconnection failures can be avoided by configuring the Terracotta server array for "permanent-store" and tuning reconnection parameters. For more information, see http://www.terracotta.org/ha


客户端:
2010-09-08 19:18:02,268 [ClientLockManager LockGC] INFO com.tc.object.locks.ClientLockManager - ClientID[0]: Lock GC collected 0 garbage locks
2010-09-08 19:23:54,003 [ClientLockManager LockGC] INFO com.tc.object.locks.ClientLockManager - ClientID[0]: Lock GC collected 0 garbage locks
2010-09-08 19:28:09,156 [ClientLockManager LockGC] INFO com.tc.object.locks.ClientLockManager - ClientID[0]: Lock GC collected 0 garbage locks
2010-09-08 19:32:36,284 [ClientLockManager LockGC] INFO com.tc.object.locks.ClientLockManager - ClientID[0]: Lock GC collected 0 garbage locks
2010-09-08 19:35:39,612 [ClientLockManager LockGC] INFO com.tc.object.locks.ClientLockManager - ClientID[0]: Lock GC collected 0 garbage locks
2010-09-08 19:38:56,423 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@10817185: connected: false, closed: true local=192.168.1.159:12639 remote=192.168.1.128:9510 connect=[Wed Sep 08 16:29:49 CST 2010] idle=1ms [1230849 read, 1706230 write]. STATUS : ESTABLISHED
2010-09-08 19:38:56,423 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] INFO com.tc.net.protocol.transport.ConnectionHealthCheckerImpl: DSO Client - Connection to [192.168.1.128:9510] DISCONNECTED. Health Monitoring for this node is now disabled.
2010-09-08 19:38:56,423 [L1_L2:TCComm Main Selector Thread_W (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.core.CoreNIOServices - Exception trying to process interest request: java.nio.channels.ClosedChannelException
2010-09-08 19:38:56,424 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@33535543: connected: true, closed: false local=192.168.1.159:12768 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:38:56 CST 2010] idle=1ms [0 read, 0 write]
2010-09-08 19:40:55,130 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@33535543: connected: false, closed: true local=192.168.1.159:12768 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:38:56 CST 2010] idle=118705ms [0 read, 80 write]. STATUS : START
2010-09-08 19:40:55,130 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): closing down connection - com.tc.net.core.TCConnectionJDK14@33535543: connected: false, closed: true local=192.168.1.159:12768 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:38:56 CST 2010] idle=118706ms [0 read, 80 write]
2010-09-08 19:40:55,130 [ConnectionEstablisher] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Timeout of 10000 milliseconds occured
2010-09-08 19:40:56,132 [ConnectionEstablisher] INFO com.tc.net.protocol.tcm.ClientMessageChannel - ClientMessageChannel moves to SessionID=[1]
2010-09-08 19:40:56,134 [WorkerThread(client_coordination_stage, 0)] INFO com.tc.object.handshakemanager.ClientHandshakeManagerImpl - ClientID[0]: Disconnected: Pausing from State[ RUNNING ] RemoteNode : GroupID[0]. Disconnect count: 0
2010-09-08 19:40:56,134 [WorkerThread(client_coordination_stage, 0)] INFO com.tc.object.handshakemanager.ClientHandshakeManagerImpl - ClientID[0]: ClientHandshakeManager moves to SessionID=[1]
2010-09-08 19:40:56,146 [ConnectionEstablisher] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Reconnect attempt 0 of unlimited reconnect tries to 192.168.1.128:9510:192.168.1.128, timeout=10000
2010-09-08 19:40:56,146 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@3795403: connected: true, closed: false local=192.168.1.159:12769 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:40:56 CST 2010] idle=0ms [0 read, 0 write]
2010-09-08 19:41:30,238 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@3795403: connected: false, closed: true local=192.168.1.159:12769 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:40:56 CST 2010] idle=34091ms [0 read, 80 write]. STATUS : START
2010-09-08 19:41:30,238 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): closing down connection - com.tc.net.core.TCConnectionJDK14@3795403: connected: false, closed: true local=192.168.1.159:12769 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:40:56 CST 2010] idle=34091ms [0 read, 80 write]
2010-09-08 19:41:30,239 [ConnectionEstablisher] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Timeout of 10000 milliseconds occured [lossy interval: 10000ms]
2010-09-08 19:41:31,244 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@23112907: connected: true, closed: false local=192.168.1.159:12770 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:41:31 CST 2010] idle=3ms [0 read, 0 write]
2010-09-08 19:41:41,246 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): CLOSE EVENT : com.tc.net.core.TCConnectionJDK14@23112907: connected: false, closed: true local=192.168.1.159:12770 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:41:31 CST 2010] idle=10002ms [0 read, 80 write]. STATUS : START
2010-09-08 19:41:41,247 [L1_L2:TCComm Main Selector Thread_R (listen 0:0:0:0:0:0:0:0:16359)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): closing down connection - com.tc.net.core.TCConnectionJDK14@23112907: connected: false, closed: true local=192.168.1.159:12770 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:41:31 CST 2010] idle=10003ms [0 read, 80 write]
2010-09-08 19:41:41,247 [ConnectionEstablisher] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Timeout of 10000 milliseconds occured [lossy interval: 10000ms]
2010-09-08 19:41:42,251 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.f6504bc6f49a42a390a0cad2cda92dbd): Attaching new connection: com.tc.net.core.TCConnectionJDK14@31306225: connected: true, closed: false local=192.168.1.159:12771 remote=192.168.1.128:9510 connect=[Wed Sep 08 19:41:42 CST 2010] idle=1ms [0 read, 0 write]

tc-config.xml
<tc:tc-config xmlns:tc="http://www.terracotta.org/config"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="http://www.terracotta.org/schema/terracotta-5.xsd">

<!-- Tell DSO where the Terracotta server can be found -->
<servers>
<server host="192.168.1.128" name="192.168.1.128">
<data>terracotta/server-data</data>
<logs>terracotta/server-logs</logs>
<statistics>terracotta/cluster-statistics</statistics>
</server>
</servers>

<!--
<tc-properties>
<property name="l2.l1reconnect.enabled" value="true"/>
<property name="l2.l1reconnect.timeout.millis" value="5000"/>
</tc-properties>
-->

<!-- Tell DSO where to put the generated client logs -->
<clients>
<logs>terracotta/client-logs</logs>
<modules>
<!-- Include the Terracotta Integration Module for the appropriate version of
your sessions container. You can view the list of supported TIMs with the
tim-get tool in the bin directory:
bin/tim-get.sh list
The TIM version will vary depending on the Terracotta release. To get
the correct version, use the tim-get tool in the bin directory to download the
latest appropriate version with a command like:
bin/tim-get.sh install tim-tomcat-6.0
-->

<module name="tim-ehcache-2.0"/>
<!--

<module name="tim-tomcat-5.5"/>

<module name="tim-quartz-1.7"/>
-->
</modules>
</clients>

<application>
<dso>



<instrumented-classes>

<include>
<class-expression>*..*</class-expression>
</include>


<exclude>org.jivesoftware.stringprep.DecompositionKeys</exclude>


</instrumented-classes>




<roots>
<root>
<field-name>com.scommnet.gumserver.util.cluster.ClusterFactory.localMUVRoom</field-name>
</root>



</roots>

<locks>

<!--
<autolock auto-synchronized="true">
<method-expression>* com.scommnet.gumserver.plugins.puborganize.database.manager.StarServerImpl.*(..)</method-expression>
<lock-level>write</lock-level>
</autolock>
-->

<named-lock>
<lock-name>lockOne</lock-name>
<method-expression>* com.scommnet.gumserver.util.cluster.ClusterFactory.setLocalMUVRoom(..)</method-expression>
<lock-level>write</lock-level>
</named-lock>


</locks>
</dso>
</application>

论坛里有找到类似的问题,但没找到具体解决方法。希望版主帮帮忙,谢谢!
etsai

master

Joined: 07/31/2007 10:14:38
Messages: 72
Offline

Looks like you have networking problem in between L2(192.168.1.128) and L1s(192.168.1.159 and 92.168.1.130) from 2010-09-08 19:38:56,423 to 2010-09-08 19:39:42,364 which is about 46 sec. Your l1reconnect is 5 sec. So the L1s were rejected once reconnected back to L2. In that kind of long disconnection, you need to restart your L1s to connect back to L2.
zhuxinping

journeyman

Joined: 11/02/2009 19:15:41
Messages: 11
Offline

thanks!
vivekt

neo

Joined: 05/12/2016 03:14:20
Messages: 2
Offline

I am also facing the same problem with quartz. Is there any way to auto-reconnect the client.
 
Forum Index -> Terracotta 中文社区
Go to:   
Powered by JForum 2.1.7 © JForum Team