Jump to content



Photo
- - - - -

v2.3.1 Order Processed But No Order

checkout_process.php

This topic has been archived. This means that you cannot reply to this topic.
3 replies to this topic

#1   lee the bean

lee the bean
  • Members
  • 60 posts

Posted 28 May 2012 - 08:57

Today I have had an order processed - but no record of order is in database.

Customer account created ok, customer checked out ok, paypal ipn completed ok, customer and store owner email sent ok, and product stock level updated ok.

The only record of an order in database is in the orders_status_history - which has a zero orders_id. However the orders_id should have been 950 (from paypal - the order number is the Invoice ID logged in the paypal record on paypal).

I've attached relevant extract from the server logs - no errors were logged in the server error logs.

Any help on this much appreciated - as I'm stuck here - I beleive we may have exposed a bug....


Server Log and database record below:

101.166.16.XXX - - [28/May/2012:07:43:14 +0100] "GET /shop/product_info.php?products_id=1069&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 31665 "http://www.facebook....ld0&h=LAQGaapIH" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:46:21 +0100] "POST /shop/product_info.php?products_id=1069&action=add_product&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:46:22 +0100] "GET /shop/shopping_cart.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 27996 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:46:23 +0100] "GET /shop/images/CAUR-0008b.jpg HTTP/1.1" 200 30041 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:46:31 +0100] "GET /shop/shopping_cart.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 27944 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:47:17 +0100] "GET /shop/shopping_cart.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 27944 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:47:33 +0100] "GET /shop/product_info.php?products_id=1069&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 32167 "http://www.facebook....ld0&h=LAQGaapIH" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:47:51 +0100] "GET /shop/shopping_cart.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 27942 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:48:00 +0100] "GET /shop/shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 33248 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:51:58 +0100] "GET /shop/shopping_cart.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 27940 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:52:05 +0100] "GET /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:52:06 +0100] "GET /shop/login.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26569 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:53:10 +0100] "POST /shop/login.php?action=process&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26927 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:53:11 +0100] "GET /shop/images/icons/error.gif HTTP/1.1" 200 193 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:53:28 +0100] "GET /shop/password_forgotten.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26138 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:07:53:41 +0100] "POST /shop/password_forgotten.php?action=process&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26443 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:00:16 +0100] "GET /shop/product_info.php?products_id=1069&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 32157 "-" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:00:40 +0100] "GET /shop/images/CAUR-0008b.jpg HTTP/1.1" 200 30041 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:01:17 +0100] "GET /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:01:18 +0100] "GET /shop/login.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26625 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:01:54 +0100] "GET /shop/create_account.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 43206 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:03:25 +0100] "POST /shop/create_account.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:03:26 +0100] "GET /shop/create_account_success.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 25672 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:03:55 +0100] "GET /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 31618 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:07:15 +0100] "GET /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 31626 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:07:49 +0100] "POST /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:07:50 +0100] "GET /shop/checkout_payment.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 30957 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:07:59 +0100] "GET /shop/password_forgotten.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26132 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:08:21 +0100] "POST /shop/password_forgotten.php?action=process&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26147 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:08:21 +0100] "POST /shop/checkout_confirmation.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 32067 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:08:44 +0100] "GET /shop/logoff.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 24680 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:08:55 +0100] "GET /shop/login.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26094 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:09:08 +0100] "POST /shop/login.php?action=process&osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:09:09 +0100] "GET /shop/index.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 35667 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:09:30 +0100] "GET /shop/shopping_cart.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 28627 "-" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:09:35 +0100] "GET /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 32379 "http://www.my-websit...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:10:05 +0100] "POST /shop/checkout_shipping.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 302 - "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:10:06 +0100] "GET /shop/checkout_payment.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 31673 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:10:41 +0100] "POST /shop/checkout_confirmation.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 32102 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:11:10 +0100] "GET /shop/checkout_payment.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 31662 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
173.0.81.1 - - [28/May/2012:08:11:45 +0100] "POST /shop/ext/modules/payment/paypal/standard_ipn.php HTTP/1.0" 200 - "-" "-"
101.166.16.XXX - - [28/May/2012:08:11:51 +0100] "GET /shop/checkout_process.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0&tx=3NL95476AR028543C&st=Completed&amt=38.44&cc=GBP&cm=773&item_number= HTTP/1.1" 302 - "https://www.paypal.c...6fcf51157e8c7b2" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:11:52 +0100] "GET /shop/checkout_success.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 26358 "https://www.paypal.c...6fcf51157e8c7b2" "Mozilla/5.0 (Windows NT 6.0) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"
101.166.16.XXX - - [28/May/2012:08:12:19 +0100] "GET /shop/logoff.php?osCsid=ulk6nn0ar2996g6oo7ev66gld0 HTTP/1.1" 200 24628 "https://www.my-websi...96g6oo7ev66gld0" "Mozilla/5.0 (Windows NT 5.1) AppleWebKit/536.5 (KHTML, like Gecko) Chrome/19.0.1084.52 Safari/536.5"

insert into orders_status_history (orders_status_history_id, orders_id, orders_status_id, date_added, customer_notified, comments) values ('3349', '0', '1', '2012-05-28 08:11:51', '1', '');

#2   Juto

Juto
  • Members
  • 369 posts

Posted 28 May 2012 - 15:34

Hi, yes I think there's a bug. However I do not know where it is. It also seems to affect cusomers order history.

Look at this: http://forums.oscomm...m-image/unread/

The person have long list of what he has done with his site, but look at nr 12 in the list.

I've also have the same problem with the customers order history. Yet to be resolved.

Sara

#3   lee the bean

lee the bean
  • Members
  • 60 posts

Posted 28 May 2012 - 15:55

Thanks Sara, just a quick scan of the referenced topic - the problem with facebook does not appear to be related (i have facebook links - which are fine - but FB is not good at picking up the photos in osC.)

Subsequent orders have been okay - so whatever caused it was transient - and I think customer caused (just a hunch by looking at the number of logins / password resets etc during the order process).

I have requested a little info from the customer to see if they had any errors or noticed a problem (how embarassing).

My hunch is that they had two browser sessions open and were switching between the two - but I cant tell from the server audit trial if that hunch is correct (ps. my site is setup to use sessions in DB rather than cookies).

#4   lee the bean

lee the bean
  • Members
  • 60 posts

Posted 29 May 2012 - 08:29

Ok - I can see why the problem occurs - PROBLEM = order is processed without an order record:

1. Customer enters shop at 07:51:58 and after creating their account creates the order record at 08:08:21. The record is then deleted at 08:08:44 when they logoff.

2. Customer then logs in again at 08:09:08 and creates a new order record at 08:10:41

3. The "BUG / Feature" is introduced at 08:11:10 - here the customer comes in with another checkout_payment.php - which has come in from a checkout_shipping.php. That would have trashed the order record again (I think - can someone confirm this).

This command can only have been run from a separate browser session (I dont think the customer could use the the browser navigation back button a couple of times or they wouldn't be able to continue with payment at paypal).

4. In the meantime the customer has continued with the order payment at paypal, having trashed the order record.

Mmmmm - how to fix this one - maybe an order record lock - to prevent multiple sessions causing confusions - or is this already handled. Need a developer now to answer how the sessions dadatabse handles multiple sessions.

FIX NEEDED ASAP