Could someone look at these log entries?

Hi guys,

I just had a member sign up to my site and from what I gather upgrade to S2 Level 1 through a standard paypal button to pay a yearly payment.

I was surprised to visit his profile to see that it hasn’t actually been upgraded yet.

First time I have ever seen this happen so I dived into the logs to take a look.

The payment was picked up successfully without issue but I don’t fully understand the last few lines of the logs.

Here they are:

PayPal IPN Logs:

PHP v5.3.14 :: WordPress® v3.4.2 :: s2Member® v120703
Memory 45.30 MB :: Real Memory 46.00 MB :: Peak Memory 45.36 MB :: Real Peak Memory 46.00 MB
http://www.actonthis.tv/?s2member_paypal_notify=1
User-Agent:
array (
‘txn_type’ => ‘subscr_signup’,
‘subscr_id’ => ‘I-RT1V8CKHR368’,
‘last_name’ => ‘mccurdy’,
‘option_selection1’ => ‘638’,
‘option_selection2’ => ‘86.145.1.179’,
‘residence_country’ => ‘GB’,
‘mc_currency’ => ‘GBP’,
‘item_name’ => ‘Act On This Yearly Premium Membership Upgrade’,
‘business’ => ‘payments@actonthis.tv’,
‘recurring’ => ‘49.00’,
‘verify_sign’ => ‘AdGQAlKqaldH9qPfVKKJnjFzlhxhAweh5lC1B1Rkhjfr7emkuhW0kTNj’,
‘payer_status’ => ‘verified’,
‘payer_email’ => ‘Customer’s email’,
‘first_name’ => ‘cfw’,
‘receiver_email’ => ‘my email’,
‘payer_id’ => ‘CBR9NQFG48FRE’,
‘option_name1’ => ‘Referencing Customer ID’,
‘invoice’ => ‘508678572743d~86.145.1.179’,
‘option_name2’ => ‘Customer IP Address’,
‘reattempt’ => ‘1’,
‘item_number’ => ‘1’,
‘subscr_date’ => ’03:59:31 Oct 23, 2012 PDT’,
‘custom’ => ‘www.mysite.com’,
‘charset’ => ‘windows-1252’,
‘notify_version’ => ‘3.7’,
‘period3’ => ‘1 Y’,
‘mc_amount3’ => ‘49.00’,
‘ipn_track_id’ => ‘75528dd29ce2’,
‘s2member_log’ =>
array (
0 => ‘IPN received on: Tue Oct 23, 2012 10:59:49 am UTC’,
1 => ‘s2Member POST vars verified through a POST back to PayPal¬Æ.’,
2 => ‘s2Member originating domain ( `$_SERVER[“HTTP_HOST”]` ) validated.’,
3 => ‘s2Member `txn_type` identified as ( `web_accept|subscr_signup` ).’,
4 => ‘s2Member `txn_type` identified as ( `web_accept|subscr_signup` ) w/ update vars.’,
5 => ‘s2Member Level/Capabilities updated w/ advanced update routines.’,
6 => ‘Modification Confirmation Email sent to Customer, with a URL that provides them with a way to log back in.’,
7 => ‘Storing Modification Tracking Codes into a Transient Queue. These will be processed on-site.’,
8 => ‘Storing IPN signup vars now. These are associated with a User\’s account record; for future reference.’,
),
‘subscr_gateway’ => ‘paypal’,
‘eotper’ => NULL,
‘ccaps’ => NULL,
‘level’ => ‘1’,
‘ip’ => ‘86.145.1.179’,
‘period1’ => ‘0 D’,
‘mc_amount1’ => ‘0.00’,
‘initial_term’ => ‘0 D’,
‘initial’ => ‘49.00’,
‘regular’ => ‘49.00’,
‘regular_term’ => ‘1 Y’,
)

PHP v5.3.14 :: WordPress® v3.4.2 :: s2Member® v120703
Memory 44.70 MB :: Real Memory 45.75 MB :: Peak Memory 45.36 MB :: Real Peak Memory 45.75 MB
http://www.actonthis.tv/?s2member_paypal_notify=1
User-Agent:
array (
‘mc_gross’ => ‘49.00’,
‘invoice’ => ‘508678572743d~86.145.1.179’,
‘protection_eligibility’ => ‘Ineligible’,
‘payer_id’ => ‘CBR9NQFG48FRE’,
‘payment_date’ => ’03:59:34 Oct 23, 2012 PDT’,
‘payment_status’ => ‘Completed’,
‘charset’ => ‘windows-1252’,
‘first_name’ => ‘cfw’,
‘option_selection1’ => ‘638’,
‘option_selection2’ => ‘86.145.1.179’,
‘mc_fee’ => ‘1.87’,
‘notify_version’ => ‘3.7’,
‘subscr_id’ => ‘I-RT1V8CKHR368’,
‘custom’ => ‘www.mysite.com’,
‘payer_status’ => ‘verified’,
‘business’ => ‘my email’,
‘verify_sign’ => ‘Ay9E7iOC4Ml0YaH2suuL1MXVErnMA47r-rTje8Uelmo1cWX5CII5cauP’,
‘payer_email’ => ‘Customer’s email’,
‘option_name1’ => ‘Referencing Customer ID’,
‘option_name2’ => ‘Customer IP Address’,
‘txn_id’ => ‘3U241928B25360927’,
‘payment_type’ => ‘instant’,
‘last_name’ => ‘mccurdy’,
‘receiver_email’ => ‘payments@actonthis.tv’,
‘payment_fee’ => ”,
‘receiver_id’ => ‘2JLD9BRTNZY3U’,
‘txn_type’ => ‘subscr_payment’,
‘item_name’ => ‘Act On This Yearly Premium Membership Upgrade’,
‘mc_currency’ => ‘GBP’,
‘item_number’ => ‘1’,
‘residence_country’ => ‘GB’,
‘transaction_subject’ => ‘Act On This Yearly Premium Membership Upgrade’,
‘payment_gross’ => ”,
‘ipn_track_id’ => ‘75528dd29ce2’,
‘s2member_log’ =>
array (
0 => ‘IPN received on: Tue Oct 23, 2012 10:59:49 am UTC’,
1 => ‘s2Member POST vars verified through a POST back to PayPal¬Æ.’,
2 => ‘s2Member originating domain ( `$_SERVER[“HTTP_HOST”]` ) validated.’,
3 => ‘s2Member `txn_type` identified as ( `subscr_payment|recurring_payment` ).’,
4 => ‘Sleeping for 5 seconds. Waiting for a possible ( `subscr_signup|subscr_modify|recurring_payment_profile_created` ).’,
5 => ‘Awake. It\’s Tue Oct 23, 2012 10:59:55 am UTC. s2Member `txn_type` identified as ( `subscr_payment|recurring_payment` ).’,
6 => ‘Skipping this IPN response, for now. The Subscr. ID is not associated with a registered Member.’,
7 => ‘Re-generating. This IPN will go into a Transient Queue; and be re-processed during registration.’,
),
‘subscr_gateway’ => ‘paypal’,
‘ccaps’ => NULL,
‘level’ => ‘1’,
‘ip’ => ‘86.145.1.179’,
)

PayPal RTN Logs:

PHP v5.3.14 :: WordPress® v3.4.2 :: s2Member® v120703
Memory 43.23 MB :: Real Memory 43.75 MB :: Peak Memory 43.41 MB :: Real Peak Memory 43.75 MB
http://www.actonthis.tv/?s2member_paypal_return=1&s2member_paypal_return_tra=fnIyOnVaS05FMEJ6aEh3Skg5cUtiTjJadnNjM0VxYVo0V3dmOmQ2ZmJiYmQ3ZDk1ZTA4ZjZmYWVlYzE4MzllYWYzYzEyfEonj4CY13nwsw-4NOWRkKLHBN8fjUGkLzrdJkpPOGuBSPBL8MzCUTRGr9bTUf0bTyzCLiDJr8HY1_6TrW73EOojvrbh7KUf73lAZF6xEfhD_n3EJew7fqS43s_i3APcIcnpaC7CdCX4l3CujZwBfj5lJFGk5h0C28FOrL2qf9LNcEh92m3odnQYQkAVpWsz8zwllfVzYqdJ9lPWFvDhJKD-SkX6rDsFB8PShUIFnSskr5KVpn6Dmfsve3q5WKKd7y2ir_U0rqTJW5QT265uLzdVAtrMtB_UQ3yplfutspPYne4Y9S4rAt6R1ErReXLCCcPlCLa88w_xxf4xjFkMJsUrA-MihFd6Z6UUJ9G3xpLaGTCQAybEtLjcpHsTotCMZg&tx=3U241928B25360927&st=Completed&amt=49.00&cc=GBP&cm=www%2eactonthis%2etv&item_number=&sig=ca3VFOp6V%2bHxW7uAuNKf%2bplcQ%2bURUd9zZ8dJ%2f1WEHeqxIDtFbz%2bW1vRYG4bOcAR%2bDtIzeCrLezPKMQetGKFh5N2hZB%2bKbcCg40ZErdswyvjEbtgGClatjsenGks33w%2bDRQsZUsE%2fLHcWH8qFYKdNEnZyilmZ%2bYEGdgUlgyJ4HaM%3d
User-Agent: Mozilla/5.0 (compatible; MSIE 9.0; Windows NT 6.1; WOW64; Trident/5.0)
array (
‘mc_gross’ => ‘49.00’,
‘invoice’ => ‘508678572743d~86.145.1.179’,
‘protection_eligibility’ => ‘Ineligible’,
‘payer_id’ => ‘CBR9NQFG48FRE’,
‘payment_date’ => ’03:59:34 Oct 23, 2012 PDT’,
‘payment_status’ => ‘Completed’,
‘charset’ => ‘windows-1252’,
‘first_name’ => ‘cfw’,
‘option_selection1’ => ‘638’,
‘option_selection2’ => ‘86.145.1.179’,
‘mc_fee’ => ‘1.87’,
‘subscr_id’ => ‘I-RT1V8CKHR368’,
‘custom’ => ‘www.mysite.com’,
‘payer_status’ => ‘verified’,
‘business’ => ‘my email’,
‘payer_email’ => ‘customer’s email’,
‘option_name1’ => ‘Referencing Customer ID’,
‘option_name2’ => ‘Customer IP Address’,
‘txn_id’ => ‘3U241928B25360927’,
‘payment_type’ => ‘instant’,
‘last_name’ => ‘mccurdy’,
‘receiver_email’ => ‘my email’,
‘receiver_id’ => ‘2JLD9BRTNZY3U’,
‘txn_type’ => ‘subscr_payment’,
‘item_name’ => ‘Act On This Yearly Premium Membership Upgrade’,
‘num_offers’ => ‘0’,
‘mc_currency’ => ‘GBP’,
‘item_number’ => ‘1’,
‘residence_country’ => ‘GB’,
‘transaction_subject’ => ‘Act On This Yearly Premium Membership Upgrade’,
‘s2member_log’ =>
array (
0 => ‘Return-Data received on: Tue Oct 23, 2012 10:59:38 am UTC’,
1 => ‘s2Member POST vars verified through a POST back to PayPal¬Æ.’,
2 => ‘s2Member originating domain ( `$_SERVER[“HTTP_HOST”]` ) validated.’,
3 => ‘s2Member `txn_type` identified as ( `web_accept|subscr_signup|subscr_payment` ).’,
4 => ‘s2Member `txn_type` identified as ( `web_accept|subscr_signup|subscr_payment` ) w/ update vars.’,
5 => ‘s2Member Level/Capabilities updated w/ advanced update routines.’,
6 => ‘Transient Tracking Cookie set on ( `web_accept|subscr_signup|subscr_payment` ) w/ update vars.’,
7 => ‘Storing Modification Tracking Codes into a Transient Queue. These will be processed on-site.’,
8 => ‘Redirecting Customer to the Login Page. They need to log back in.’,
),
‘subscr_gateway’ => ‘paypal’,
‘eotper’ => NULL,
‘ccaps’ => NULL,
‘level’ => ‘1’,
‘ip’ => ‘86.145.1.179’,
‘period1’ => ‘0 D’,
‘mc_amount1’ => ‘0.00’,
‘period3’ => ‘1 Y’,
‘mc_amount3’ => ‘49.00’,
‘recurring’ => ‘49.00’,
‘initial_term’ => ‘0 D’,
‘initial’ => ‘49.00’,
‘regular’ => ‘49.00’,
‘regular_term’ => ‘1 Y’,
‘s2member_paypal_return_tra’ => ‘fnIyOnVaS05FMEJ6aEh3Skg5cUtiTjJadnNjM0VxYVo0V3dmOmQ2ZmJiYmQ3ZDk1ZTA4ZjZmYWVlYzE4MzllYWYzYzEyfEonj4CY13nwsw-4NOWRkKLHBN8fjUGkLzrdJkpPOGuBSPBL8MzCUTRGr9bTUf0bTyzCLiDJr8HY1_6TrW73EOojvrbh7KUf73lAZF6xEfhD_n3EJew7fqS43s_i3APcIcnpaC7CdCX4l3CujZwBfj5lJFGk5h0C28FOrL2qf9LNcEh92m3odnQYQkAVpWsz8zwllfVzYqdJ9lPWFvDhJKD-SkX6rDsFB8PShUIFnSskr5KVpn6Dmfsve3q5WKKd7y2ir_U0rqTJW5QT265uLzdVAtrMtB_UQ3yplfutspPYne4Y9S4rAt6R1ErReXLCCcPlCLa88w_xxf4xjFkMJsUrA-MihFd6Z6UUJ9G3xpLaGTCQAybEtLjcpHsTotCMZg’,
)

It seems that something is being placed in transient and will be processed on site?

Does this mean the upgrade process may still happen or has something not fired.

A few weeks back I started using Gravity Forms and needed to use the default PayPal IPN URL for that.

That means there isn’t an s2 member URL set for the default IPN address, but i only use standard Paypal buttons so I thought that would be fine as they use their own IPN don’t they?

Does that still include upgrade buttons?

If someone could have a look at the above for me, that would be great!

Thanks :)

By the way, I have removed email addresses purposely above for privacy. they were all there in the logs just fine initially.

Hahaha, actually guys I THINK I might know what caused this and it’s pretty innocent.

I noticed the member had left a Capital letter off their name when they first registered so I went into the backend to change it in their profile.

It was at the time the upgrade was going through. It does look from the logs above that the S2 level WAS upgraded, BUT I bet when I saved the name change, it actually saved over all of the upgrade details that S2 would have just processed.

If someone could just confirm that the logs above look like they DID actually upgrade this member, i think we can put it down to that as the issue.

Hi Ross,

Yes, the message “s2Member Level/Capabilities updated w/ advanced update routines” would indicate that an upgrade was successfully processed.