1

Edited 2017 08 01; The problem still exists despite upgrades to WordPress 4.8.0 / CiviCRM 4.7.22. Recent findings and advice indicate that the issue may be due to the fact that this payment is initiated from a private page, not a public page. I am investigating a potential solution and will document it here if the solution proves out.

Also, due to changes made over the past two weeks, the IDS messages are no longer appearing in the CiviCRM log, so there is some progress.

-----END EDIT-----

Using WordPress 4.7.5 and CiviCRM 4.7.19, a membership renewal transaction is entered in CiviCRM and paid via PayPal. The transaction completes in PayPal and an IPN is sent to CiviCRM. CiviCRM displays the thank-you page with transaction details, but in CiviCRM the payment remains in Pending status. The user gets a PayPal payment message, but no CiviCRM receipt.

Checking the CiviCRM log file in the ConfigAndLog folder shows this sequence of events (identifying elements overwritten with XXX):

Jun 05 14:19:25  [info] $IDS Detector Details = Array
(
    [0] => Array
        (
            [name] => cm
            [value] => {"module":"contribute","contactID":"4563","contributionID":78,"membershipID":"45","contributionPageID":"3"}
            [page] => /member-renewal-application/?page=CiviCRM&q=civicrm/contribute/transact&_qf_ThankYou_display=1&qfKey=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&amt=125.00&cc=USD&cm=%7B%22module%22%3A%22contribute%22%2C%22contactID%22%3A%224563%22%2C%22contributionID%22%3A78%2C%22membershipID%22%3A%2245%22%2C%22contributionPageID%22%3A%223%22%7D&item_name=4563-78-XXX%20Membership&st=Completed&tx=62S51295KD1681941
            [userid] => XXXX
            [session] => XXXXXXXXXXXXXXXXXXXXXXXXXX
            [ip] => XXX.XX.XX.45
            [reaction] => 0
            [impact] => 37
        )

    [1] => Array
        (
            [name] => IDS_request_uri
            [value] => /member-renewal-application/?page=CiviCRM&q=civicrm/contribute/transact&_qf_ThankYou_display=1&qfKey=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&amt=125.00&cc=USD&cm=%7B%22module%22%3A%22contribute%22%2C%22contactID%22%3A%224563%22%2C%22contributionID%22%3A78%2C%22membershipID%22%3A%2245%22%2C%22contributionPageID%22%3A%223%22%7D&item_name=4563-78-XXX%20Membership&st=Completed&tx=62S51295KD1681941
            [page] => /member-renewal-application/?page=CiviCRM&q=civicrm/contribute/transact&_qf_ThankYou_display=1&qfKey=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&amt=125.00&cc=USD&cm=%7B%22module%22%3A%22contribute%22%2C%22contactID%22%3A%224563%22%2C%22contributionID%22%3A78%2C%22membershipID%22%3A%2245%22%2C%22contributionPageID%22%3A%223%22%7D&item_name=4563-78-XXX%20Membership&st=Completed&tx=62S51295KD1681941
            [userid] => XXXX
            [session] => XXXXXXXXXXXXXXXXXXXXXXXXXX
            [ip] => XXX.XX.XX.45
            [reaction] => 0
            [impact] => 37
        )

)

A network log is available if details would help with analysis.

This looks similar to CRM-18245. The error is preventing renewals processing which is due this month for the organization. A fix is needed. Thanks.

Edit 2017 06 06; 16:00 PDT per @kevin (with extraneous health check records removed).

Network log shows:

127.0.0.1 - - [05/Jun/2017:14:19:19 -0700] "POST /wp-cron.php?doing_wp_cron=<id1>.<id2> HTTP/1.1" 200 3556 "https://<domain>.org/wp-cron.php?doing_wp_cron=<id1>.<id2>" "WordPress/4.7.5; https://<domain>.org"
173.0.81.1 - - [05/Jun/2017:14:19:19 -0700] "POST /member-renewal-application/?page=CiviCRM&q=civicrm/payment/ipn/3& HTTP/1.1" 404 42292 "-" "PayPal IPN ( https://www.paypal.com/ipn )"
76.115.xxx.xxx - - [05/Jun/2017:14:19:25 -0700] "GET /member-renewal-application/?page=CiviCRM&q=civicrm/contribute/transact&_qf_ThankYou_display=1&qfKey=<key>&amt=125.00&cc=USD&cm=%7B%22module%22%3A%22contribute%22%2C%22contactID%22%3A%224563%22%2C%22contributionID%22%3A78%2C%22membershipID%22%3A%2245%22%2C%22contributionPageID%22%3A%223%22%7D&item_name=4563-78-xxx%20Membership&st=Completed&tx=<tx> HTTP/1.1" 200 15436 "https://www.paypal.com/webapps/hermes?token=<token>&useraction=commit&rm=2&mfid=<fid>" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"
76.115.xxx.xxx - - [05/Jun/2017:14:19:25 -0700] "GET /member-renewal-application/?page=CiviCRM&q=civicrm/ajax/l10n-js/en_US&r=YrDT6 HTTP/1.1" 200 6206 "https://<domain>.org/member-renewal-application/?page=CiviCRM&q=civicrm/contribute/transact&_qf_ThankYou_display=1&qfKey=<key>&amt=125.00&cc=USD&cm=%7B%22module%22%3A%22contribute%22%2C%22contactID%22%3A%224563%22%2C%22contributionID%22%3A78%2C%22membershipID%22%3A%2245%22%2C%22contributionPageID%22%3A%223%22%7D&item_name=4563-78-xxx%20Membership&st=Completed&tx=<tx>" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"
173.0.81.1 - - [05/Jun/2017:14:19:32 -0700] "POST /member-renewal-application/?page=CiviCRM&q=civicrm/payment/ipn/3& HTTP/1.1" 404 42292 "-" "PayPal IPN ( https://www.paypal.com/ipn )"
173.0.81.1 - - [05/Jun/2017:14:19:55 -0700] "POST /member-renewal-application/?page=CiviCRM&q=civicrm/payment/ipn/3& HTTP/1.1" 404 42292 "-" "PayPal IPN ( https://www.paypal.com/ipn )"
127.0.0.1 - - [05/Jun/2017:14:20:01 -0700] "GET /wp-content/plugins/civicrm/civicrm/bin/cron.php?name=<name>&pass=<pass>&key=<key> HTTP/1.1" 200 3788 "-" "Wget/1.15 (linux-gnu)"
76.115.xxx.xxx - - [05/Jun/2017:14:20:29 -0700] "POST /wp-admin/admin-ajax.php HTTP/1.1" 200 3883 "https://<domain>.org/wp-admin/admin.php?page=CiviCRM&q=civicrm%2Fadmin%2Fuf%2Fgroup%2Ffield&reset=1&action=browse&gid=15" "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:53.0) Gecko/20100101 Firefox/53.0"
173.0.81.1 - - [05/Jun/2017:14:20:35 -0700] "POST /member-renewal-application/?page=CiviCRM&q=civicrm/payment/ipn/3& HTTP/1.1" 404 42292 "-" "PayPal IPN ( https://www.paypal.com/ipn )"
76.115.xxx.xxx - - [05/Jun/2017:14:21:56 -0700] "POST /wp-admin/admin-ajax.php HTTP/1.1" 200 806 "https://<domain>.org/wp-admin/admin.php?page=CiviCRM&q=civicrm%2Fadmin%2Fuf%2Fgroup%2Ffield&reset=1&action=browse&gid=15" "Mozilla/5.0 (Windows NT 10.0; WOW64; rv:53.0) Gecko/20100101 Firefox/53.0"
173.0.81.1 - - [05/Jun/2017:14:21:58 -0700] "POST /member-renewal-application/?page=CiviCRM&q=civicrm/payment/ipn/3& HTTP/1.1" 404 42292 "-" "PayPal IPN ( https://www.paypal.com/ipn )" 

@Kevin, others, this is the network activity log around the time of the ConfigAndLog entry. Let me know if it sheds any light on the error. I do see the IPN messages, note they appear before and after the time of interest. There were earlier failed transactions that are causing this. Looking at PayPal, the IPN log shows a number of transactions in retry.

  • 1
    anything before or after that block of data? The IPN gets a bit after the transaction is complete (sometimes almost immediately, sometimes there is a lag). You should see the transaction in your web server logs. Check there. We have many many sites with WP and CiviCRM using PayPal. Sometimes it is tough to find the failure, but if we can see the IPN transaction in the web server logs it helps. – Kevin Cristiano Jun 5 '17 at 23:34
  • As of 2017 07 28, this is still unresolved. I'll try again and see if I can close this one out... – dvhirst Jul 28 '17 at 18:07

Your Answer

By clicking “Post Your Answer”, you agree to our terms of service, privacy policy and cookie policy

Browse other questions tagged or ask your own question.