Hi! The gravity form submission of a form on our website takes about 10-12s to submit successfully, which is too long. The logs shows about 7s is taken for processing and 4-5s for sending email notification using an external SMTP provider, SMTP2GO. There are no other errors on the log. We use LiteSpeed cache on the website and we have already tried disabling it, but doesn’t help. Can you please help?
Thanks!
Enabling background processing of notifications will shave off those 4-5 seconds.
As for the initial 10-12s, you could try Stripe Checkout and see if that helps: Overview of Stripe Payment Collection Methods
Thanks Jake,
We enabled asynchronous processing but could not find any difference in processing time; and then noticed that the Stripe add-on doesn’t support background processing - Gravity Forms: Background Feed Processing Add-On Support.
Beow is the log file that is still taking 12s. Is there anything else we can do to reduce the processing time.
2024-03-01 14:01:31.872301 - DEBUG → GFFormDisplay::process_form(): Starting to process form (#6) submission.
2024-03-01 14:01:31.877763 - DEBUG → GFFormDisplay::process_form(): Source page number: 1. Target page number: 0.
2024-03-01 14:01:31.877887 - DEBUG → GFFormDisplay::validate(): Starting for form #6.
2024-03-01 14:01:31.877966 - DEBUG → GFFormDisplay::validate(): Checking restrictions.
2024-03-01 14:01:31.878895 - DEBUG → GFFormDisplay::validate(): Completed restrictions. Starting field validation.
2024-03-01 14:01:31.879226 - DEBUG → GFFormDisplay::validate_character_encoding(): Starting invalid characters validation for field: Applicant name (3 - name)
2024-03-01 14:01:31.881107 - DEBUG → GFFormDisplay::validate_character_encoding(): gf_entry_meta meta_value charset = utf8mb4
2024-03-01 14:01:31.881184 - DEBUG → GFFormDisplay::validate_character_encoding(): reflecting methods
2024-03-01 14:01:31.881266 - DEBUG → GFFormDisplay::validate_character_encoding(): Completed in 0.002043 seconds. Value is valid ascii
2024-03-01 14:01:31.881584 - DEBUG → GFFormDisplay::validate_character_encoding(): Starting invalid characters validation for field: Delivery address (4 - address)
2024-03-01 14:01:31.881642 - DEBUG → GFFormDisplay::validate_character_encoding(): Completed in 0.000059 seconds. Value is valid ascii
2024-03-01 14:01:31.882858 - DEBUG → GFFormDisplay::validate(): Field validation completed in 0.003841 seconds.
2024-03-01 14:01:31.882958 - DEBUG → GFFormDisplay::validate(): Executing functions hooked to gform_validation.
2024-03-01 14:01:31.884429 - DEBUG → GFFormsModel::create_lead(): Draft entry created for form (#6) in 0.001350 seconds.
2024-03-01 14:01:32.428746 - DEBUG → GFCommon::is_spam_entry(): Result from Akismet: false
2024-03-01 14:01:32.428894 - DEBUG → GFCommon::is_spam_entry(): Executing functions hooked to gform_entry_is_spam.
2024-03-01 14:01:32.428977 - DEBUG → GFCommon::is_spam_entry(): Result from gform_entry_is_spam filter: false
2024-03-01 14:01:32.429023 - DEBUG → GFCommon::is_spam_entry(): Spam checks completed in 0.543496 seconds. Is submission considered spam? No.
2024-03-01 14:01:35.715440 - DEBUG → GFFormDisplay::validate(): Completed gform_validation.
2024-03-01 14:01:35.715507 - DEBUG → GFFormDisplay::process_form(): After validation. Is submission valid? Yes.
2024-03-01 14:01:35.715615 - DEBUG → GFFormDisplay::process_form(): Submission is valid. Moving forward.
2024-03-01 14:01:35.715682 - DEBUG → GFFormDisplay::process_form(): Executing functions hooked to gform_pre_submission.
2024-03-01 14:01:35.715960 - DEBUG → GFFormDisplay::process_form(): Completed gform_pre_submission.
2024-03-01 14:01:35.716186 - DEBUG → GFFormsModel::save_entry(): Saving entry.
2024-03-01 14:01:35.722732 - DEBUG → GFFormsModel::save_entry(): Entry record created in the database. ID: 8720.
2024-03-01 14:01:35.724883 - DEBUG → GFFormsModel::save_entry(): Saving entry fields.
2024-03-01 14:01:35.725193 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Organisation (if applicable)(#1 - text).
2024-03-01 14:01:35.725296 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Applicant name(#3.2 - name).
2024-03-01 14:01:35.725374 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Applicant name(#3.3 - name).
2024-03-01 14:01:35.725435 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Applicant name(#3.4 - name).
2024-03-01 14:01:35.725500 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Applicant name(#3.6 - name).
2024-03-01 14:01:35.725558 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Applicant name(#3.8 - name).
2024-03-01 14:01:35.725642 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Email address(#5 - email).
2024-03-01 14:01:35.725754 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Delivery address(#4.1 - address).
2024-03-01 14:01:35.725837 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Delivery address(#4.2 - address).
2024-03-01 14:01:35.725946 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Delivery address(#4.3 - address).
2024-03-01 14:01:35.726042 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Delivery address(#4.4 - address).
2024-03-01 14:01:35.726109 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Delivery address(#4.5 - address).
2024-03-01 14:01:35.726185 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Delivery address(#4.6 - address).
2024-03-01 14:01:35.726423 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Eligibility for MLAK(#15 - radio).
2024-03-01 14:01:35.726629 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Declaration of eligibility(#7.1 - consent).
2024-03-01 14:01:35.726733 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Declaration of eligibility(#7.2 - consent).
2024-03-01 14:01:35.726802 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Declaration of eligibility(#7.3 - consent).
2024-03-01 14:01:35.726932 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Quantity of MLAK keys required(#9.1 - product).
2024-03-01 14:01:35.727022 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Quantity of MLAK keys required(#9.2 - product).
2024-03-01 14:01:35.727093 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Quantity of MLAK keys required(#9.3 - product).
2024-03-01 14:01:35.727251 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Credit Card(#14.1 - stripe_creditcard).
2024-03-01 14:01:35.727346 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Credit Card(#14.4 - stripe_creditcard).
2024-03-01 14:01:35.751302 - DEBUG → GFFormsModel::queue_save_input_value(): Queued field operation: Total(#11 - total).
2024-03-01 14:01:35.754570 - DEBUG → GFFormsModel::save_entry(): Finished saving entry fields.
2024-03-01 14:01:35.754690 - DEBUG → GFFormsModel::save_entry(): Saving entry completed in 0.038523 seconds.
2024-03-01 14:01:35.754805 - DEBUG → GFFormsModel::set_entry_meta(): Saving meta for entry (#8720) completed in 0.000015 seconds.
2024-03-01 14:01:35.754885 - DEBUG → GFFormDisplay::handle_submission(): Executing functions hooked to gform_entry_post_save.
2024-03-01 14:01:38.209336 - DEBUG → GFAPI::send_notifications(): Gathering notifications for complete_payment event for entry #8720.
2024-03-01 14:01:38.209476 - DEBUG → GFAPI::send_notifications(): Aborting. No notifications to process for complete_payment event for entry #8720.
2024-03-01 14:01:38.228111 - DEBUG → GFFormDisplay::handle_submission(): Completed gform_entry_post_save.
2024-03-01 14:01:38.233736 - DEBUG → GF_Background_Process::dispatch(): Running for gf_feed_processor.
2024-03-01 14:01:38.234752 - DEBUG → GF_Background_Process::schedule_event(): Scheduling cron event for gf_feed_processor.
2024-03-01 14:01:40.504292 - DEBUG → GF_Background_Process::maybe_handle(): Running for gf_feed_processor.
2024-03-01 14:01:40.509749 - DEBUG → GF_Background_Process::handle(): Processing batch wp_gf_feed_processor_batch_blog_id_1_d0219fc1d66509c98d72dd98c2c; Tasks: 1.
2024-03-01 14:01:40.509860 - DEBUG → GF_Background_Process::handle(): Processing task 1.
2024-03-01 14:01:40.516391 - DEBUG → GF_Background_Process::update(): Updating batch wp_gf_feed_processor_batch_blog_id_1_d0219fc1d66509c98d72dd98c2c. Tasks remaining: 1.
2024-03-01 14:01:40.524940 - DEBUG → GF_Background_Process::handle(): Removing task 1 from batch.
2024-03-01 14:01:40.525070 - DEBUG → GF_Background_Process::delete(): Deleting batch wp_gf_feed_processor_batch_blog_id_1_d0219fc1d66509c98d72dd98c2c.
2024-03-01 14:01:40.530508 - DEBUG → GF_Background_Process::handle(): Batch completed for gf_feed_processor.
2024-03-01 14:01:40.532975 - DEBUG → GF_Background_Process::clear_scheduled_event(): Clearing cron event for gf_feed_processor.
2024-03-01 14:01:40.561429 - DEBUG → GFFormsModel::create_post(): Starting.
2024-03-01 14:01:40.561780 - DEBUG → GFFormsModel::create_post(): Stopping. The form doesn’t have any post fields.
2024-03-01 14:01:40.561875 - DEBUG → GFAPI::send_notifications(): Gathering notifications for form_submission event for entry #8720.
2024-03-01 14:01:40.563781 - DEBUG → GFAPI::send_notifications(): Adding 1 notification(s) to the async processing queue for entry #8720.
2024-03-01 14:01:40.566402 - DEBUG → GF_Background_Process::dispatch(): Running for gf_notifications_processor.
2024-03-01 14:01:40.567821 - DEBUG → GF_Background_Process::schedule_event(): Scheduling cron event for gf_notifications_processor.
2024-03-01 14:01:40.894896 - DEBUG → GF_Query::query(): sql => SELECT SQL_CALC_FOUND_ROWS DISTINCT t1.id FROM wp_gf_entry AS t1 LEFT JOIN wp_gf_entry_meta AS m2 ON (m2.entry_id = t1.id AND m2.meta_key = ‘payment_element_intent_id’) WHERE (m2.meta_key = ‘payment_element_intent_id’ AND m2.meta_value = ‘pi_3OpMLlLQ18IO6EI51SHvR4pg’) ORDER BY t1.id DESC LIMIT 20
2024-03-01 14:01:42.580856 - DEBUG → GF_Background_Process::maybe_handle(): Running for gf_notifications_processor.
2024-03-01 14:01:42.587594 - DEBUG → GF_Background_Process::handle(): Processing batch wp_gf_notifications_processor_batch_blog_id_1_bb5084d17dbe641c1a; Tasks: 1.
2024-03-01 14:01:42.587690 - DEBUG → GF_Background_Process::handle(): Processing task 1.
2024-03-01 14:01:42.590447 - DEBUG → GFCommon::send_notifications(): Processing notifications for form_submission event for entry #8720: Array
(
[0] => 62847899a4f7a
)
(only active/applicable notifications are sent)
2024-03-01 14:01:42.590544 - DEBUG → GFCommon::send_notification(): Starting to process notification (#62847899a4f7a - National email notification).
2024-03-01 14:01:42.605539 - DEBUG → GFCommon::send_notification(): No file(s) to attach for field #6
2024-03-01 14:01:42.605837 - DEBUG → GFCommon::send_email(): Sending email via wp_mail().
2024-03-01 14:01:42.605930 - DEBUG → Array
(
[to] => admin@masterlocksmiths.com.au
[subject] => New submission from MLAK order form
[message] =>
New submission from MLAK order form
</body>
[attachments] => Array
(
)
[abort_email] =>
)
2024-03-01 14:01:44.618673 - DEBUG → GFCommon::send_email(): Result from wp_mail(): 1
2024-03-01 14:01:44.618818 - DEBUG → GFCommon::send_email(): WordPress successfully passed the notification email (#62847899a4f7a - National email notification) for entry #8720 to the sending server.
2024-03-01 14:01:44.618863 - DEBUG → GFCommon::send_email(): The WordPress phpmailer_init hook has been detected, usually used by SMTP plugins. It can alter the email setup/content or sending server, and impact the notification deliverability.
2024-03-01 14:01:44.630808 - DEBUG → GFCommon::send_notifications(): Sending notifications for entry (#8720) completed in 2.040347 seconds.
2024-03-01 14:01:44.630935 - DEBUG → GF_Background_Process::handle(): Removing task 1 from batch.
2024-03-01 14:01:44.631014 - DEBUG → GF_Background_Process::delete(): Deleting batch wp_gf_notifications_processor_batch_blog_id_1_bb5084d17dbe641c1a.
2024-03-01 14:01:44.636599 - DEBUG → GF_Background_Process::handle(): Batch completed for gf_notifications_processor.
2024-03-01 14:01:44.640024 - DEBUG → GF_Background_Process::clear_scheduled_event(): Clearing cron event for gf_notifications_processor.
2024-03-01 14:01:44.667398 - DEBUG → GFFormDisplay::handle_confirmation(): Preparing confirmation (#62846099d9b0f - Default Confirmation).
2024-03-01 14:01:44.669466 - DEBUG → GFFormDisplay::handle_confirmation(): Executing functions hooked to gform_confirmation.
2024-03-01 14:01:44.669588 - DEBUG → GFFormDisplay::handle_confirmation(): Completed gform_confirmation.
2024-03-01 14:01:44.669670 - DEBUG → GFFormDisplay::handle_confirmation(): Confirmation to be used =>
2024-03-01 14:01:44.669753 - DEBUG → GFFormDisplay::process_form(): Executing functions hooked to gform_after_submission.
2024-03-01 14:01:44.670512 - DEBUG → GFFormDisplay::process_form(): Completed gform_after_submission.
2024-03-01 14:01:44.670643 - DEBUG → GFFormDisplay::process_form(): Processing completed in 12.798943 seconds.
2024-03-01 14:01:44.674973 - DEBUG → Gravity_Forms\Gravity_Forms\Duplicate_Submissions\GF_Duplicate_Submissions_Handler::is_valid_submission(): form #6. entry #8720.
2024-03-01 14:01:44.687813 - DEBUG → GFFormDisplay::get_form(): Preparing form (#6) confirmation completed in 0.016497 seconds.
2024-03-01 14:01:54.211607 - DEBUG → GF_Background_Process::handle_cron_healthcheck(): Running for gf_feed_processor.
2024-03-01 14:01:54.217670 - DEBUG → GF_Background_Process::clear_scheduled_event(): Clearing cron event for gf_feed_processor.
Interesting! Also waiting for Team Gravity to respond to your latest finding.
PS: We’ll run some tests next week and report back here.
Cheers!
This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.