AppSync: El mapeo del sistema de archivos falla con "HeapDumpOnOutOfMemoryError"

Los trabajos de AppSync se bloquean y los trabajos posteriores no se pueden ejecutar.

En el registro del servidor de AppSync (C:\EMC\AppSync\jboss\logs\server.log en el servidor de AppSync), vemos que el servicio del servidor de AppSync se reinicia aquí....

07-30-2016 01:10:13.494 INFO [subproceso de servicio MSC 1-1] [org.jboss.as.naming] [] [] JBAS011802: Iniciando el servicio de nombres
07-30-2016 01:10:14.823 INFO [subproceso de servicio MSC 1-7] [org.jboss.ws.common.management.AbstractServerConfig] [] [] Servicios web de JBoss: Pila CXF Server 4.0.2.GA

En el registro ejecutivo de AppSync (C:\EMC\AppSync\jboss\logs\executive.log en el servidor de AppSync) vemos el siguiente error de falta de memoria:

4098664.623: [Full 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]
Error de apagado: Se agotó el tiempo de espera de la señal de JVM.
Poner en cola el evento 'jvm_kill'
4098666.090: [GC completo
JVM no se cerró a petición, se finalizó
Poner en cola el evento 'jvm_killed'
Poner en cola el evento 'jvm_stopped'
falla de lectura de conector. (El host remoto cerró a la fuerza una conexión existente. (0x2746))
Cerrando el conector de back-end.
Preparando el reinicio con el modo 4.
JVM solo se ejecutó durante -547624 segundos, lo que provocó un conteo de reinicios fallidos de 1.
Poner en cola el evento 'jvm_failed_invocation'
Esperando 5 segundos antes de iniciar otra JVM.
el servidor escucha en el puerto 32000.
Configuración de ping: wrapper.ping.interval=5, wrapper.ping.interval.logged=1, wrapper.ping.timeout=300
Poner en cola el evento 'jvm_restart'
Poner en cola el evento 'jvm_prelaunch'
Lanzando un comando de JVM...
: "_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=en -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)
Poner en cola el evento '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: Inicializando...
WrapperManager Debug: JVM #2
WrapperManager Debug: Ejecutando una JVM de 64 bits.
WrapperManager Debug: org.tanukisoftware.wrapper.WrapperManager información del paquete:
WrapperManager Debug:   Título de implementación: org.tanukisoftware.wrapper
WrapperManager Debug:   Proveedor de implementación: Tanuki Software, Ltd.
WrapperManager Debug:   Versión de implementación: 3.5.14
WrapperManager Debug:   ¿Está sellado?: False
WrapperManager Debug: org.tanukisoftware.wrapper.WrapperManager protection domain:
WrapperManager Debug:   Location: file:/C:/EMC/AppSync/jboss/executive/wrapper.jar
WrapperManager Debug:     Tamaño: 117,262
WrapperManager Debug:     MD5: 3a16b23744071559f42f26c48b36e756
WrapperManager Debug: Registering shutdown hook
WrapperManager Debug: Using wrapper
WrapperManager Debug: Cargar biblioteca nativa.  Hay varios nombres de archivo posibles y se utilizará el primero que se encuentre.  Los errores al cargar archivos inexistentes son normales y solo son un problema si todos fallan.
WrapperManager Debug:   Intente cargar la biblioteca nativa con el nombre: wrapper-windows-x86-64.dll Resultado: no wrapper-windows-x86-64 en java.library.path
WrapperManager Debug:   Intente cargar la biblioteca nativa con el nombre: wrapper.dll Resultado: Éxito.
WrapperManager Debug:   La biblioteca nativa se cargó correctamente.
WrapperManager Debug: Recursos localizados cargados.
WrapperManager Debug: Llamando al método de inicialización nativo.
WrapperJNI Debug: Inicializando la biblioteca nativa de WrapperManager.
WrapperJNI Debug: Archivo ejecutable de Java: C:\EMC\AppSync\jboss\_jre\bin\java.exe
WrapperJNI Debug: Biblioteca nativa: C:\EMC\AppSync\jboss\executive\wrapper.dll
WrapperJNI Debug: Versión de Windows: 6.2.9200
WrapperManager Debug: Versión de Java: 1.7.0_17-b02 Java HotSpot(TM) 64 bits, VM servidor
WrapperManager Debug: Proveedor de VM Java: Oracle Corporation
WrapperManager Debug: Nombre de SO: Windows Server 2012
WrapperManager Debug: SO Arch        : amd64
WrapperManager Debug:
WrapperManager Debug: Se inició el subproceso del monitor de eventos de control.
WrapperManager Debug: Se inició el subproceso del ejecutor de inicio.
WrapperJarApp Debug: Jar Main-Class: org.jboss.modules.Main
WrapperJarApp Debug: Classpath de jar: No se especifica.
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: Se inició el subproceso del ejecutor de comunicaciones.
WrapperManager Debug: Abra el conector en wrapper...Wrapper-Connection
WrapperManager Debug: Se abrió el conector de 31 000 a 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.
read a packet KEY : g6PkoEa65igOyT26W3eDRDQ6_Kektd7Q
Got key from JVM: g6PkoEa65igOyT26W3eDRDQ6_Kektd7Q
send a packet LOW_LOG_LEVEL : 1
send a packet LOGFILE : logs\executive.log
send a packet PROPERTIES : (Valores de la propiedad)
Iniciar aplicación.
enviar un paquete START : start
WrapperManager Debug: Se recibió un paquete LOW_LOG_LEVEL: 1
WrapperManager Debug: LowLogLevel de Wrapper es 1
WrapperManager Debug: Se recibió un paquete LOGFILE: logs\executive.log
WrapperManager Debug: Se recibió un paquete PROPERTIES: (Valores de la propiedad)
WrapperManager Debug: Se recibió un paquete START : start
WrapperManager Debug: Directorio temporal de Java: C:\Windows\TEMP
WrapperManager Debug: calling WrapperListener.start()
WrapperManager Debug: Se inició el subproceso del ejecutor WrapperListener.start.
WrapperJarApp Debug: start(args) Esperará hasta 2 segundo para que se complete el método principal.
WrapperJarApp Debug: invocando método principal
WrapperManager Debug: Envíe un paquete START_PENDING: 5000
leer un paquete START_PENDING: 5000
JVM señaló un inicio pendiente con waitHint de 5000 milisegundos.
WrapperManager Debug: Envíe un paquete START_PENDING: 5000
WrapperJarApp Debug: start(args) end.  Main Completed=false, exitCode=null
WrapperManager Debug: returned from WrapperListener.start()
WrapperManager Debug: Enviar un paquete STARTED :
WrapperManager Debug: El subproceso del ejecutar WrapperListener.start se detuvo.
WrapperManager Debug: El subproceso del ejecutor de inicio se detuvo.
Leer un paquete START_PENDING: 5000
JVM señaló un inicio pendiente con waitHint de 5000 milisegundos.
Leer un paquete STARTED :
JVM señaló que se inició.
Puesta en cola de evento 'jvm_started'
enviar un paquete PING : ping
WrapperManager Debug: Se recibió un paquete PING: ping
WrapperManager Debug: Enviar un paquete PING : ping
leer un paquete PING : ping
INFO  (WrapperJarAppMain) 2016-07-30 01:10:02,400 [org.jboss.modules] Módulo JBoss Modules versión 1.1.1.GA
enviar un paquete PING : ping
WrapperManager Debug: Se recibió un paquete PING: ping
WrapperManager Debug: Enviar un paquete PING : ping
leer un paquete PING : ping
INFO (WrapperJarAppMain) 2016-07-30 01:10:05,072 [org.jboss.msc] ServiceLogger_$logger JBoss MSC versión 1.0.2.GA
INFO (subproceso de servicio MSC 1-4) 2016-07-30 01:10:05,151 [org.jboss.as] ApplicationServerService JBAS015899: JBoss AS 7.1.1.Final "Brontes" iniciando
WrapperJarApp Debug: método principal completado
enviar un paquete PING : ping
WrapperManager Debug: Se recibió un paquete PING: ping
WrapperManager Debug: Enviar un paquete PING : ping
leer un paquete PING : ping
INFO  (subproceso de servicio MSC 1-3) 2016-07-30 01:10:11,057 [org.xnio] Xnio XNIO Versión 3.0.3.GA
INFO  (supbroceso de arranque de controladora) 2016-07-30 01:10:11,057 [org.jboss.as.server] HttpManagementAddHandler JBAS015888: Creando servicio de gestión HTTP utilizando la vinculación de conector (management-http)
INFO (subproceso de servicio MSC 1-3) 2016-07-30 01:10:11,213 [org.xnio.nio] NioXnio XNIO NIO Versión de implementación 3.0.3.GA
INFO (Grupo de subprocesos de ServerService: 25) 2016-07-30 01:10:11,651 [org.jboss.as.configadmin] ConfigAdminAdd JBAS016200: Activando el subsistema ConfigAdmin
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: Activando el subsistema de Infinispan.
INFO  (Grupo de subprocesos de ServerService -- 46) 2016-07-30 01:10:

Se produjo un reinicio del servicio del servidor de AppSync mientras se ejecutaba la operación de mapeo, debido a un problema de falta de memoria. Esto generó una entrada de mapeo obsoleta en la base de datos interna de AppSync.

Resolución:

La corrección evitará que se encuentre la entrada obsoleta, lo que evitará el bloqueo y permitirá que se ejecuten trabajos posteriores.
Este problema se resolvió en AppSync 2.2.3.1.