Conversion Document to Pdf Attemping to purge from DB too slow

[Julien2]

Hi 

Here 2 trace log for conversion any document  to Pdf 

On ALC 

2022-06-23 09:23:58,950 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-024-File.jpg: Time Job Submitted: 6/23/22 9:23 AM for job = File.jpg3cd9aa-a58661-2a4aac-832532-0cad9a-4d6c6e
2022-06-23 09:23:58,950 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-017-File.jpg: Job Submitted by User: usersystemcontext
2022-06-23 09:23:58,950 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-016-File.jpg: Job Type: Convert to PDF
2022-06-23 09:23:58,950 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-022-File.jpg: Security Settings Used: No Security
2022-06-23 09:23:58,950 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-023-File.jpg: FileType Settings Used: PDFa
2022-06-23 09:23:58,950 INFO [com.adobe.service.J2EEConnectionFactoryManagerPeerImpl] (http-0.0.0.0-8080-127) Service: Img2PDFSvc resource: ProcessResource@6bee2cac(name=java.exe,pid=0) applying queue wait adjustment of 0s to timeout of transaction: TransactionImple < ac, BasicAction: 555ba967:d5cd:6133ea63:2917ee0 status: ActionStatus.RUNNING >. Supplied timeout: 300s, Effective timeout: 300s.
2022-06-23 09:23:58,950 INFO [com.adobe.service.J2EEConnectionFactoryManagerPeerImpl] (http-0.0.0.0-8080-127) Service: Img2PDFSvc successfully scheduled an interrupt for transaction: TransactionImple < ac, BasicAction: 555ba967:d5cd:6133ea63:2917ee0 status: ActionStatus.RUNNING > after 300s.
2022-06-23 09:23:58,965 INFO [com.adobe.image2pdf.bmc.Image2PDFConverterInternal] (RequestProcessor-2446) ALC-PDG-001-001-Begin processing job : File.jpg
2022-06-23 09:23:58,965 INFO [com.adobe.image2pdf.bmc.Image2PDFConverterInternal] (RequestProcessor-2446) ALC-PDG-001-000-Invoking jpeg specific converter
2022-06-23 09:23:58,965 INFO [com.adobe.image2pdf.bmc.Image2PDFConverterInternal] (RequestProcessor-2446) ALC-PDG-001-002-Done processing job : File.jpg
2022-06-23 09:23:58,981 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-027-File.jpg: Job Completion Status: Succeeded
2022-06-23 09:23:58,981 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-025-File.jpg: Time Job Completed: 6/23/22 9:23 AM for job = File.jpg3cd9aa-a58661-2a4aac-832532-0cad9a-4d6c6e
2022-06-23 09:23:58,981 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-030-File.jpg: Waiting time in queue: 0 ms for job = File.jpg3cd9aa-a58661-2a4aac-832532-0cad9a-4d6c6e
2022-06-23 09:23:58,981 INFO [com.adobe.pdfg.BMCCaller] (http-0.0.0.0-8080-127) ALC-PDG-001-026-File.jpg: Time of Job Execution: 31 ms for job = File.jpg3cd9aa-a58661-2a4aac-832532-0cad9a-4d6c6e
2022-06-23 09:23:59,012 INFO [com.adobe.idp.Document] (http-0.0.0.0-8080-127) Attempting to purge document Invocation-adobejb_wpextdsp001a-f51a1f466e7b4789a958f332f3325d25-1964979 from DB...

ON AEM  6.5.6  

08:10:23,546 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-024-File.jpg: Time Job Submitted: 6/23/22 8:10 AM for job = File.jpg1ba598-97c483-e082d2-ddab1b-dc0bc8-7bb0b3
08:10:23,546 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-017-File.jpg: Job Submitted by User: usersystemcontext
08:10:23,546 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-016-File.jpg: Job Type: Convert to PDF
08:10:23,548 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-022-File.jpg: Security Settings Used: No Security
08:10:23,548 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-023-File.jpg: FileType Settings Used: PDFa
08:10:23,549 INFO [com.adobe.service.J2EEConnectionFactoryManagerPeerImpl] (default task-41) Service: Img2PDFSvc resource: ProcessResource@704f8245(name=java.exe,pid=0) applying queue wait adjustment of 0s to timeout of transaction: Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff64421724:-2b222045:62a8a1d2:35c0a2 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider). Supplied timeout: 300s, Effective timeout: 300s.
08:10:23,549 INFO [com.adobe.service.J2EEConnectionFactoryManagerPeerImpl] (default task-41) Service: Img2PDFSvc successfully scheduled an interrupt for transaction: Local transaction (delegate=TransactionImple < ac, BasicAction: 0:ffff64421724:-2b222045:62a8a1d2:35c0a2 status: ActionStatus.RUNNING >, owner=Local transaction context for provider JBoss JTA transaction provider) after 300s.
08:10:23,557 INFO [com.adobe.image2pdf.bmc.Image2PDFConverterInternal] (p: default-threadpool; w: Idle) ALC-PDG-001-001-Begin processing job : File.jpg
08:10:23,557 INFO [com.adobe.image2pdf.bmc.Image2PDFConverterInternal] (p: default-threadpool; w: Idle) ALC-PDG-001-000-Invoking jpeg specific converter
08:10:23,564 INFO [com.adobe.image2pdf.bmc.Image2PDFConverterInternal] (p: default-threadpool; w: Idle) ALC-PDG-001-002-Done processing job : File.jpg
08:10:23,639 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-027-File.jpg: Job Completion Status: Succeeded
08:10:23,639 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-025-File.jpg: Time Job Completed: 6/23/22 8:10 AM for job = File.jpg1ba598-97c483-e082d2-ddab1b-dc0bc8-7bb0b3
08:10:23,639 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-030-File.jpg: Waiting time in queue: 0 ms for job = File.jpg1ba598-97c483-e082d2-ddab1b-dc0bc8-7bb0b3
08:10:23,639 INFO [com.adobe.pdfg.BMCCaller] (default task-41) ALC-PDG-001-026-File.jpg: Time of Job Execution: 103 ms for job = File.jpg1ba598-97c483-e082d2-ddab1b-dc0bc8-7bb0b3
08:10:28,175 INFO [com.adobe.idp.Document] (default task-41) Attempting to purge document Invocation-adobejb_AdminServer-484ba3e51da04657b19e4bbad9d3567a-788 from DB...

On the old system (ALC), the line of the document purge of the db is executed in the millisecond ( the lasts lines ) 

on the new system (AEM) the document purge starts 5 seconds later.

How can I reduce this time? like on ALC

[tusharbias]

@Julien2 

I would suggest you to enable DEBUG logs and see what activities are performed during this time.

This is not a apple to apple comparison every system behaves differently but Yes 5 sec lean time is lot. You need to discover more on this issue specially on Debug logs, also make sure system should not be under high load while you validate it.

[Julien2]

We put in debug mode but it didn't bring much. it seems that it is not possible to put the package com.adobe.idp.Document

[tusharbias · Accepted solution]

If debug logs is not the option then try to do the debugging on local by adding break points and see what is causing the issue. 

Your query, does not have a point to point solution rather you need to do the debugging for this either by debug logs or by adding breakpoints on local to see what is causing the delay.

Once you get the root cause, then start thinking for optimization.

[Julien2]

Hi 

To me, it seems more of a configuration issue.
These lines:
Attempting to purge document Invocation-adobejb_AdminServer-......from DB... come from AEM behavior

[Julien2]

It's not solved !