Skip to content

Duplicated, or other request-related logs in a request's log section #1663

Description

@andergmartins

Expected Behavior

When the log is grouped by request, we expect to see in the group only registers related to that request. Also, no duplicated entries.

Current Behavior

Some logs are groupping what seem to be different requests on the same request ID.

------------------------------------------------------------
Request ID: 6a500019ef163
2026-07-09 20:10:01.989: DEBUG: [Plugin] Reviews module has been initialized successfully.
2026-07-09 20:10:01.989: DEBUG: [Plugin] Notices module has been initialized successfully.
2026-07-09 20:10:01.989: DEBUG: [Plugin] PublishPress\Future\Modules\Debug\Module module has been initialized successfully.
2026-07-09 20:10:01.989: DEBUG: [Plugin] PublishPress\Future\Modules\InstanceProtection\Module module has been initialized successfully.
2026-07-09 20:10:01.990: DEBUG: [Plugin] PublishPress\Future\Modules\VersionNotices\Module module has been initialized successfully.
2026-07-09 20:10:01.990: DEBUG: [Plugin] PublishPress\Future\Modules\Expirator\Module module has been initialized successfully.
2026-07-09 20:10:01.990: DEBUG: [Plugin] PublishPress\Future\Modules\Settings\Module module has been initialized successfully.
2026-07-09 20:10:01.990: DEBUG: [Plugin] PublishPress\Future\Modules\WooCommerce\Module module has been initialized successfully.
2026-07-09 20:10:01.995: DEBUG: [Plugin] PublishPress\Future\Modules\Workflows\Module module has been initialized successfully.
2026-07-09 20:10:01.996: DEBUG: [Plugin] PublishPress\Future\Modules\Backup\Module module has been initialized successfully.
2026-07-09 20:10:02.021: DEBUG: [WorkflowEngine] Engine starting with execution id "fcc6d72ef47f7407ed61ff337752c349" for user #guest (cron)
2026-07-09 20:10:02.022: DEBUG: [WorkflowEngine] Engine ready, listening for triggers
2026-07-09 20:10:02.022: DEBUG: [WorkflowEngine] Loading all published workflows
2026-07-09 20:10:02.025: DEBUG: [WorkflowEngine] All workflows are ready
2026-07-09 20:10:02.104: DEBUG: [WorkflowEngine] Engine finished processing request (83 ms)
2026-07-09 20:10:02.105: DEBUG: Shutdown
------------------------------------------------------------
Request ID: 6a5000651fa72
2026-07-09 20:11:17.138: DEBUG: [Plugin] Reviews module has been initialized successfully.
2026-07-09 20:11:17.138: DEBUG: [Plugin] Reviews module has been initialized successfully.
2026-07-09 20:11:17.138: DEBUG: [Plugin] Notices module has been initialized successfully.
2026-07-09 20:11:17.138: DEBUG: [Plugin] Notices module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Debug\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Debug\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\InstanceProtection\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\VersionNotices\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\InstanceProtection\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\VersionNotices\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Expirator\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Settings\Module module has been initialized successfully.
2026-07-09 20:11:17.140: DEBUG: [Plugin] PublishPress\Future\Modules\WooCommerce\Module module has been initialized successfully.
2026-07-09 20:11:17.140: DEBUG: [Plugin] PublishPress\Future\Modules\Expirator\Module module has been initialized successfully.
2026-07-09 20:11:17.140: DEBUG: [Plugin] PublishPress\Future\Modules\Settings\Module module has been initialized successfully.
2026-07-09 20:11:17.140: DEBUG: [Plugin] PublishPress\Future\Modules\WooCommerce\Module module has been initialized successfully.
2026-07-09 20:11:17.144: DEBUG: [Plugin] PublishPress\Future\Modules\Workflows\Module module has been initialized successfully.
2026-07-09 20:11:17.144: DEBUG: [Plugin] PublishPress\Future\Modules\Workflows\Module module has been initialized successfully.
2026-07-09 20:11:17.144: DEBUG: [Plugin] PublishPress\Future\Modules\Backup\Module module has been initialized successfully.
2026-07-09 20:11:17.145: DEBUG: [Plugin] PublishPress\Future\Modules\Backup\Module module has been initialized successfully.
2026-07-09 20:11:17.170: DEBUG: [WorkflowEngine] Engine starting with execution id "8e5790a3c32a42c73b36fe0d719c614c" for user #guest (frontend)
2026-07-09 20:11:17.170: DEBUG: [WorkflowEngine] Engine starting with execution id "7a2ea62e11d557cd21be0f9e06a13551" for user #guest (frontend)
2026-07-09 20:11:17.170: DEBUG: [WorkflowEngine] Engine ready, listening for triggers
2026-07-09 20:11:17.170: DEBUG: [WorkflowEngine] Engine ready, listening for triggers
2026-07-09 20:11:17.171: DEBUG: [WorkflowEngine] Loading all published workflows
2026-07-09 20:11:17.171: DEBUG: [WorkflowEngine] Loading all published workflows
2026-07-09 20:11:17.174: DEBUG: [WorkflowEngine] All workflows are ready
2026-07-09 20:11:17.174: DEBUG: [WorkflowEngine] All workflows are ready
2026-07-09 20:11:17.508: DEBUG: [WorkflowEngine] Engine finished processing request (338 ms)
2026-07-09 20:11:17.508: DEBUG: Shutdown
2026-07-09 20:11:17.514: DEBUG: [WorkflowEngine] Engine finished processing request (344 ms)
2026-07-09 20:11:17.515: DEBUG: Shutdown
------------------------------------------------------------
Request ID: 6a5000651fb43
2026-07-09 20:11:17.138: DEBUG: [Plugin] Reviews module has been initialized successfully.
2026-07-09 20:11:17.138: DEBUG: [Plugin] Notices module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Debug\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\InstanceProtection\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\VersionNotices\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Expirator\Module module has been initialized successfully.
2026-07-09 20:11:17.139: DEBUG: [Plugin] PublishPress\Future\Modules\Settings\Module module has been initialized successfully.
2026-07-09 20:11:17.140: DEBUG: [Plugin] PublishPress\Future\Modules\WooCommerce\Module module has been initialized successfully.
2026-07-09 20:11:17.144: DEBUG: [Plugin] PublishPress\Future\Modules\Workflows\Module module has been initialized successfully.
2026-07-09 20:11:17.145: DEBUG: [Plugin] PublishPress\Future\Modules\Backup\Module module has been initialized successfully.
2026-07-09 20:11:17.171: DEBUG: [WorkflowEngine] Engine starting with execution id "99dd58fee7ce99dd9807582d0122f30a" for user #guest (frontend)
2026-07-09 20:11:17.171: DEBUG: [WorkflowEngine] Engine ready, listening for triggers
2026-07-09 20:11:17.171: DEBUG: [WorkflowEngine] Loading all published workflows
2026-07-09 20:11:17.174: DEBUG: [WorkflowEngine] All workflows are ready
2026-07-09 20:11:18.059: DEBUG: [WorkflowEngine] Engine finished processing request (888 ms)
2026-07-09 20:11:18.059: DEBUG: Shutdown
------------------------------------------------------------

Possible Solution

Double-check if the log registers are being directly inserted into the DB or file when log functions are called in the code. If so, we could try to store it in the memory and delay the insertion for the end of the request or shutfown action. Insert all the request log in the same operation. One possible downside is there is a chance the log in the memory is lost in case the request is interrupted.

Activity

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

No one assigned

    Labels

    confirmed bugThe reporter has tested and confirmed on more than 1 site

    Type

    Projects

    No projects

      Milestone

      No milestone

      Relationships

      None yet

      Development

      No branches or pull requests

      Issue actions