AppSync: Toewijzing van bestandssysteem mislukt met "HeapDumpOnOutOfMemoryError"

AppSync-taken lopen vast en de daaropvolgende taken kunnen niet worden uitgevoerd.

In het AppSync Server-logboek (C:\EMC\AppSync\jboss\logs\server.log op de AppSync server) zien we dat de AppSync Server-service hier opnieuw wordt gestart....

07-30-2016 01:10:13.494 INFO [MSC service thread 1-1] [org.jboss.as.naming] [] [] JBAS011802: Starting Naming Service
07-30-2016 01:10:14.823 INFO [MSC service thread 1-7] [org.jboss.ws.common.management.AbstractServerConfig] [] [] JBoss Web Services - Stack CXF Server 4.0.2.GA

In het AppSync Executive-logboek (C:\EMC\AppSync\jboss\logs\executive.log op de AppSync server) zien we de volgende fout met onvoldoende geheugen:

4098664.623: [Volledige GC
 [PSYoungGen: 384064K-384062K>(404032K)] [ParOldGen: 1398143K-1398143K>(1398144K)] 1782207K-1782205K>(1802176K) [PSPermGen: 157954K-157954K>(158272K)], 1.4650114 secs] [Times: user=5.06 sys=0.00, real=1.47 secs]
Shutdown failed: Time-out opgetreden bij het wachten op signaal van JVM.
Enqueue Event 'jvm_kill'4098666.090
: [Volledige GC
JVM is niet afgesloten op aanvraag, heeft
Enqueue Event 'jvm_killed'Enqueue
Event 'jvm_stopped'gelezen
in socket mislukt. (Een bestaande verbinding is geforceerd gesloten door de externe host. (0x2746))
Back-endsocket sluiten.
Voorbereiding om opnieuw op te starten met modus 4.
JVM werd slechts -547624 seconden uitgevoerd, wat leidde tot een mislukte herstart van 1.
Enqueue Event 'jvm_failed_invocation
5 seconden wachten voordat een nieuwe JVM wordt gestart.
Server luistert op poort 32000.
Ping-instellingen: wrapper.ping.interval=5, wrapper.ping.interval.logged=1, wrapper.ping.timeout=300
Enqueue Event 'jvm_restart'Enqueue
Event 'jvm_prelaunch'Launching
a JVM...
Bevelen: "_jre\bin\java" -server -Xms1024m -Xmx2048m -XX:MaxPermSize=512m -XX:+HeapDumpOnOutOfMemoryError -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintCommandLineFlags -XX:+UseCompressedOops -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000 -Djava.net.preferIPv4Stack=false -Dorg.jboss.resolver.warning=true -Dorg.tanukisoftware.wrapper.WrapperManager.mbean=false -Djboss.modules.system.pkgs=org.jboss.byteman - Djboss.server.default.config=standalone.xml -Djava.util.logging.manager=org.jboss.logmanager.LogManager -Dorg.jboss.boot.log.file=logs/boot.log -Dlogging.configuration=file:standalone/configuration/logging.properties -Djboss.server.log.dir=logs -Dappsync.server=SVRAPPSYNC. PHYSIOCORP.COM -Dfile.encoding=UTF8 -Duser.language=nl -Djava.library.path="executive;.. /CST/builds/win_vc100_x64_r/bin" -classpath "executive/wrapper.jar;. /jboss-modules.jar" -Dwrapper.key="g6PkoEa65igOyT26W3eDRDQ6_Kektd7Q" -Dwrapper.port=32000 -Dwrapper.jvm.port.min=31000 -Dwrapper.jvm.port.max=31999 -Dwrapper.debug="TRUE" -Dwrapper.pid=3408 -Dwrapper.version="3.5.14-pro" -Dwrapper.native_library="wrapper" -Dwrapper.service="TRUE" -Dwrapper.cpu.timeout="10" -Dwrapper.jvmid=2 -Dwrapper.lang.domain=wrapper -Dwrapper.lang.folder=.. /lang org.tanukisoftware.wrapper.WrapperJarApp jboss-modules.jar -mp modules -jaxpmodule javax.xml.jaxp-provider org.jboss.as.standalone -Djboss.home.dir=.
JVM started (PID=7788)
Enqueue Event 'jvm_start'
-XX:+HeapDumpOnOutOfMemoryError -XX:InitialHeapSize=1073741824 -XX:MaxHeapSize=2147483648 -XX:MaxPermSize=536870912 -XX:+PrintCommandLineFlags -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+UseCompressedOops -XX:-UseLargePagesIndividualAllocation -XX:+UseParallelGC
WrapperManager Debug: WrapperManager class initialized by thread: main Using classloader: sun.misc.Launcher$AppClassLoader@78d5c653
WrapperManager: Initialiseren...
WrapperManager Debug: JVM #2
WrapperManager Debug: Een 64-bits JVM uitvoeren.
WrapperManager Debug: org.tanukisoftware.wrapper.WrapperManager package information:
WrapperManager Debug:   Implementation Title: org.tanukisoftware.wrapper
WrapperManager Debug:   Implementatieleverancier: Tanuki Software, Ltd.
WrapperManager Debug:   Implementatieversie: 3.5.14
WrapperManager Debug:   Is verzegeld?: False
WrapperManager Debug: org.tanukisoftware.wrapper.WrapperManager protection domain:
WrapperManager Debug:   Location: file:/C:/EMC/AppSync/jboss/executive/wrapper.jar
WrapperManager Debug:     Grootte: 117,262
WrapperManager Debug:     MD5: 3a16b23744071559f42f26c48b36e756
WrapperManager Debug: Registering shutdown hook
WrapperManager Debug:
WrapperManager Debug gebruiken: Native bibliotheek laden.  Er zijn meerdere mogelijke bestandsnamen en de eerste die gevonden wordt, wordt gebruikt.  Fouten bij het laden van niet-bestaande bestanden zijn normaal en vormen alleen een probleem als ze allemaal mislukken.
WrapperManager Debug:   Poging om native bibliotheek te laden met de naam: wrapper-windows-x86-64.dll Result: no wrapper-windows-x86-64 in java.library.path
WrapperManager Debug:   Poging om native bibliotheek te laden met de naam: wrapper.dll Resultaat: Succes!
WrapperManager Debug:   Native bibliotheek geladen.
WrapperManager Debug: Gelokaliseerde bronnen geladen.
WrapperManager Debug: Native initialisatiemethode aanroepen.
WrapperJNI Debug: Initialiseren van de native bibliotheek van WrapperManager.
WrapperJNI Debug: Uitvoerbaar bestand van Java: C:\EMC\AppSync\jboss\_jre\bin\java.exe
WrapperJNI Debug: Native bibliotheek: C:\EMC\AppSync\jboss\executive\wrapper.dll
WrapperJNI Debug: Windows-versie: 6.2.9200
WrapperManager Debug: Java-versie : 1.7.0_17-B02 Java HotSpot(TM) 64-Bit Server VM
WrapperManager Debug: Java VM-leverancier: Oracle Corporation
WrapperManager Debug: OS-naam : Windows Server 2012
WrapperManager Debug: OS Arch : amd64
WrapperManager Debug:
WrapperManager Debug: Control Event Monitor Thread gestart.
WrapperManager Debug: Startup runner thread gestart.
WrapperJarApp Debug: Jar Main-Class: org.jboss.modules.Main
WrapperJarApp Debug: Jar Classpath: Niet gespecificeerd.
WrapperJarApp Debug:     Classpath[0]=file:C:/EMC/AppSync/jboss/jboss-modules.jar
WrapperManager Debug: WrapperManager.start(org.tanukisoftware.wrapper.WrapperJarApp@5bfa19ca, args["-mp", "modules", "-jaxpmodule", "javax.xml.jaxp-provider", "org.jboss.as.standalone", "-Djboss.home.dir=."]) called by thread: main
WrapperManager Debug: Communicatie runner thread gestart.
WrapperManager Debug: Open de socket om te wikkelen... Wrapper-Connection
WrapperManager Debug: Geopende socket van 31.000 tot 32.000
WrapperManager Debug: Send a packet KEY : g6PkoEa65igOyT26W3eDRDQ6_Kektd7Q
WrapperManager Debug: handleBackend()
accepted a socket from 127.0.0.1 on port 31000
closing backend server.
lees een pakket SLEUTEL : g6PkoEa65igOyT26W3eDRDQ6_Kektd7Q
Kreeg sleutel van JVM: g6PkoEa65igOyT26W3eDRDQ6_Kektd7Q
stuur een pakket LOW_LOG_LEVEL :
1 stuur een pakket LOGFILE : logs\executive.log
stuur een pakket EIGENSCHAPPEN : (Waarde van onroerend goed)
Start de applicatie.
stuur een pakket START : start
WrapperManager Debug: Een pakket ontvangen LOW_LOG_LEVEL :
1 WrapperManager-foutopsporing: LowLogLevel van Wrapper is 1
WrapperManager Debug: Received a packet LOGFILE : logs\executive.log
WrapperManager Debug: Een pakket ontvangen EIGENSCHAPPEN : (Waarde van onroerend goed)
WrapperManager Debug: Received a packet START : start
WrapperManager Debug: Tijdelijke map Java: C:\Windows\TEMP
WrapperManager Debug: calling WrapperListener.start()
WrapperManager Debug: WrapperListener.start runner thread gestart.
WrapperJarApp Debug: start(args) Wacht maximaal 2 seconden totdat de hoofdmethode is voltooid.
WrapperJarApp Debug: het aanroepen van de hoofdmethode
WrapperManager Debug: Stuur een pakket START_PENDING : 5000
lees een pakket START_PENDING : 5000
JVM signaleerde een start in afwachting met een wachttijd van 5000 millis.
WrapperManager Debug: Stuur een pakket START_PENDING : 5000
WrapperJarApp Debug: start(args) end.  Main Completed=false, exitCode=null
WrapperManager Debug: returned from WrapperListener.start()
WrapperManager Debug: Send a packet STARTED :
WrapperManager Debug: WrapperListener.start runner-thread gestopt.
WrapperManager Debug: Opstartrunner-thread gestopt.
Lees een pakket START_PENDING : 5000
JVM signaleerde een start in afwachting met een wachttijd van 5000 millis.
read a packet STARTED :
JVM gaf aan dat het gestart was.
Enqueue Event 'jvm_started'send
a packet PING : ping
WrapperManager Debug: Received a packet PING : ping
WrapperManager Debug: Send a packet PING : ping
read a packet PING : ping
INFO (WrapperJarAppMain) 2016-07-30 01:10:02,400 [org.jboss.modules] Module JBoss Modules version 1.1.1.GA
send a packet PING : ping
WrapperManager Debug: Received a packet PING : ping
WrapperManager Debug: Send a packet PING : ping
read a packet PING : ping
INFO (WrapperJarAppMain) 2016-07-30 01:10:05,072 [org.jboss.msc] ServiceLogger_$logger JBoss MSC version 1.0.2.GA
INFO (MSC service thread 1-4) 2016-07-30 01:10:05,151 [org.jboss.as] ApplicationServerService JBAS015899: JBoss AS 7.1.1.Final "Brontes" starting
WrapperJarApp Debug: main method completed
send a packet PING : ping
WrapperManager Debug: Received a packet PING : ping
WrapperManager Debug: Send a packet PING : ping
read a packet PING : ping
INFO (MSC service thread 1-3) 2016-07-30 01:10:11,057 [org.xnio] Xnio XNIO Version 3.0.3.GA
INFO (Controller Boot Thread) 2016-07-30 01:10:11,057 [org.jboss.as.server] HttpManagementAddHandler JBAS015888: Creating http management service using socket-binding (management-http)
INFO (MSC service thread 1-3) 2016-07-30 01:10:11,213 [org.xnio.nio] NioXnio XNIO NIO Implementation Version 3.0.3.GA
INFO (ServerService Thread Pool -- 25) 2016-07-30 01:10:11,651 [org.jboss.as.configadmin] ConfigAdminAdd JBAS016200: Activating ConfigAdmin Subsystem
21.020: [GC [PSYoungGen: 262208K-21745K>(305856K)] 262263K-21808K>(1004928K), 0,0279106 secs] [Times: user=0.05 sys=0.03, real=0.03 secs]
21.051: [GC [PSYoungGen: 82212K->21713K(305856K)] 82275K-21848K>(1004928K), 0,0207127 secs] [Times: user=0.06 sys=0.00, real=0.02 secs]
INFO (ServerService Thread Pool -- 30) 2016-07-30 01:10:11,713 [org.jboss.as.clustering.infinispan] InfinispanSubsystemAdd JBAS010280: Infinispan-subsysteem wordt geactiveerd.
INFO (ServerService Thread Pool -- 46) 2016-07-30 01:10:

De AppSync Server-service is opnieuw opgestart terwijl de toewijzingsbewerking bezig was vanwege het probleem met onvoldoende geheugen. Dit resulteerde in een verouderde toewijzingsvermelding in de interne AppSync-database.

Resolutie:

De oplossing voorkomt dat de verouderde invoer wordt aangetroffen, waardoor deze niet vastloopt en volgende taken kunnen worden uitgevoerd.
Dit probleem is opgelost in AppSync 2.2.3.1.