Paypal was working until last week now -10002 security header is not valid

[rustle]

Hi VM Team maybe someone can direct me to the right direction?
We have a Paypal pro account, which was processing CC transactions up until last week using the Paypal API creds
Nobody has changed credentials on either side (paypal or vm)

We did upgrade this week, but this error seems to have happened before the upgrade

Paypal support says it is an error on the vm side of things... and we need to check the debug file

I have turned on debug logs but I am unable to find the log file?
Where is this info stored?

Joomla version
v 3.9.16
vm version:
VirtueMart 3.6.10 10211

[AH]

In you joomla global settings

Systems tab

Path to Log Folder *

[GJC Web Design]

or turn on PP debug .. errors should show up

[rustle]

Hi guys
I thought of this file
but no new logging is occurring since last fall
/administrator/logs/com_virtuemart.log.php
here is the message on checkout
Error
vmError: 10002: Security header is not valid
Message
There was a problem processing your order, please try again or contact the store administrator.

[rustle]

I turned on vm debug for admin and I get this

Message
1 vmdebug Show All Errors, PHP-Version 7.3.162 vmdebug 1 Language, default shoplanguage (VmConfig::$jDefLang): en_gb en-GB3 vmdebug vmTime: time to load config: 0.006983041763305664 vmdebug Lädt die Config Var1:
en-GB
5 vmdebug There is no requested itemid loaded home Itemid Var1:
101
6 vmdebug Router initialised with language en-GB7 vmdebug vmTime: time to import plugins vmuserfield: 3.09944152832031E-58 vmdebug Start used Ram 4M9 vmdebug SSL enabled10 vmdebug my setupAddressFieldsForCart $countBT $this->byDefaultBT Var1:
29
Var2:
0
11 vmdebug Going to set pw fields unrequired12 vmdebug getVendorId normal shopper13 vmdebug My Memory Limit in Bytes 13421772814 vmdebug Canon cat found15 vmdebug gatherEffectingRulesForBill $hitsDeliveryArea 1 60 Var1:
Array
(
   

• => 53
      [1] => 60
  )
  
  16 vmdebug gatherEffectingRulesForBill $hitsDeliveryArea 1 60 Var1:
  Array
  (
     
• => 60
  )
  
  17 vmdebug shipmentmethod Rush Delivery = TRUE for variable weight = 0 Reason: no boundary conditions set18 vmdebug shipmentmethod Rush Delivery = TRUE for variable order amount = 60 Reason: no boundary conditions set19 vmdebug shipmentmethod Rush Delivery = TRUE for variable zip = K9P1J3 Reason: no boundary conditions set20 vmdebug shipmentmethod Rush Delivery = TRUE for variable virtuemart_country_id = 38, Reason: Countries in rule or none set21 vmdebug shipmentmethod Standard Mail = TRUE for variable weight = 0 Reason: no boundary conditions set22 vmdebug shipmentmethod Standard Mail = TRUE for variable order amount = 60 Reason: no boundary conditions set23 vmdebug shipmentmethod Standard Mail = TRUE for variable zip = K9P1J3 Reason: no boundary conditions set24 vmdebug shipmentmethod Standard Mail = TRUE for variable virtuemart_country_id = 38, Reason: Countries in rule or none set25 vmdebug Check for shipment method Var1:
  2
  Var2:
  Array
  (
     
• => 1
      [1] => 2
  )
  
  26 vmdebug Check for shipment method for paypal PASSED27 vmdebug Doing base64_decode v�v�E���~��t���sl��w�28 vmdebug vmTime: Time consumed for shipment/payment plugins: 0.0052678585052490229 vmdebug Check for shipment method Var1:
  2
  Var2:
  Array
  (
     
• => 1
      [1] => 2
  )
  
  30 vmdebug Check for shipment method for paypal PASSED31 vmdebug vmTime: Time consumed for shipment/payment plugins: 0.0025799274444580132 vmdebug deleteOldPendingOrder PT2M33 vmdebug deleteOldPendingOrder Var1:
  SELECT * FROM `#__virtuemart_orders` WHERE `order_status` = "P" AND `virtuemart_order_id`= "163" AND `created_on` > "2020-03-26 16:30:01"
  34 vmdebug getClientIP Var1:
  45.74.141.73
  35 vmdebug Table #__virtuemart_orders check 136 vmdebug VmTableData Updated entry with correct hash Var1:
  virtuemart_order_id
  Var2:
  0
  Var3:
  164
  Var4:
  622bf99a6c396fa75d9f6fe2516b221a
  Var5:
  25af7e017532ac9684d2e45ac73b635f
  37 vmdebug Table #__virtuemart_order_items check 138 vmdebug handleStockAfterStatusChangedPerProduct N P 1 Var1:
  1
  39 vmdebug Table #__virtuemart_order_items check 140 vmdebug handleStockAfterStatusChangedPerProduct N P 1 Var1:
  1
  41 vmdebug Table #__virtuemart_order_calc_rules check 142 vmdebug Table #__virtuemart_order_calc_rules check 143 vmdebug Table #__virtuemart_order_calc_rules check 144 vmdebug Table #__virtuemart_order_calc_rules check 145 vmdebug getClientIP Var1:
  45.74.141.73
  There was a problem processing your order, please try again or contact the store administrator.3 vmdebug vmTime: time to load config: 0.002952098846435554 vmdebug vmTime: time to import plugins vmuserfield: 3.29017639160156E-55 vmdebug Start used Ram 2M6 vmdebug SSL enabled7 vmdebug my setupAddressFieldsForCart $countBT $this->byDefaultBT Var1:
  29
  Var2:
  0
  8 vmdebug Going to set pw fields unrequired9 vmdebug getVendorId normal shopper10 vmdebug My Memory Limit in Bytes 13421772811 vmdebug Router initialised with language en-GB12 vmdebug Canon cat found13 vmdebug gatherEffectingRulesForBill $hitsDeliveryArea 1 60 Var1:
  Array
  (
     
• => 53
      [1] => 60
  )
  
  14 vmdebug gatherEffectingRulesForBill $hitsDeliveryArea 1 60 Var1:
  Array
  (
     
• => 60
  )
  
  15 vmdebug shipmentmethod Rush Delivery = TRUE for variable weight = 0 Reason: no boundary conditions set16 vmdebug shipmentmethod Rush Delivery = TRUE for variable order amount = 60 Reason: no boundary conditions set17 vmdebug shipmentmethod Rush Delivery = TRUE for variable zip = K9P1J3 Reason: no boundary conditions set18 vmdebug shipmentmethod Rush Delivery = TRUE for variable virtuemart_country_id = 38, Reason: Countries in rule or none set19 vmdebug shipmentmethod Standard Mail = TRUE for variable weight = 0 Reason: no boundary conditions set20 vmdebug shipmentmethod Standard Mail = TRUE for variable order amount = 60 Reason: no boundary conditions set21 vmdebug shipmentmethod Standard Mail = TRUE for variable zip = K9P1J3 Reason: no boundary conditions set22 vmdebug shipmentmethod Standard Mail = TRUE for variable virtuemart_country_id = 38, Reason: Countries in rule or none set23 vmdebug vmTime: Time consumed for shipment/payment plugins: 0.0024139881134033224 vmdebug Check for shipment method Var1:
  2
  Var2:
  Array
  (
     
• => 1
      [1] => 2
  )
  
  25 vmdebug Check for shipment method for paypal PASSED26 vmdebug Doing base64_decode v�v�E���~��t���sl��w�27 vmdebug Check for shipment method Var1:
  2
  Var2:
  Array
  (
     
• => 1
      [1] => 2
  )
  
  28 vmdebug Check for shipment method for paypal PASSED29 vmdebug Use cache for mod products30 vmdebug vmTime: sortSearchQuery products: featured: 0.00063896179199218831 vmdebug End used Ram 4M32 vmdebug Peak memory peak 4M33 vmdebug vmTime: "VirtuemartControllerCart" Finished task : 0.0840070247650146
  Error
  vmError: 10002: Security header is not valid

[GJC Web Design]

and the Paypal debug?

[rustle]

I can not find any debug info rom the Paypal plugin
this is with the debug settings turned on

this file displays no new errors
/administrator/logs/com_virtuemart.log.php

am I doing something incorrectly?

[GJC Web Design]

??

[rustle]

yes both are turned on

[rustle]

Hi guys i found the log location, which is new to me
Vm admin --> Tools

here is the response
2020-03-26 17:52:55 DEBUG plgVmConfirmedOrder: order number: 5K6S050
2020-03-26 17:52:55 MESSAGE plgVmConfirmedOrder: Amount/Currency stored 1.13 paymentcurrency 26 orderusercurrency26
2020-03-26 17:52:55 DEBUG PayPal DoDirectPayment Request variables:: Array
(
    [METHOD] => DoDirectPayment
    [version] => 104.0
    [USER] => ian_api1.diagnoseathome.com
    [PWD] => **MASKED**
    [SIGNATURE] => **MASKED**
    [BUTTONSOURCE] => VirtueMart_Cart_PPA
    [CURRENCYCODE] => CAD
    [INVNUM] => 5K6S050
    [IPADDRESS] => 45.74.141.73
    [FIRSTNAME] => Russell
    [LASTNAME] => Compton
    [STREET] => 204 Ridgewood Rd
    [CITY] => Toronto
    [ZIP] => K9P1J3
    [STATE] => ON
    [COUNTRYCODE] => CA
    [SHIPTONAME] => Russell Compton
    [SHIPTOSTREET] => 204 Ridgewood Rd
    [SHIPTOCITY] => Toronto
    [SHIPTOZIP] => K9P1J3
    [SHIPTOSTATE] => ON
    [SHIPTOCOUNTRYCODE] => CA
    [ACCT] => **MASKED**
    [CVV2] => **MASKED**
    [CREDITCARDTYPE] => Visa
    [EXPDATE] => 042022
    [PAYMENTACTION] => Sale
    [AMT] => 1.13
    [L_NAME1] => test purchase
    [L_NUMBER1] => tp
    [L_AMT1] => 1
    [L_QTY1] => 1
    [L_TAXAMT1] =>
    [L_NAME2] => Medical Doctor  Free with OHIPMSP
    [L_NUMBER2] => C-MD-C
    [L_AMT2] => 59
    [L_QTY2] => 1
    [L_TAXAMT2] =>
    [SHIPPINGAMT] => 0
    [HANDLINGAMT] => -58.87
    [TAXAMT] => 0
    [ITEMAMT] => 60
)
2020-03-26 17:52:55 DEBUG PayPal response:: Array
(
    [TIMESTAMP] => 2020-03-26T17:52:56Z
    [CORRELATIONID] => 3cbe0f95c3f8d
    [ACK] => Failure
    [VERSION] => 104.0
    [BUILD] => 53816554
    [L_ERRORCODE0] => 10002
    [L_SHORTMESSAGE0] => Security error
    [L_LONGMESSAGE0] => Security header is not valid
    [L_SEVERITYCODE0] => Error
    [custom] => 4b44e6997ad38dfd732b6b5efdd1ba84
    [method] => DoDirectPayment
)
2020-03-26 17:52:55 DEBUG handleResponse:: Array
(
    [TIMESTAMP] => 2020-03-26T17:52:56Z
    [CORRELATIONID] => 3cbe0f95c3f8d
    [ACK] => Failure
    [VERSION] => 104.0
    [BUILD] => 53816554
    [L_ERRORCODE0] => 10002
    [L_SHORTMESSAGE0] => Security error
    [L_LONGMESSAGE0] => Security header is not valid
    [L_SEVERITYCODE0] => Error
    [custom] => 4b44e6997ad38dfd732b6b5efdd1ba84
    [method] => DoDirectPayment
)
2020-03-26 17:52:55 ERROR vmError: 10002: Security header is not valid<br />2020-03-26 17:52:55 DEBUG plgVmConfirmedOrder: order number: 5K6S050
2020-03-26 17:52:55 MESSAGE plgVmConfirmedOrder: Amount/Currency stored 1.13 paymentcurrency 26 orderusercurrency26
2020-03-26 17:52:55 DEBUG PayPal DoDirectPayment Request variables:: Array
(
    [METHOD] => DoDirectPayment
    [version] => 104.0
    [USER] => ian_api1.diagnoseathome.com
    [PWD] => **MASKED**
    [SIGNATURE] => **MASKED**
    [BUTTONSOURCE] => VirtueMart_Cart_PPA
    [CURRENCYCODE] => CAD
    [INVNUM] => 5K6S050
    [IPADDRESS] => 45.74.141.73
    [FIRSTNAME] => Russell
    [LASTNAME] => Compton
    [STREET] => 204 Ridgewood Rd
    [CITY] => Toronto
    [ZIP] => K9P1J3
    [STATE] => ON
    [COUNTRYCODE] => CA
    [SHIPTONAME] => Russell Compton
    [SHIPTOSTREET] => **MASKED**
    [SHIPTOCITY] => **MASKED**
    [SHIPTOZIP] => **MASKED**
    [SHIPTOSTATE] => **MASKED**
    [SHIPTOCOUNTRYCODE] => **MASKED**
    [ACCT] => **MASKED**
    [CVV2] => **MASKED**
    [CREDITCARDTYPE] => Visa
    [EXPDATE] => 042022
    [PAYMENTACTION] => Sale
    [AMT] => 1.13
    [L_NAME1] => test purchase
    [L_NUMBER1] => tp
    [L_AMT1] => 1
    [L_QTY1] => 1
    [L_TAXAMT1] =>
    [L_NAME2] => Medical Doctor  Free with OHIPMSP
    [L_NUMBER2] => C-MD-C
    [L_AMT2] => 59
    [L_QTY2] => 1
    [L_TAXAMT2] =>
    [SHIPPINGAMT] => 0
    [HANDLINGAMT] => -58.87
    [TAXAMT] => 0
    [ITEMAMT] => 60
)
2020-03-26 17:52:55 DEBUG PayPal response:: Array
(
    [TIMESTAMP] => 2020-03-26T17:52:56Z
    [CORRELATIONID] => 3cbe0f95c3f8d
    [ACK] => Failure
    [VERSION] => 104.0
    [BUILD] => 53816554
    [L_ERRORCODE0] => 10002
    [L_SHORTMESSAGE0] => Security error
    [L_LONGMESSAGE0] => Security header is not valid
    [L_SEVERITYCODE0] => Error
    [custom] => 4b44e6997ad38dfd732b6b5efdd1ba84
    [method] => DoDirectPayment
)
2020-03-26 17:52:55 DEBUG handleResponse:: Array
(
    [TIMESTAMP] => 2020-03-26T17:52:56Z
    [CORRELATIONID] => 3cbe0f95c3f8d
    [ACK] => Failure
    [VERSION] => 104.0
    [BUILD] => 53816554
    [L_ERRORCODE0] => 10002
    [L_SHORTMESSAGE0] => Security error
    [L_LONGMESSAGE0] => Security header is not valid
    [L_SEVERITYCODE0] => Error
    [custom] => 4b44e6997ad38dfd732b6b5efdd1ba84
    [method] => DoDirectPayment
)
2020-03-26 17:52:55 ERROR vmError: 10002: Security header is not valid<br />

[AH]

Check your API credentials
It is a 10002 PayPal error

[rustle]

These creds are only 6 months old
also were processing up until last week...

do you guys see any other errors in the above thread?

[GJC Web Design]

no - and just re-enter your creds

I had a client a couple of weeks ago who also swore blind the creds were correct.. but upon re-entering them it all worked..

It could have been something like the browser autofill etc filling the wrong details

[rustle]

Thanks guys
i will retry and report

[rustle]

When I logged in to the Paypal api,
the passwords were no longer the same length...?
this is posting orders again
thanks everybody

everybody swears they did not update the passwords

SOLVED