search

asit-asso / extract

EXTRACT makes it easy to extract and deliver of your geodata
GNU General Public License v3.0
9 stars 4 forks source link

2.1-BETA.1 : Les nouvelles requêtes gardent leur statut import pendant plusieurs heures juste après l'upgrade #298

Closed maltaesousa closed 4 months ago

maltaesousa commented 7 months ago

image

Les connecteurs sont ok mais les requêtes restent sur import et ne passent pas à l'étape suivante.

STDERR ```log 2024-04-09 14:15:44 Apache Commons Daemon procrun stderr initialized. 09-Apr-2024 14:15:45.005 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name: Apache Tomcat/9.0.87 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built: Mar 11 2024 10:12:34 UTC 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.87.0 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name: Windows Server 2019 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version: 10.0 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture: amd64 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home: D:\Applications\Java\jdk17.0.4.1 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version: 17.0.4.1+1-LTS-2 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor: Oracle Corporation 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE: D:\Applications\ApacheTomcat9 09-Apr-2024 14:15:45.022 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME: D:\Applications\ApacheTomcat9 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=D:\Applications\ApacheTomcat9 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=D:\Applications\ApacheTomcat9 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs=D:\Applications\ApacheTomcat9\endorsed 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=D:\Applications\ApacheTomcat9\temp 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=D:\Applications\ApacheTomcat9\conf\logging.properties 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.lang=ALL-UNNAMED 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.io=ALL-UNNAMED 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util=ALL-UNNAMED 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.base/java.util.concurrent=ALL-UNNAMED 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: exit 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: abort 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xms2048m 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xmx14336m 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded Apache Tomcat Native library [1.3.0] using APR version [1.7.4]. 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true], UDS [true]. 09-Apr-2024 14:15:45.037 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 09-Apr-2024 14:15:45.053 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 3.0.13 30 Jan 2024] 09-Apr-2024 14:15:45.459 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 09-Apr-2024 14:15:45.506 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [788] milliseconds 09-Apr-2024 14:15:45.615 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 09-Apr-2024 14:15:45.615 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.87] 09-Apr-2024 14:15:45.678 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [D:\Applications\ApacheTomcat9\webapps\extract##2.1-BETA1.war] 09-Apr-2024 14:16:09.506 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time. 09-Apr-2024 14:16:29.574 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [D:\Applications\ApacheTomcat9\webapps\extract##2.1-BETA1.war] has finished in [43,896] ms 09-Apr-2024 14:16:29.574 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [D:\Applications\ApacheTomcat9\webapps\docs] 09-Apr-2024 14:16:29.605 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\Applications\ApacheTomcat9\webapps\docs] has finished in [31] ms 09-Apr-2024 14:16:29.605 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [D:\Applications\ApacheTomcat9\webapps\examples] 09-Apr-2024 14:16:29.964 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\Applications\ApacheTomcat9\webapps\examples] has finished in [359] ms 09-Apr-2024 14:16:29.964 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [D:\Applications\ApacheTomcat9\webapps\host-manager] 09-Apr-2024 14:16:29.996 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\Applications\ApacheTomcat9\webapps\host-manager] has finished in [32] ms 09-Apr-2024 14:16:29.996 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [D:\Applications\ApacheTomcat9\webapps\manager] 09-Apr-2024 14:16:30.027 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\Applications\ApacheTomcat9\webapps\manager] has finished in [31] ms 09-Apr-2024 14:16:30.027 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [D:\Applications\ApacheTomcat9\webapps\ROOT] 09-Apr-2024 14:16:30.042 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [D:\Applications\ApacheTomcat9\webapps\ROOT] has finished in [15] ms 09-Apr-2024 14:16:30.058 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"] 09-Apr-2024 14:16:30.074 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [44567] milliseconds ```
maltaesousa commented 7 months ago

stdout.log

STDOUT

maltaesousa commented 7 months ago

Sur la même machine, même Tomcat, l'ancienne version fonctionne comme attendu: image

Je peux mettre à disposition des commandes de test avec un connecteur à notre geoshop prepub si besoin

maltaesousa commented 7 months ago

@yblatti côté sitn cette erreur étant assez bloquante nous ne pouvons pas continuer les autres tests

yblatti commented 7 months ago

Non reproduit chez moi, cela dépend peut-être d'un traitement particulier. J'ai remonté chez arx iT : ref interne FS#23356

maltaesousa commented 7 months ago

J'ai pu le reproduire avec différents traitements. Est-ce lié à notre connecteur? @yblatti est-ce que ça vaut le coup que je tente avec votre connecteur sur une instance test d'asit?

maltaesousa commented 7 months ago

Hello, en fait il faut attendre plusieurs heures avant que ça se débloque: image

Mais ça n'arrive que la première fois. Les imports suivants se font en 30 secondes. Peut-être que c'est dû à une nouvelle installation avec reprise de base de données.

yblatti commented 7 months ago

Hello, en fait il faut attendre plusieurs heures avant que ça se débloque

😮 plus qu'étrange ! Avez-vous des heures de fonctionnement configurées ?

maltaesousa commented 7 months ago

Oh oui!! de 6h30 à 19h00! On a donc un déclenchement 60 secondes après l'heure d'arrêt 🕵️‍♀️

Après un coup de téléphone, @yblatti suggère que le backup de la base pendant les heures creuses pourrait être la cause de ce bug. Je vais tester avec un backup en heures de fonctionnement

maltaesousa commented 7 months ago

J'ai testé avec un backup/restore pendant les heures de fonctionnement. Même comportement: ça reste bloqué sur import.

Cependant, chose intéressante: si on change l'heure de fonctionnement dans les paramètres, la commande passe à la prochaine étape instantanément.

Ici j'ai changé l'heure de fonctionnement à 11h30 pour la définir sur 6h30-12h00: image