Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Job sometimes does not execute #3931

Closed
tyrng opened this issue Jun 7, 2021 · 2 comments
Closed

Job sometimes does not execute #3931

tyrng opened this issue Jun 7, 2021 · 2 comments
Labels
status: waiting-for-triage Issues that we did not analyse yet type: bug

Comments

@tyrng
Copy link

tyrng commented Jun 7, 2021

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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStartupProfileInfo - No active profile set, falling back to default profiles: default
2021-06-05 15:42:18.935 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.data.repository.config.RepositoryConfigurationDelegate.registerRepositoriesIn - Bootstrapping Spring Data repositories in DEFAULT mode.
2021-06-05 15:42:20.451 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m com.zaxxer.hikari.HikariDataSource.getConnection - HikariPool-1 - Starting...
2021-06-05 15:42:25.879 �[35m[main]�[0;39m �[34mINFO �[0;39m com.zaxxer.hikari.HikariDataSource.getConnection - HikariPool-1 - Start completed.
2021-06-05 15:42:26.351 �[35m[main]�[0;39m �[34mINFO �[0;39m org.hibernate.jpa.internal.util.LogHelper.logPersistenceUnitInformation - HHH000204: Processing PersistenceUnitInfo [
	name: default
	...]
2021-06-05 15:42:26.594 �[35m[main]�[0;39m �[34mINFO �[0;39m org.hibernate.Version.logVersion - HHH000412: Hibernate Core {5.3.10.Final}
2021-06-05 15:42:26.598 �[35m[main]�[0;39m �[34mINFO �[0;39m org.hibernate.cfg.Environment.<clinit> - HHH000206: hibernate.properties not found
2021-06-05 15:42:27.178 �[35m[main]�[0;39m �[34mINFO �[0;39m org.hibernate.annotations.common.Version.<clinit> - HCANN000001: Hibernate Commons Annotations {5.0.4.Final}
2021-06-05 15:42:28.553 �[35m[main]�[0;39m �[34mINFO �[0;39m org.hibernate.dialect.Dialect.<init> - HHH000400: Using dialect: org.hibernate.dialect.SQLServer2012Dialect
2021-06-05 15:42:34.417 �[35m[main]�[0;39m �[34mINFO �[0;39m org.hibernate.hql.internal.QueryTranslatorFactoryInitiator.initiateService - HHH000397: Using ASTQueryTranslatorFactory
2021-06-05 15:42:34.850 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.buildNativeEntityManagerFactory - Initialized JPA EntityManagerFactory for persistence unit 'default'
2021-06-05 15:42:40.443 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.initialize - Initializing ExecutorService
2021-06-05 15:42:40.445 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.initialize - Initializing ExecutorService 'wllThreadPoolTaskExecutor'
2021-06-05 15:42:44.207 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.batch.core.repository.support.JobRepositoryFactoryBean.afterPropertiesSet - No database type set, using meta data indicating: SQLSERVER
2021-06-05 15:42:47.606 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.batch.core.launch.support.SimpleJobLauncher.afterPropertiesSet - No TaskExecutor has been set, defaulting to synchronous executor.
2021-06-05 15:42:48.847 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.dashboard.RefreshDashboardIndicatorJobConfig.refreshDashboardIndicatorStep - Run refreshDashboardIndicatorStep
2021-06-05 15:42:49.207 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.dashboard.RefreshDashboardIndicatorJobConfig.refreshDashboardIndicatorJob - Run refreshDashboardIndicatorJob
2021-06-05 15:42:49.633 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.housekeeping.springbatch.BatchRecordHkJobConfig.batchRecordHkJob - batchRecordHkJob is skip CSV: false
2021-06-05 15:42:50.712 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m c.w.d.r.searchcustomer.configuration.RefreshSearchCustomerTableWithIndexConfig.refreshSearchCustomerTableWithIndexProcessingStep - run refreshSearchCustomerTableWithIndex
2021-06-05 15:42:51.010 �[35m[main]�[0;39m �[34mINFO �[0;39m c.w.d.r.searchcustomer.configuration.RefreshSearchCustomerTableWithIndexConfig.refreshSearchCustomerTableWithIndexJob - run refreshSearchCustomerTableWithIndex
2021-06-05 15:42:51.057 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m c.w.d.r.datarelationrecovery.configuration.CampaignDataRelationRecoveryConfig.campaignDataRelationRecoveryProcessingStep - run campaignDataRelationRecovery
2021-06-05 15:42:51.090 �[35m[main]�[0;39m �[34mINFO �[0;39m c.w.d.r.datarelationrecovery.configuration.CampaignDataRelationRecoveryConfig.campaignDataRelationRecoveryJob - run campaignDataRelationRecovery
2021-06-05 15:42:51.596 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceDataGatheringItemWriter - run groupMaintenanceDataGatheringItemWriter
2021-06-05 15:42:52.448 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceDataGatheringStep - run groupMaintenanceDataGatheringStep
2021-06-05 15:42:52.449 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenancePreparationStep - run groupMaintenancePreparationStep
2021-06-05 15:42:52.450 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceJRProcessingStep - run groupMaintenanceJRProcessingStep
2021-06-05 15:42:52.452 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.report.group.GroupMaintenanceReportBatchConfig.groupMaintenanceReportJob - run groupMaintenanceReportJob
2021-06-05 15:42:52.724 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[31mWARN �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.housekeeping.audittrail.AuditTrailHkJobConfig.auditTrailHkJob - auditTrailHkJob is skip CSV: false
2021-06-05 15:42:55.173 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.initialize - Initializing ExecutorService 'taskScheduler'
2021-06-05 15:42:55.984 �[35m[main]�[0;39m �[34mINFO �[0;39m 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 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.integration.channel.PublishSubscribeChannel.adjustCounterIfNecessary - Channel 'application.errorChannel' has 1 subscriber(s).
2021-06-05 15:42:55.984 �[35m[main]�[0;39m �[34mINFO �[0;39m org.springframework.integration.endpoint.EventDrivenConsumer.start - started _org.springframework.integration.errorLogger
2021-06-05 15:42:56.016 �[35m[main]�[0;39m �[34mINFO �[0;39m cc.worldline.dni.rhbwllbatch.RhbWllBatchApplication.logStarted - Started RhbWllBatchApplication in 44.876 seconds (JVM running for 47.128)
2021-06-05 15:42:56.274 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m 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 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m org.springframework.integration.channel.PublishSubscribeChannel.adjustCounterIfNecessary - Channel 'application.errorChannel' has 0 subscriber(s).
2021-06-05 15:42:56.277 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m org.springframework.integration.endpoint.EventDrivenConsumer.stop - stopped _org.springframework.integration.errorLogger
2021-06-05 15:42:56.279 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.shutdown - Shutting down ExecutorService 'taskScheduler'
2021-06-05 15:42:56.388 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor.shutdown - Shutting down ExecutorService 'wllThreadPoolTaskExecutor'
2021-06-05 15:42:56.508 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.destroy - Closing JPA EntityManagerFactory for persistence unit 'default'
2021-06-05 15:42:56.529 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m com.zaxxer.hikari.HikariDataSource.close - HikariPool-1 - Shutdown initiated...
2021-06-05 15:42:56.592 �[35m[Thread-2]�[0;39m �[34mINFO �[0;39m com.zaxxer.hikari.HikariDataSource.close - HikariPool-1 - Shutdown completed.
@tyrng tyrng added status: waiting-for-triage Issues that we did not analyse yet type: bug labels Jun 7, 2021
@tyrng
Copy link
Author

tyrng commented Jun 7, 2021

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
Copy link
Author

tyrng commented Jun 10, 2021

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 .

@tyrng tyrng closed this as completed Jun 10, 2021
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
status: waiting-for-triage Issues that we did not analyse yet type: bug
Projects
None yet
Development

No branches or pull requests

1 participant