CiviCRM Community Forums (archive)

*

News:

Have a question about CiviCRM?
Get it answered quickly at the new
CiviCRM Stack Exchange Q+A site
This forum was archived on 25 November 2017. Learn more.
How to get involved.
What to do if you think you've found a bug.



  • CiviCRM Community Forums (archive) »
  • Old sections (read-only, deprecated) »
  • Support »
  • Using CiviCRM »
  • Using CiviContribute (Moderator: Donald Lobo) »
  • Resolved:Oh dear payment pending:Could not find an entry for invoice in POST
Pages: [1]

Author Topic: Resolved:Oh dear payment pending:Could not find an entry for invoice in POST  (Read 1512 times)

alanski

  • I post frequently
  • ***
  • Posts: 216
  • Karma: 5
  • Cup of tea? Yes please
    • Joomkit
  • CiviCRM version: Version in post
  • CMS version: Joomla
  • MySQL version: 5.0
Resolved:Oh dear payment pending:Could not find an entry for invoice in POST
February 21, 2012, 01:44:26 pm
See last reply :)
--------------------------------------------
Thought this might be smoother on 4.x  lol  :-\
Cant believe it still runs but hey ho

Have setup my sandbox etc and done a test drive contribution payment [non-recurring]

I am seeing sandbox in paypal is showing completed payment.
Civi record is created and contribution is there. Payment is pending.

I hit the ipn directly:
administrator/components/com_civicrm/civicrm/extern/ipn.php?reset=1&contactID=1212&contributionID=1&module=contribute

and watch the error log.

Error log shows: [info] Could not find an entry for invoice in POST

Am working thru ipn code to find error but getting nowhere so far.
Any ideas?



===============
CiviCRM 4.1 stable
Jooma 1.7
« Last Edit: February 23, 2012, 03:41:46 am by alanski »

alanski

  • I post frequently
  • ***
  • Posts: 216
  • Karma: 5
  • Cup of tea? Yes please
    • Joomkit
  • CiviCRM version: Version in post
  • CMS version: Joomla
  • MySQL version: 5.0
Payment pending debug info
February 21, 2012, 02:01:32 pm
SO heres debug/loginfo:
So is this related to email settings somehow? [Need fresh eyes and coffee perhaps.....but any Civi seasoned eyes that can elucidate welcome]

Code: [Select]
Feb 21 21:56:47  [info] Contribution record updated successfully

Feb 21 21:56:47  [info] Ignoring exception thrown by nullHandler: 0, fsockopen(): unable to connect to a:0 (Failed to parse address "a")

Feb 21 21:56:47  [info] $backTrace = /path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Error.php, backtrace, 547
, nullHandler,
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, call_user_func, 931
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, PEAR_Error, 566
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/Socket.php, raiseError, 156
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/SMTP.php, connect, 451
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, connect, 371
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, getSMTPObject, 254
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Utils/Mail.php, send, 160
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/BAO/MessageTemplates.php, send, 555
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Contribute/BAO/ContributionPage.php, sendTemplate, 330
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 953
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 592
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, completeTransaction, 254
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, single, 299
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/extern/ipn.php, main, 65

Feb 21 21:56:47  [info] Ignoring exception thrown by nullHandler: , Failed to connect socket: fsockopen(): unable to connect to a:0 (Failed to parse address "a")

Feb 21 21:56:47  [info] $backTrace = /path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Error.php, backtrace, 547
, nullHandler,
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, call_user_func, 931
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, PEAR_Error, 566
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/SMTP.php, raiseError, 454
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, connect, 371
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, getSMTPObject, 254
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Utils/Mail.php, send, 160
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/BAO/MessageTemplates.php, send, 555
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Contribute/BAO/ContributionPage.php, sendTemplate, 330
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 953
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 592
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, completeTransaction, 254
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, single, 299
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/extern/ipn.php, main, 65

Feb 21 21:56:47  [info] Ignoring exception thrown by nullHandler: 10001, Failed to connect to a:a [SMTP: Failed to connect socket: fsockopen(): unable to connect to a:0 (Failed to parse address "a") (code: -1, response: )]

Feb 21 21:56:47  [info] $backTrace = /path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Error.php, backtrace, 547
, nullHandler,
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, call_user_func, 931
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, PEAR_Error, 566
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, raiseError, 375
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, getSMTPObject, 254
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Utils/Mail.php, send, 160
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/BAO/MessageTemplates.php, send, 555
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Contribute/BAO/ContributionPage.php, sendTemplate, 330
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 953
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 592
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, completeTransaction, 254
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, single, 299
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/extern/ipn.php, main, 65

Feb 21 21:56:47  [info] Success: Database updated and mail sent

Feb 21 21:56:47  [info] $Fatal Error Details = Array
(
    [callback] => Array
        (
            [0] => CRM_Core_Error
            [1] => handle
        )

    [code] =>
    [message] => not connected
    [mode] => 16
    [debug_info] =>
    [type] => PEAR_Error
    [user_info] =>
    [to_string] => [pear_error: message="not connected" code=0 mode=callback callback=CRM_Core_Error::handle prefix="" info=""]
)


Feb 21 21:56:47  [info] $backTrace = /path/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Error.php, backtrace, 205
, handle,
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, call_user_func, 931
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, PEAR_Error, 566
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/Socket.php, raiseError, 327
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/SMTP.php, write, 264
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/SMTP.php, _send, 302
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Net/SMTP.php, _put, 493
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, disconnect, 420
/path/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/smtp.php, disconnect, 218
, _Mail_smtp,

alanski

  • I post frequently
  • ***
  • Posts: 216
  • Karma: 5
  • Cup of tea? Yes please
    • Joomkit
  • CiviCRM version: Version in post
  • CMS version: Joomla
  • MySQL version: 5.0
Need help understanding log :(
February 22, 2012, 01:16:31 pm
UPDATE:
Have got better log messages but still errors  :'(

Although below suggests contribution is updated and mail sent nothing is actually happening on the IPN front as the contribution record is not created.

Where is the best way to debug this/IPN issues?


Quote
Feb 22 21:06:33  [info] Contribution record updated successfully

Feb 22 21:06:33  [info] Ignoring exception thrown by nullHandler: , mail() returned failure

Feb 22 21:06:33  [info] $backTrace = /home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Error.php, backtrace, 547
, nullHandler,
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, call_user_func, 931
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/PEAR.php, PEAR_Error, 566
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/packages/Mail/mail.php, raiseError, 174
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Utils/Mail.php, send, 160
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/BAO/MessageTemplates.php, send, 555
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Contribute/BAO/ContributionPage.php, sendTemplate, 330
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 953
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/BaseIPN.php, sendMail, 592
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, completeTransaction, 254
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Core/Payment/PayPalIPN.php, single, 299
/home/mypath/public_html/administrator/components/com_civicrm/civicrm/extern/ipn.php, main, 65

Feb 22 21:06:33  [info] Success: Database updated and mail sent

Donald Lobo

  • Administrator
  • I’m (like) Lobo ;)
  • *****
  • Posts: 15963
  • Karma: 470
    • CiviCRM site
  • CiviCRM version: 4.2+
  • CMS version: Drupal 7, Joomla 2.5+
  • MySQL version: 5.5.x
  • PHP version: 5.4.x
Re: Oh dear payment pending again.err:Could not find an entry for invoice in POST
February 22, 2012, 02:21:23 pm

I would first track and figure out why "mail" is returning a failure. The backtrace gives you the specific files and lines where the error is being thrown

/home/mypath/public_html/administrator/components/com_civicrm/civicrm/CRM/Utils/Mail.php, send, 160

that might help u figure out whats happening and why

lobo
A new CiviCRM Q&A resource needs YOUR help to get started. Visit our StackExchange proposed site, sign up and vote on 5 questions

alanski

  • I post frequently
  • ***
  • Posts: 216
  • Karma: 5
  • Cup of tea? Yes please
    • Joomkit
  • CiviCRM version: Version in post
  • CMS version: Joomla
  • MySQL version: 5.0
Re: Oh dear payment pending again.err:Could not find an entry for invoice in POST
February 23, 2012, 12:34:52 am
Weird.Mail function for emailing via civimail works ok.
Just tried live transaction which works perfectly payment completed.
So paypal sandbox /civi interaction might cause this?

alanski

  • I post frequently
  • ***
  • Posts: 216
  • Karma: 5
  • Cup of tea? Yes please
    • Joomkit
  • CiviCRM version: Version in post
  • CMS version: Joomla
  • MySQL version: 5.0
payment pending update: RESOLVED
February 23, 2012, 03:40:55 am
Update:
So now I have got a nice clean log file (how I dont know which is whats so worrying about using Civi)
Two test drive transactions show this:
Feb 23 11:11:35  [info] Contribution record updated successfully

Feb 23 11:11:35  [info] Success: Database updated and mail sent

Feb 23 11:20:53  [info] Contribution record updated successfully

Feb 23 11:20:54  [info] Success: Database updated and mail sent

Issue: no test drive payments show up - zero on contact record

Live payments work.

As noted elsewhere I had to go into PayPals sandbox seller account and manually resend the IPN then it showed.

This is good news and bad. Settings in sandbox are not set to restrict/approve before IPN is sent. It means chasing tail in sandbox.

Lessons learnt:
1. start with resolving log file issues - my issues seem to have been related to 'bug' in outbound mail interface that would forget settings
2. Check server error logs for any big boo boos
3. Check 'Instant Payment Notification (IPN) history' in sandbox and resend the IPNs and check contribution status

On to recurring and memberships now!
Pages: [1]
  • CiviCRM Community Forums (archive) »
  • Old sections (read-only, deprecated) »
  • Support »
  • Using CiviCRM »
  • Using CiviContribute (Moderator: Donald Lobo) »
  • Resolved:Oh dear payment pending:Could not find an entry for invoice in POST

This forum was archived on 2017-11-26.