spring-projects / spring-batch

Spring Batch is a framework for writing batch applications using Java and Spring
http://projects.spring.io/spring-batch/
Apache License 2.0
2.69k stars 2.33k forks source link

Job sometimes does not execute #3931

Closed tyrng closed 3 years ago

tyrng commented 3 years ago

Bug description We currently launch our jobs using command line arguments (the main function we used is attached below). We have a collection of Bash scripts that run the jobs separately, hence the job names would not contain human typing error during execution (we have tested each script thoroughly). However, sometimes, and without a clear pattern, some jobs would not be executed at all (refer to the logs attached), nor any new entry inserted into BATCH_JOB_EXECUTION and BATCH_STEP_EXECUTION.

Environment Spring Batch 2.1.7 Java 1.8 SQL Server 2017

Steps to reproduce Run the Java application with job name as the command line argument.

Expected behavior Job should always execute, the execution should be shown in the logs, new entries should be able to be seen in BATCH_JOB_EXECUTION and BATCH_STEP_EXECUTION.

Minimal Complete Reproducible example Main function:

public static void main(String[] args) throws Exception {
        String jobName = null;

        JobParametersBuilder jobParametersBuilder = new JobParametersBuilder();
        jobParametersBuilder.addLong("time", System.currentTimeMillis());

        List<String> otherArgs = new ArrayList<>();

        try {
            for (int i = 0; i < args.length; i++) {
                if (args[i].startsWith("-")) {
                    switch (args[i]) {
                        case "-h":
                        case "--help":
                            if (i + 1 < args.length && !args[i + 1].startsWith("-")) {
                                try {
                                    System.out.println(getContent(ResourceFactory.newClassPathResource("help/help_" + args[i + 1] + ".txt").getInputStream()));
                                } catch (Exception e) {
                                    throw new RuntimeException("Job help file not found");
                                }
                            } else {
                                System.out.println(getContent(ResourceFactory.newClassPathResource("help/help.txt").getInputStream()));
                            }
                            return;

                        case "-d":
                        case "--date":
                            // TODO validate date format
                            jobParametersBuilder.addString("date", args[++i]);
                            break;

                        case "-f":
                        case "--filename":
                            // TODO get filename
                            jobParametersBuilder.addString("filename", args[++i]);
                            break;
                        case "-tc":
                        case "--transactionCode":
                            jobParametersBuilder.addString("transactionCode", args[++i]);
                            break;
                        default:
                            otherArgs.add(args[i]);
                    }
                } else {
                    if (jobName != null) {
                        throw new IllegalArgumentException("Multiple jobName detected");
                    }
                    jobName = args[i];
                    jobParametersBuilder.addString("job", jobName);
                }
            }

            if (jobName == null) {
                throw new IllegalArgumentException("Unable to process, jobName required!");
            }
        } catch (IndexOutOfBoundsException e) {
            throw new IllegalArgumentException("Argument count not match");
        }

        ConfigurableApplicationContext context = SpringApplication.run(RhbWllBatchApplication.class,
                otherArgs.toArray(new String[]{}));
        JobLauncher jobLauncher = (JobLauncher) context.getBean("jobLauncher");
        Job job = (Job) context.getBean(jobName);

        try {
            JobExecution execution = jobLauncher.run(job, jobParametersBuilder.toJobParameters());
            if (execution.getStatus().getBatchStatus() == javax.batch.runtime.BatchStatus.FAILED) {
                log.error("Job execution failure = " + execution.getFailureExceptions() +
                        "\n\t Step Executions = "+ execution.getStepExecutions());
                System.exit(1);
            }
        } catch (Exception e) {
            e.printStackTrace();
            System.exit(1);
        }

        System.exit(0);
    }

Log when the job is executed normally:

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::        (v2.1.7.RELEASE)

2021-06-07 17:26:32.964 [main] INFO  cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStarting - Starting RhbWllBatchApplication v2.0.1.16.1 on myrhboalsapp01vd with PID 17095 (/appl/RHBMY/WLL/batch/rhb-wll-batch.jar started by wllappsadmin in /home/wllappsadmin)
2021-06-07 17:26:32.970 [main] INFO  cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStartupProfileInfo - No active profile set, falling back to default profiles: default
2021-06-07 17:26:35.413 [main] INFO  org.springframework.data.repository.config.RepositoryConfigurationDelegate.registerRepositoriesIn - Bootstrapping Spring Data repositories in DEFAULT mode.
2021-06-07 17:26:35.909 [main] INFO  org.springframework.data.repository.config.RepositoryConfigurationDelegate.registerRepositoriesIn - Finished Spring Data repository scanning in 485ms. Found 38 repository interfaces.
2021-06-07 17:26:36.790 [main] INFO  o.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor.registerErrorChannel - No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
2021-06-07 17:26:36.797 [main] INFO  o.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor.registerTaskScheduler - No bean named 'taskScheduler' has been explicitly defined. Therefore, a default ThreadPoolTaskScheduler will be created.
2021-06-07 17:26:36.806 [main] INFO  o.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor.registerHeaderChannelRegistry - No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
2021-06-07 17:26:37.230 [main] INFO  o.s.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker.postProcessAfterInitialization - Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$8b3c03c4] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2021-06-07 17:26:37.261 [main] INFO  o.s.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker.postProcessAfterInitialization - Bean 'org.springframework.integration.config.IntegrationManagementConfiguration' of type [org.springframework.integration.config.IntegrationManagementConfiguration$$EnhancerBySpringCGLIB$$f62dbef3] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2021-06-07 17:26:37.282 [main] INFO  o.s.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker.postProcessAfterInitialization - Bean 'integrationDisposableAutoCreatedBeans' of type [org.springframework.integration.config.annotation.Disposables] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2021-06-07 17:26:37.738 [main] INFO  com.zaxxer.hikari.HikariDataSource.getConnection - HikariPool-1 - Starting...
2021-06-07 17:26:38.155 [main] INFO  com.zaxxer.hikari.HikariDataSource.getConnection - HikariPool-1 - Start completed.
2021-06-07 17:26:38.401 [main] INFO  org.hibernate.jpa.internal.util.LogHelper.logPersistenceUnitInformation - HHH000204: Processing PersistenceUnitInfo [
        name: default
        ...]
2021-06-07 17:26:38.579 [main] INFO  org.hibernate.Version.logVersion - HHH000412: Hibernate Core {5.3.10.Final}
2021-06-07 17:26:38.581 [main] INFO  org.hibernate.cfg.Environment.<clinit> - HHH000206: hibernate.properties not found
2021-06-07 17:26:38.793 [main] INFO  org.hibernate.annotations.common.Version.<clinit> - HCANN000001: Hibernate Commons Annotations {5.0.4.Final}
2021-06-07 17:26:39.340 [main] INFO  org.hibernate.dialect.Dialect.<init> - HHH000400: Using dialect: org.hibernate.dialect.SQLServer2012Dialect
2021-06-07 17:26:41.482 [main] INFO  org.hibernate.hql.internal.QueryTranslatorFactoryInitiator.initiateService - HHH000397: Using ASTQueryTranslatorFactory
2021-06-07 17:26:41.606 [main] INFO  org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.buildNativeEntityManagerFactory - Initialized JPA EntityManagerFactory for persistence unit 'default'
2021-06-07 17:26:43.273 [main] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.initialize - Initializing ExecutorService
2021-06-07 17:26:43.275 [main] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.initialize - Initializing ExecutorService 'wllThreadPoolTaskExecutor'
2021-06-07 17:26:44.511 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.541 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.557 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.588 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.609 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.631 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.661 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.679 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.699 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.716 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.728 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.747 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.757 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.773 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.781 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.791 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.811 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.825 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.848 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:44.861 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.414 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.486 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.500 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.514 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.533 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.545 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.561 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.575 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.584 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.590 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.597 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:45.811 [main] WARN  org.springframework.boot.autoconfigure.batch.JpaBatchConfigurer.determineIsolationLevel - JPA does not support custom isolation levels, so locks may not be taken when launching Jobs
2021-06-07 17:26:45.813 [main] INFO  org.springframework.batch.core.repository.support.JobRepositoryFactoryBean.afterPropertiesSet - No database type set, using meta data indicating: SQLSERVER
2021-06-07 17:26:45.815 [main] INFO  org.springframework.batch.core.launch.support.SimpleJobLauncher.afterPropertiesSet - No TaskExecutor has been set, defaulting to synchronous executor.
2021-06-07 17:26:46.298 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:46.329 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:46.380 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:46.407 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:46.448 [main] INFO  cc.worldline.dni.rhbwllbatch.dashboard.RefreshDashboardIndicatorJobConfig.refreshDashboardIndicatorStep - Run refreshDashboardIndicatorStep
2021-06-07 17:26:46.449 [main] INFO  cc.worldline.dni.rhbwllbatch.dashboard.RefreshDashboardIndicatorJobConfig.refreshDashboardIndicatorJob - Run refreshDashboardIndicatorJob
2021-06-07 17:26:46.590 [main] WARN  org.springframework.batch.core.listener.AbstractListenerFactoryBean.isListener - org.springframework.batch.item.ItemProcessor is an interface. The implementing class will not be queried for annotation based listener configurations. If using @StepScope on a @Bean method, be sure to return the implementing class so listener annotations can be used.
2021-06-07 17:26:46.593 [main] WARN  org.springframework.batch.core.listener.AbstractListenerFactoryBean.isListener - org.springframework.batch.item.ItemProcessor is an interface. The implementing class will not be queried for annotation based listener configurations. If using @StepScope on a @Bean method, be sure to return the implementing class so listener annotations can be used.
2021-06-07 17:26:46.668 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:46.813 [main] INFO  cc.worldline.dni.rhbwllbatch.housekeeping.springbatch.BatchRecordHkJobConfig.batchRecordHkJob - batchRecordHkJob is skip CSV: false
2021-06-07 17:26:46.959 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:46.993 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.002 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.051 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.060 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.146 [main] INFO  c.w.d.r.searchcustomer.configuration.RefreshSearchCustomerTableWithIndexConfig.refreshSearchCustomerTableWithIndexProcessingStep - run refreshSearchCustomerTableWithIndex
2021-06-07 17:26:47.148 [main] INFO  c.w.d.r.searchcustomer.configuration.RefreshSearchCustomerTableWithIndexConfig.refreshSearchCustomerTableWithIndexJob - run refreshSearchCustomerTableWithIndex
2021-06-07 17:26:47.170 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.175 [main] INFO  c.w.d.r.datarelationrecovery.configuration.CampaignDataRelationRecoveryConfig.campaignDataRelationRecoveryProcessingStep - run campaignDataRelationRecovery
2021-06-07 17:26:47.176 [main] INFO  c.w.d.r.datarelationrecovery.configuration.CampaignDataRelationRecoveryConfig.campaignDataRelationRecoveryJob - run campaignDataRelationRecovery
2021-06-07 17:26:47.428 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.666 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.780 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceDataGatheringItemWriter - run groupMaintenanceDataGatheringItemWriter
2021-06-07 17:26:47.783 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceDataGatheringStep - run groupMaintenanceDataGatheringStep
2021-06-07 17:26:47.784 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenancePreparationStep - run groupMaintenancePreparationStep
2021-06-07 17:26:47.785 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceJRProcessingStep - run groupMaintenanceJRProcessingStep
2021-06-07 17:26:47.787 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceReportJob - run groupMaintenanceReportJob
2021-06-07 17:26:47.880 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.920 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:47.930 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-07 17:26:48.164 [main] INFO  cc.worldline.dni.rhbwllbatch.housekeeping.audittrail.AuditTrailHkJobConfig.auditTrailHkJob - auditTrailHkJob is skip CSV: false
2021-06-07 17:26:48.640 [main] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.initialize - Initializing ExecutorService 'taskScheduler'
2021-06-07 17:26:48.897 [main] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.logComponentSubscriptionEvent - Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2021-06-07 17:26:48.898 [main] INFO  org.springframework.integration.channel.PublishSubscribeChannel.adjustCounterIfNecessary - Channel 'application.errorChannel' has 1 subscriber(s).
2021-06-07 17:26:48.898 [main] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.start - started _org.springframework.integration.errorLogger
2021-06-07 17:26:48.915 [main] INFO  cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStarted - Started RhbWllBatchApplication in 16.842 seconds (JVM running for 18.189)
2021-06-07 17:26:49.028 [main] INFO  org.springframework.batch.core.launch.support.SimpleJobLauncher.run - Job: [SimpleJob: [name=userLoginReportJob]] launched with the following parameters: [{time=1623057991865, job=userLoginReportJob}]
2021-06-07 17:26:49.068 [main] INFO  org.springframework.batch.core.job.SimpleStepHandler.handleStep - Executing step: [userLoginProcessingStep]
2021-06-07 17:26:49.088 [main] INFO  cc.worldline.dni.rhbwllbatch.common.DateUtil.beginEndDate - begin date: 2021-06-07T00:00 end date get hour: 23 get minute: 59 get second: 59
2021-06-07 17:26:49.088 [main] INFO  cc.worldline.dni.rhbwllbatch.common.DateUtil.beginEndDate - end date: 2021-06-07T23:59:59 reportDate: 20210607
2021-06-07 17:26:51.120 [main] INFO  org.springframework.batch.core.launch.support.SimpleJobLauncher.run - Job: [SimpleJob: [name=userLoginReportJob]] completed with the following parameters: [{time=1623057991865, job=userLoginReportJob}] and the following status: [COMPLETED]
2021-06-07 17:26:51.131 [Thread-2] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.logComponentSubscriptionEvent - Removing {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2021-06-07 17:26:51.133 [Thread-2] INFO  org.springframework.integration.channel.PublishSubscribeChannel.adjustCounterIfNecessary - Channel 'application.errorChannel' has 0 subscriber(s).
2021-06-07 17:26:51.133 [Thread-2] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.stop - stopped _org.springframework.integration.errorLogger
2021-06-07 17:26:51.134 [Thread-2] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.shutdown - Shutting down ExecutorService 'taskScheduler'
2021-06-07 17:26:51.205 [Thread-2] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.shutdown - Shutting down ExecutorService 'wllThreadPoolTaskExecutor'
2021-06-07 17:26:51.280 [Thread-2] INFO  org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.destroy - Closing JPA EntityManagerFactory for persistence unit 'default'
2021-06-07 17:26:51.291 [Thread-2] INFO  com.zaxxer.hikari.HikariDataSource.close - HikariPool-1 - Shutdown initiated...
2021-06-07 17:26:51.294 [Thread-2] INFO  com.zaxxer.hikari.HikariDataSource.close - HikariPool-1 - Shutdown completed.

Log when the job randomly does not execute:

  .   ____          _            __ _ _
 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
  '  |____| .__|_| |_|_| |_\__, | / / / /
 =========|_|==============|___/=/_/_/_/
 :: Spring Boot ::        (v2.1.7.RELEASE)

2021-06-05 15:42:12.699 [main] INFO  cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStarting - Starting RhbWllBatchApplication v2.0.1.16.1 on myrhboalsapp01vd with PID 15474 (/appl/RHBMY/WLL/batch/rhb-wll-batch.jar started by wllappsadmin in /home/wllappsadmin)
2021-06-05 15:42:12.704 [main] INFO  cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStartupProfileInfo - No active profile set, falling back to default profiles: default
2021-06-05 15:42:18.935 [main] INFO  org.springframework.data.repository.config.RepositoryConfigurationDelegate.registerRepositoriesIn - Bootstrapping Spring Data repositories in DEFAULT mode.
2021-06-05 15:42:20.451 [main] INFO  org.springframework.data.repository.config.RepositoryConfigurationDelegate.registerRepositoriesIn - Finished Spring Data repository scanning in 1493ms. Found 38 repository interfaces.
2021-06-05 15:42:22.733 [main] INFO  o.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor.registerErrorChannel - No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
2021-06-05 15:42:22.750 [main] INFO  o.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor.registerTaskScheduler - No bean named 'taskScheduler' has been explicitly defined. Therefore, a default ThreadPoolTaskScheduler will be created.
2021-06-05 15:42:22.769 [main] INFO  o.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor.registerHeaderChannelRegistry - No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
2021-06-05 15:42:23.807 [main] INFO  o.s.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker.postProcessAfterInitialization - Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$dadee998] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2021-06-05 15:42:23.856 [main] INFO  o.s.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker.postProcessAfterInitialization - Bean 'org.springframework.integration.config.IntegrationManagementConfiguration' of type [org.springframework.integration.config.IntegrationManagementConfiguration$$EnhancerBySpringCGLIB$$45d0a4c7] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2021-06-05 15:42:23.896 [main] INFO  o.s.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker.postProcessAfterInitialization - Bean 'integrationDisposableAutoCreatedBeans' of type [org.springframework.integration.config.annotation.Disposables] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2021-06-05 15:42:25.106 [main] INFO  com.zaxxer.hikari.HikariDataSource.getConnection - HikariPool-1 - Starting...
2021-06-05 15:42:25.879 [main] INFO  com.zaxxer.hikari.HikariDataSource.getConnection - HikariPool-1 - Start completed.
2021-06-05 15:42:26.351 [main] INFO  org.hibernate.jpa.internal.util.LogHelper.logPersistenceUnitInformation - HHH000204: Processing PersistenceUnitInfo [
    name: default
    ...]
2021-06-05 15:42:26.594 [main] INFO  org.hibernate.Version.logVersion - HHH000412: Hibernate Core {5.3.10.Final}
2021-06-05 15:42:26.598 [main] INFO  org.hibernate.cfg.Environment.<clinit> - HHH000206: hibernate.properties not found
2021-06-05 15:42:27.178 [main] INFO  org.hibernate.annotations.common.Version.<clinit> - HCANN000001: Hibernate Commons Annotations {5.0.4.Final}
2021-06-05 15:42:28.553 [main] INFO  org.hibernate.dialect.Dialect.<init> - HHH000400: Using dialect: org.hibernate.dialect.SQLServer2012Dialect
2021-06-05 15:42:34.417 [main] INFO  org.hibernate.hql.internal.QueryTranslatorFactoryInitiator.initiateService - HHH000397: Using ASTQueryTranslatorFactory
2021-06-05 15:42:34.850 [main] INFO  org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.buildNativeEntityManagerFactory - Initialized JPA EntityManagerFactory for persistence unit 'default'
2021-06-05 15:42:40.443 [main] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.initialize - Initializing ExecutorService
2021-06-05 15:42:40.445 [main] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.initialize - Initializing ExecutorService 'wllThreadPoolTaskExecutor'
2021-06-05 15:42:44.207 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.266 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.308 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.342 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.389 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.448 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.514 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.578 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.666 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.727 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.799 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.819 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.859 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.882 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.912 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:44.939 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:45.000 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:45.045 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:45.093 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:45.120 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.474 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.683 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.706 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.756 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.819 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.842 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.871 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.885 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.896 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.903 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:46.927 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:47.601 [main] WARN  org.springframework.boot.autoconfigure.batch.JpaBatchConfigurer.determineIsolationLevel - JPA does not support custom isolation levels, so locks may not be taken when launching Jobs
2021-06-05 15:42:47.603 [main] INFO  org.springframework.batch.core.repository.support.JobRepositoryFactoryBean.afterPropertiesSet - No database type set, using meta data indicating: SQLSERVER
2021-06-05 15:42:47.606 [main] INFO  org.springframework.batch.core.launch.support.SimpleJobLauncher.afterPropertiesSet - No TaskExecutor has been set, defaulting to synchronous executor.
2021-06-05 15:42:48.847 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:48.920 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:49.069 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:49.104 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:49.206 [main] INFO  cc.worldline.dni.rhbwllbatch.dashboard.RefreshDashboardIndicatorJobConfig.refreshDashboardIndicatorStep - Run refreshDashboardIndicatorStep
2021-06-05 15:42:49.207 [main] INFO  cc.worldline.dni.rhbwllbatch.dashboard.RefreshDashboardIndicatorJobConfig.refreshDashboardIndicatorJob - Run refreshDashboardIndicatorJob
2021-06-05 15:42:49.633 [main] WARN  org.springframework.batch.core.listener.AbstractListenerFactoryBean.isListener - org.springframework.batch.item.ItemProcessor is an interface. The implementing class will not be queried for annotation based listener configurations. If using @StepScope on a @Bean method, be sure to return the implementing class so listener annotations can be used.
2021-06-05 15:42:49.636 [main] WARN  org.springframework.batch.core.listener.AbstractListenerFactoryBean.isListener - org.springframework.batch.item.ItemProcessor is an interface. The implementing class will not be queried for annotation based listener configurations. If using @StepScope on a @Bean method, be sure to return the implementing class so listener annotations can be used.
2021-06-05 15:42:50.117 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:50.399 [main] INFO  cc.worldline.dni.rhbwllbatch.housekeeping.springbatch.BatchRecordHkJobConfig.batchRecordHkJob - batchRecordHkJob is skip CSV: false
2021-06-05 15:42:50.712 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:50.744 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:50.779 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:50.815 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:50.821 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:51.009 [main] INFO  c.w.d.r.searchcustomer.configuration.RefreshSearchCustomerTableWithIndexConfig.refreshSearchCustomerTableWithIndexProcessingStep - run refreshSearchCustomerTableWithIndex
2021-06-05 15:42:51.010 [main] INFO  c.w.d.r.searchcustomer.configuration.RefreshSearchCustomerTableWithIndexConfig.refreshSearchCustomerTableWithIndexJob - run refreshSearchCustomerTableWithIndex
2021-06-05 15:42:51.057 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:51.089 [main] INFO  c.w.d.r.datarelationrecovery.configuration.CampaignDataRelationRecoveryConfig.campaignDataRelationRecoveryProcessingStep - run campaignDataRelationRecovery
2021-06-05 15:42:51.090 [main] INFO  c.w.d.r.datarelationrecovery.configuration.CampaignDataRelationRecoveryConfig.campaignDataRelationRecoveryJob - run campaignDataRelationRecovery
2021-06-05 15:42:51.596 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:52.111 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:52.445 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceDataGatheringItemWriter - run groupMaintenanceDataGatheringItemWriter
2021-06-05 15:42:52.448 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceDataGatheringStep - run groupMaintenanceDataGatheringStep
2021-06-05 15:42:52.449 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenancePreparationStep - run groupMaintenancePreparationStep
2021-06-05 15:42:52.450 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceJRProcessingStep - run groupMaintenanceJRProcessingStep
2021-06-05 15:42:52.452 [main] INFO  cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceReportJob - run groupMaintenanceReportJob
2021-06-05 15:42:52.724 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:52.923 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:52.965 [main] WARN  org.springframework.batch.core.step.builder.FaultTolerantStepBuilder.detectStreamInReader - Asynchronous TaskExecutor detected with ItemStream reader.  This is probably an error, and may lead to incorrect restart data being stored.
2021-06-05 15:42:53.525 [main] INFO  cc.worldline.dni.rhbwllbatch.housekeeping.audittrail.AuditTrailHkJobConfig.auditTrailHkJob - auditTrailHkJob is skip CSV: false
2021-06-05 15:42:55.173 [main] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.initialize - Initializing ExecutorService 'taskScheduler'
2021-06-05 15:42:55.984 [main] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.logComponentSubscriptionEvent - Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2021-06-05 15:42:55.984 [main] INFO  org.springframework.integration.channel.PublishSubscribeChannel.adjustCounterIfNecessary - Channel 'application.errorChannel' has 1 subscriber(s).
2021-06-05 15:42:55.984 [main] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.start - started _org.springframework.integration.errorLogger
2021-06-05 15:42:56.016 [main] INFO  cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStarted - Started RhbWllBatchApplication in 44.876 seconds (JVM running for 47.128)
2021-06-05 15:42:56.274 [Thread-2] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.logComponentSubscriptionEvent - Removing {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2021-06-05 15:42:56.277 [Thread-2] INFO  org.springframework.integration.channel.PublishSubscribeChannel.adjustCounterIfNecessary - Channel 'application.errorChannel' has 0 subscriber(s).
2021-06-05 15:42:56.277 [Thread-2] INFO  org.springframework.integration.endpoint.EventDrivenConsumer.stop - stopped _org.springframework.integration.errorLogger
2021-06-05 15:42:56.279 [Thread-2] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.shutdown - Shutting down ExecutorService 'taskScheduler'
2021-06-05 15:42:56.388 [Thread-2] INFO  org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.shutdown - Shutting down ExecutorService 'wllThreadPoolTaskExecutor'
2021-06-05 15:42:56.508 [Thread-2] INFO  org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.destroy - Closing JPA EntityManagerFactory for persistence unit 'default'
2021-06-05 15:42:56.529 [Thread-2] INFO  com.zaxxer.hikari.HikariDataSource.close - HikariPool-1 - Shutdown initiated...
2021-06-05 15:42:56.592 [Thread-2] INFO  com.zaxxer.hikari.HikariDataSource.close - HikariPool-1 - Shutdown completed.
tyrng commented 3 years ago

Attached the specific job config that is used in the logs in my original post:

package cc.worldline.dni.rhbwllbatch.report.user;

import cc.worldline.dni.rhbwllbatch.common.DateUtil;
import cc.worldline.dni.rhbwllbatch.common.ReportInfor;
import cc.worldline.dni.rhbwllbatch.common.entity.ActivityLog;
import cc.worldline.dni.rhbwllbatch.common.entity.ActivityLogRepository;
import cc.worldline.dni.rhbwllbatch.report.common.service.MaintenanceListingService;
import lombok.extern.slf4j.Slf4j;
import net.sf.jasperreports.engine.JRException;
import org.springframework.batch.core.Job;
import org.springframework.batch.core.Step;
import org.springframework.batch.core.StepContribution;
import org.springframework.batch.core.configuration.annotation.EnableBatchProcessing;
import org.springframework.batch.core.configuration.annotation.JobBuilderFactory;
import org.springframework.batch.core.configuration.annotation.StepBuilderFactory;
import org.springframework.batch.core.scope.context.ChunkContext;
import org.springframework.batch.core.step.tasklet.Tasklet;
import org.springframework.batch.repeat.RepeatStatus;
import org.springframework.beans.factory.annotation.Autowired;
import org.springframework.beans.factory.annotation.Value;
import org.springframework.context.annotation.Bean;
import org.springframework.context.annotation.Configuration;

import java.io.IOException;
import java.sql.SQLException;
import java.text.ParseException;
import java.time.LocalDate;
import java.time.format.DateTimeFormatter;
import java.util.List;

@Slf4j
@Configuration
@EnableBatchProcessing
public class UserLoginReportBatchConfig {

    @Autowired
    private JobBuilderFactory jobBuilderFactory;

    @Autowired
    private StepBuilderFactory stepBuilderFactory;

    @Autowired
    MaintenanceListingService maintenanceListingService;

    @Autowired
    ActivityLogRepository activityLogRepository;

    @Value("${reportProperties.common.mainTitle}")
    private String mainTitle;
    @Value("${reportProperties.common.headerSeparator}")
    private String headerSeparator;
    @Value("${reportProperties.common.reportEndNote}")
    private String reportEndNote;
    @Value("${reportProperties.userLoginReportJob.reportId}")
    private String reportId;
    @Value("${reportProperties.userLoginReportJob.reportTitle}")
    private String reportTitle;
    @Value("${reportProperties.userLoginReportJob.location}")
    private String location;
    @Value("${reportProperties.userLoginReportJob.reportName}")
    private String reportName;
    @Value("${reportProperties.userLoginReportJob.createdJob}")
    private String createdJob;
    @Value("${reportProperties.userLoginReportJob.templateName}")
    private String templateName;
    @Value("${reportProperties.userLoginReportJob.jrGzipVirtualizerSize}")
    private String jrGzipVirtualizerSize;
    @Value("${reportProperties.userLoginReportJob.minusDay}")
    private String minusDay;

    @Bean
    public Job userLoginReportJob(Step userLoginProcessingStep) {
        return jobBuilderFactory.get("userLoginReportJob")
                .start(userLoginProcessingStep)
                .build();
    }

    @Bean
    public Step userLoginProcessingStep() throws IOException,  IllegalStateException {
        return stepBuilderFactory.get("userLoginProcessingStep")
                .tasklet(userLoginListing())
                .build();
    }

    @Bean
    public Tasklet userLoginListing() {

        return (Tasklet)(new Tasklet() {

            public final RepeatStatus execute(StepContribution stepContribution, ChunkContext chunkContext) throws ParseException, IOException, JRException, SQLException {

                List<ActivityLog> activityLogList;
                String paramDate = (String) chunkContext.getStepContext().getJobParameters().get("date");
                ReportInfor reportInfor;

                if (paramDate != null) {
                    reportInfor = DateUtil.beginEndDate(paramDate, DateUtil.SYSTEM_YEAR_MONTH_DATE + DateUtil.TIME_PATTERN_1, DateUtil.SYSTEM_YEAR_MONTH_DATE);
                } else {
                    // minusDay = 0 or 1, get today or today - 1 date (yesterday)
                    paramDate = LocalDate.now().minusDays(Long.valueOf(minusDay)).format(DateTimeFormatter.ofPattern(DateUtil.SYSTEM_YEAR_MONTH_DATE));
                    reportInfor = DateUtil.beginEndDate(paramDate, DateUtil.SYSTEM_YEAR_MONTH_DATE + DateUtil.TIME_PATTERN_1, DateUtil.SYSTEM_YEAR_MONTH_DATE);
                }
                reportInfor.setReportDate(paramDate);
                reportInfor.setMainTitle(mainTitle);
                reportInfor.setHeaderSeparator(headerSeparator);
                reportInfor.setReportEndNote(reportEndNote);
                reportInfor.setReportTitle(reportTitle);
                reportInfor.setReportId(reportId);
                reportInfor.setLocation(location);
                reportInfor.setReportName(reportName);
                reportInfor.setKind(createdJob);
                reportInfor.setTemplateName(templateName);
                reportInfor.setJrGzipVirtualizerSize(jrGzipVirtualizerSize);

                maintenanceListingService.generateUserLoginReport(reportInfor);
                return RepeatStatus.FINISHED;
            }
        });
    }

}
tyrng commented 3 years ago

Closing this issue as we found that printStackTrace() outputs to STDERR instead of STDOUT, which our script is not able to produce in the log file output. And the printed error seems to be caused by #1448 .