here is sample log (you will see 3 sections each ends with "Handler: "filewriter": filewriter(FileWriterHandler)". Each section begins with blank lines before it starts the line with DEBUG.
Each of this section to the log is written in all at once ,and that repeats in the log for every 30 secs.
Along with particular format of section, i found its writing other lines too. How can i ensure i get this all into log.
DEBUG 2020-05-18 12:46:46.000365 [main] - == JNI == createColumnValue()
DEBUG 2020-05-18 12:46:46.000365 [main] - == JNI == createOperation(AP.AP_INVOICES_ALL, 15, 4909, 25284690, 4909, 25287278, 3, [B@1c70cf02, [B@246cc4b6)
DEBUG 2020-05-18 12:46:46.000365 [main] - create token, key='R' (R), value=AAEVU6AGUAAKBayAAP (AAEVU6AGUAAKBayAAP), isSet=HAS_VALUE (HAS_VALUE)
DEBUG 2020-05-18 12:46:46.000365 [main] - create token, key='TKN-COMMITTIMESTAMP' (TKN-COMMITTIMESTAMP), value=2020-05-18 12:46:25.000000 (2020-05-18 12:46:25.000000), isSet=HAS_VALUE (HAS_VALUE)
DEBUG 2020-05-18 12:46:46.000365 [main] - create token, key='TKN-FILESEQNO' (TKN-FILESEQNO), value= (), isSet=HAS_VALUE (HAS_VALUE)
DEBUG 2020-05-18 12:46:46.000365 [main] - create token, key='TKN-FILERBA' (TKN-FILERBA), value= (), isSet=HAS_VALUE (HAS_VALUE)
DEBUG 2020-05-18 12:46:47.000291 [pool-2-thread-1] - UserExitDataSource.getStatusReport
DEBUG 2020-05-18 12:46:47.000291 [pool-2-thread-1] - [115511] getStatusReport: Mon May 18 12:46:47 PDT 2020
INFO 2020-05-18 12:46:47.000292 [pool-2-thread-1] - Memory at Status : Max: 455.50 MB, Total: 296.50 MB, Free: 170.68 MB, Used: 125.82 MB
INFO 2020-05-18 12:46:47.000292 [pool-2-thread-1] - Status report: Mon May 18 12:46:47 PDT 2020
*************************************************
Status Report for UserExit
*************************************************
Total elapsed time: 40 days 2:35:00.012 [total = 3465300 sec = 57755 min = 962 hr ] => Total time since first event
Event processing time: 1:35:35.979 [total = 5735 sec = 95 min = 1 hr ] => Time spent sending msgs (max: 160 ms)
Metadata process time: 0:00:00.005 [total = 5 ms ] => Time spent receiving metadata (14 tables, 1978 columns)
Operations Received/Sent: 25834852 / 25834852
Rate (overall): 7 op/s (peak: 2182 op/s)
(per event): 4504 op/s
Transactions Received/Sent: 61653 / 61653
Rate (overall): 0 tx/s (peak: 1 tx/s)
(per event): 10 tx/s
25834852 records processed as of Mon May 18 12:46:47 PDT 2020 (rate 12/sec, delta 367)
*************************************************
-------------------------------------------------
Handler: "filewriter": filewriter(FileWriterHandler)
*************************************************
DEBUG 2020-05-18 12:46:51.000167 [FutureTaskScheduler] - Scheduling Future Task for Callable oracle.goldengate.handler.filewriter.AvroFileWriterManager.FileRollTask
INFO 2020-05-18 12:46:51.000167 [FutureTaskScheduler] - New Thread Added to the pool, Name[TaskEngine_78920:132654]
DEBUG 2020-05-18 12:46:51.000167 [TaskEngine_78920] - In BeforeExecute for FileRollTask
DEBUG 2020-05-18 12:46:51.000168 [TaskEngine_78920(FileRollTask)] - Roll Task running
INFO 2020-05-18 12:46:51.000168 [TaskEngine_78920(FileRollTask)] - New Thread Added to the pool, Name[TaskEngine_78921:132655]
DEBUG 2020-05-18 12:46:51.000168 [TaskEngine_78920] - In AfterExecute for FileRollTask
DEBUG 2020-05-18 12:46:51.000168 [TaskEngine_78921] - In BeforeExecute for FileFinalizeTask
DEBUG 2020-05-18 12:46:51.000168 [TaskEngine_78920] - Execution Complete for oracle.goldengate.handler.filewriter.AvroFileWriterManager$FileRollTask, ElapsedTime[0.79772ms] CPU Time[374,353ns] Enqueue Time[0ms] Wait Count[0] Wait Time[0m
s] Block Count[0] Block Time[0ms]
DEBUG 2020-05-18 12:46:52.000369 [TaskEngine_78920] - In AfterExecute for FileFinalizeTask
DEBUG 2020-05-18 12:46:52.000369 [TaskEngine_78920] - Execution Complete for oracle.goldengate.handler.filewriter.FileFinalizeManager$FileFinalizeTask, ElapsedTime[174.78134ms] CPU Time[223,899ns] Enqueue Time[0ms] Wait Count[1] Wait Tim
e[174ms] Block Count[0] Block Time[0ms]
DEBUG 2020-05-18 12:47:17.000291 [pool-2-thread-1] - UserExitDataSource.getStatusReport
DEBUG 2020-05-18 12:47:17.000292 [pool-2-thread-1] - [115512] getStatusReport: Mon May 18 12:47:17 PDT 2020
INFO 2020-05-18 12:47:17.000292 [pool-2-thread-1] - Memory at Status : Max: 455.50 MB, Total: 296.50 MB, Free: 165.63 MB, Used: 130.87 MB
INFO 2020-05-18 12:47:17.000292 [pool-2-thread-1] - Status report: Mon May 18 12:47:17 PDT 2020
*************************************************
Status Report for UserExit
*************************************************
Total elapsed time: 40 days 2:35:30.012 [total = 3465330 sec = 57755 min = 962 hr ] => Total time since first event
Event processing time: 1:35:35.979 [total = 5735 sec = 95 min = 1 hr ] => Time spent sending msgs (max: 160 ms)
Metadata process time: 0:00:00.005 [total = 5 ms ] => Time spent receiving metadata (14 tables, 1978 columns)
Operations Received/Sent: 25834852 / 25834852
Rate (overall): 7 op/s (peak: 2182 op/s)
(per event): 4504 op/s
Transactions Received/Sent: 61653 / 61653
Rate (overall): 0 tx/s (peak: 1 tx/s)
(per event): 10 tx/s
25834852 records processed as of Mon May 18 12:47:17 PDT 2020 (rate 0/sec, delta 0)
*************************************************
-------------------------------------------------
Handler: "filewriter": filewriter(FileWriterHandler)
*************************************************
INFO 2020-05-18 12:47:22.000195 [TaskEngine_78924] - Thread[TaskEngine_78924:132660] Removed from the pool. Tasks Executed[1] CPU Usage[0]
INFO 2020-05-18 12:47:22.000202 [TaskEngine_78921] - Thread[TaskEngine_78921:132655] Removed from the pool. Tasks Executed[2] CPU Usage[0]
INFO 2020-05-18 12:47:22.000224 [TaskEngine_78923] - Thread[TaskEngine_78923:132658] Removed from the pool. Tasks Executed[1] CPU Usage[0]
INFO 2020-05-18 12:47:22.000311 [TaskEngine_78922] - Thread[TaskEngine_78922:132657] Removed from the pool. Tasks Executed[2] CPU Usage[0]
INFO 2020-05-18 12:47:22.000369 [TaskEngine_78920] - Thread[TaskEngine_78920:132654] Removed from the pool. Tasks Executed[2] CPU Usage[0]
DEBUG 2020-05-18 12:47:47.000291 [pool-2-thread-1] - UserExitDataSource.getStatusReport
DEBUG 2020-05-18 12:47:47.000292 [pool-2-thread-1] - [115513] getStatusReport: Mon May 18 12:47:47 PDT 2020
INFO 2020-05-18 12:47:47.000292 [pool-2-thread-1] - Memory at Status : Max: 455.50 MB, Total: 296.50 MB, Free: 165.60 MB, Used: 130.90 MB
INFO 2020-05-18 12:47:47.000292 [pool-2-thread-1] - Status report: Mon May 18 12:47:47 PDT 2020
*************************************************
Status Report for UserExit
*************************************************
Total elapsed time: 40 days 2:36:00.012 [total = 3465360 sec = 57756 min = 962 hr ] => Total time since first event
Event processing time: 1:35:35.979 [total = 5735 sec = 95 min = 1 hr ] => Time spent sending msgs (max: 160 ms)
Metadata process time: 0:00:00.005 [total = 5 ms ] => Time spent receiving metadata (14 tables, 1978 columns)
Operations Received/Sent: 25834852 / 25834852
Rate (overall): 7 op/s (peak: 2182 op/s)
(per event): 4504 op/s
Transactions Received/Sent: 61653 / 61653
Rate (overall): 0 tx/s (peak: 1 tx/s)
(per event): 10 tx/s
25834852 records processed as of Mon May 18 12:47:47 PDT 2020 (rate 0/sec, delta 0)
*************************************************
-------------------------------------------------
Handler: "filewriter": filewriter(FileWriterHandler)
*************************************************
... View more