płatność przyjęta - brak zamówienia gości / odwiedzających

[Cezary-sp7ukl]

Witam,
po ostatniej aktualizacji zaobserwowałem dziwne zjawisko.
GOŚĆ (nie zarejestrowany i nie zalogowany) składa zamówienie, wybiera płatność (z informacji od klientów wynika że wybierali BLIK) dokonuje płatności.
Płatność zaakceptowana (otrzymuję z PayNow potwierdzenie płątności, widzę też płątnośc na koncie PayNow), ale nie wpływa zamówienie, klient widzi zaś ponownie swój koszyk z produktami, jakby nie zrealizował zamówienia.
Nie ma problemu z zamówieniami zalogowanych klientów - tu jest OK.

poniżej log jednego z takich zamówień ręcznie zrealizowanego z porzuconych koszyków:

---

2022-12-28 9:01:47.983059 8bc783fe87a1f537dc2858f7ece2efbd INFO Processing payment for cart {cartId=2843, externalId=2843_63abf7ebefffe}
2022-12-28 9:01:50.749299 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Deactivating all payments. {"external_id":"2843_63abf7ebefffe"}
2022-12-28 9:01:50.750726 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Created new paynow data entry {"id_order":null,"id_cart":2843,"id_payment":"BL39-477-PET-JVD","order_reference":null,"external_id":"2843_63abf7ebefffe","status":"NEW","sent_at":"2022-12-28 09:01:50"}
2022-12-28 9:01:50.750886 8bc783fe87a1f537dc2858f7ece2efbd INFO Payment has been successfully created {cartId=2843, externalId=2843_63abf7ebefffe, paymentId=BL39-477-PET-JVD, status=PENDING}
2022-12-28 9:01:50.752706 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Setting optimistic lock on paynow data {cartId=2843, externalId=2843_63abf7ebefffe}
2022-12-28 9:01:50.754817 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Successfully set up optimistic lock on paynow data {externalId=2843_63abf7ebefffe}
2022-12-28 9:01:50.754906 8bc783fe87a1f537dc2858f7ece2efbd INFO Creating an order from cart {cartId=2843, externalId=2843_63abf7ebefffe}
2022-12-28 9:01:50.917622 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Incoming notification {"paymentId":"BL39-477-PET-JVD","externalId":"2843_63abf7ebefffe","status":"NEW","modifiedAt":"2022-12-28T09:01:48"}
2022-12-28 9:01:51.109646 5894f5bae910a7be2366f9518f0baedb DEBUG Nofification: Incoming notification {"paymentId":"BL39-477-PET-JVD","externalId":"2843_63abf7ebefffe","status":"PENDING","modifiedAt":"2022-12-28T09:01:48"}
2022-12-28 9:01:51.111892 5894f5bae910a7be2366f9518f0baedb DEBUG Nofification: Skipped processing. Order has newer status. Time travels are prohibited. {"paymentId":"BL39-477-PET-JVD","externalId":"2843_63abf7ebefffe","status":"PENDING","modifiedAt":"2022-12-28 09:01:48","activePaymentId":"BL39-477-PET-JVD","activePaymentStatus":"NEW","activePaymentDate":"2022-12-28 09:01:50","responseCode":202}
2022-12-28 9:01:51.114401 8bc783fe87a1f537dc2858f7ece2efbd INFO An order has been successfully created {cartId=2843, externalId=2843_63abf7ebefffe, orderId=31765, orderReference=TYZVSNKHD, paymentId=BL39-477-PET-JVD}
2022-12-28 9:01:51.114614 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Unsetting optimistic lock on paynow data {cartId=2843, externalId=2843_63abf7ebefffe}
2022-12-28 9:01:51.117641 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Successfully unset optimistic lock on paynow data {externalId=2843_63abf7ebefffe}
2022-12-28 9:01:51.122499 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Successfully updated payment data {orderId=31765, orderReference=TYZVSNKHD, paymentId=BL39-477-PET-JVD}
2022-12-28 9:01:51.353940 8bc783fe87a1f537dc2858f7ece2efbd INFO Retrieving payment status {paymentId=BL39-477-PET-JVD}
2022-12-28 9:01:51.496993 8bc783fe87a1f537dc2858f7ece2efbd INFO Retrieved payment status {paymentId=BL39-477-PET-JVD, status=PENDING}
2022-12-28 9:01:51.497157 8bc783fe87a1f537dc2858f7ece2efbd DEBUG confirmBlik: status processing started {"status":"PENDING","externalId":"2843_63abf7ebefffe","paymentId":"BL39-477-PET-JVD"}
2022-12-28 9:01:51.500155 8bc783fe87a1f537dc2858f7ece2efbd DEBUG confirmBlik: status processing ended {"status":"PENDING","externalId":"2843_63abf7ebefffe","paymentId":"BL39-477-PET-JVD"}
2022-12-28 9:01:51.919625 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Skipped processing. Order has newer status. Time travels are prohibited. {"paymentId":"BL39-477-PET-JVD","externalId":"2843_63abf7ebefffe","status":"NEW","modifiedAt":"2022-12-28 09:01:48","activePaymentId":"BL39-477-PET-JVD","activePaymentStatus":"PENDING","activePaymentDate":"2022-12-28 09:01:51","responseCode":202}
2022-12-28 9:01:55.521845 8bc783fe87a1f537dc2858f7ece2efbd INFO Checking order's payment status {externalId=2843_63abf7ebefffe}
2022-12-28 9:01:55.522411 8bc783fe87a1f537dc2858f7ece2efbd INFO Retrieving payment status {paymentId=BL39-477-PET-JVD}
2022-12-28 9:01:55.702821 8bc783fe87a1f537dc2858f7ece2efbd INFO Retrieved payment status {paymentId=BL39-477-PET-JVD, status=PENDING}
2022-12-28 9:01:55.703118 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Status: status processing started {"status":"PENDING","externalId":null,"paymentId":null}
2022-12-28 9:01:55.704902 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Status: status processing failed {"status":"PENDING","externalId":null,"paymentId":null,"exception":"Skipped processing. Order or Cart not found."}
2022-12-28 9:01:58.529257 8bc783fe87a1f537dc2858f7ece2efbd INFO Checking order's payment status {externalId=2843_63abf7ebefffe}
2022-12-28 9:01:58.529855 8bc783fe87a1f537dc2858f7ece2efbd INFO Retrieving payment status {paymentId=BL39-477-PET-JVD}
2022-12-28 9:01:58.710594 8bc783fe87a1f537dc2858f7ece2efbd INFO Retrieved payment status {paymentId=BL39-477-PET-JVD, status=PENDING}
2022-12-28 9:01:58.710834 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Status: status processing started {"status":"PENDING","externalId":null,"paymentId":null}
2022-12-28 9:01:58.711179 8bc783fe87a1f537dc2858f7ece2efbd DEBUG Status: status processing failed {"status":"PENDING","externalId":null,"paymentId":null,"exception":"Skipped processing. Order or Cart not found."}
2022-12-28 9:02:00.632627 5894f5bae910a7be2366f9518f0baedb DEBUG Nofification: Incoming notification {"paymentId":"BL39-477-PET-JVD","externalId":"2843_63abf7ebefffe","status":"CONFIRMED","modifiedAt":"2022-12-28T09:02:00"}
2022-12-28 9:02:00.633934 5894f5bae910a7be2366f9518f0baedb INFO Adding new state to order's history {externalId=2843_63abf7ebefffe, orderId=31765, orderReference=TYZVSNKHD, paymentId=BL39-477-PET-JVD, paymentStatus=CONFIRMED, state=2}
2022-12-28 9:02:00.713768 5894f5bae910a7be2366f9518f0baedb INFO Added new state to order's history {externalId=2843_63abf7ebefffe, orderId=31765, orderReference=TYZVSNKHD, paymentId=BL39-477-PET-JVD, paymentStatus=CONFIRMED, state=2, historyId=6933}
2022-12-28 9:02:01.523082 8bc783fe87a1f537dc2858f7ece2efbd INFO Checking order's payment status {externalId=2843_63abf7ebefffe}
2022-12-28 10:10:53.574166 0b38506ddea71ed618f687bf41ce0b61 INFO Processing payment for cart {cartId=2153, externalId=2153_63ac081d8c2bf}
2022-12-28 10:10:56.584983 0b38506ddea71ed618f687bf41ce0b61 DEBUG Deactivating all payments. {"external_id":"2153_63ac081d8c2bf"}
2022-12-28 10:10:56.586330 0b38506ddea71ed618f687bf41ce0b61 DEBUG Created new paynow data entry {"id_order":null,"id_cart":2153,"id_payment":"BLAK-OJT-S6M-ULK","order_reference":null,"external_id":"2153_63ac081d8c2bf","status":"NEW","sent_at":"2022-12-28 10:10:56"}
2022-12-28 10:10:56.586479 0b38506ddea71ed618f687bf41ce0b61 INFO Payment has been successfully created {cartId=2153, externalId=2153_63ac081d8c2bf, paymentId=BLAK-OJT-S6M-ULK, status=PENDING}
2022-12-28 10:10:56.588262 0b38506ddea71ed618f687bf41ce0b61 DEBUG Setting optimistic lock on paynow data {cartId=2153, externalId=2153_63ac081d8c2bf}
2022-12-28 10:10:56.590236 0b38506ddea71ed618f687bf41ce0b61 DEBUG Successfully set up optimistic lock on paynow data {externalId=2153_63ac081d8c2bf}
2022-12-28 10:10:56.590308 0b38506ddea71ed618f687bf41ce0b61 INFO Creating an order from cart {cartId=2153, externalId=2153_63ac081d8c2bf}
2022-12-28 10:10:56.865422 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Incoming notification {"paymentId":"BLAK-OJT-S6M-ULK","externalId":"2153_63ac081d8c2bf","status":"NEW","modifiedAt":"2022-12-28T10:10:53"}
2022-12-28 10:10:56.890988 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Incoming notification {"paymentId":"BLAK-OJT-S6M-ULK","externalId":"2153_63ac081d8c2bf","status":"PENDING","modifiedAt":"2022-12-28T10:10:53"}
2022-12-28 10:10:56.893331 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Skipped processing. Order has newer status. Time travels are prohibited. {"paymentId":"BLAK-OJT-S6M-ULK","externalId":"2153_63ac081d8c2bf","status":"PENDING","modifiedAt":"2022-12-28 10:10:53","activePaymentId":"BLAK-OJT-S6M-ULK","activePaymentStatus":"NEW","activePaymentDate":"2022-12-28 10:10:56","responseCode":202}
2022-12-28 10:10:57.136832 0b38506ddea71ed618f687bf41ce0b61 INFO An order has been successfully created {cartId=2153, externalId=2153_63ac081d8c2bf, orderId=31768, orderReference=HGCAEHBAL, paymentId=BLAK-OJT-S6M-ULK}
2022-12-28 10:10:57.137092 0b38506ddea71ed618f687bf41ce0b61 DEBUG Unsetting optimistic lock on paynow data {cartId=2153, externalId=2153_63ac081d8c2bf}
2022-12-28 10:10:57.139545 0b38506ddea71ed618f687bf41ce0b61 DEBUG Successfully unset optimistic lock on paynow data {externalId=2153_63ac081d8c2bf}
2022-12-28 10:10:57.142051 0b38506ddea71ed618f687bf41ce0b61 DEBUG Successfully updated payment data {orderId=31768, orderReference=HGCAEHBAL, paymentId=BLAK-OJT-S6M-ULK}
2022-12-28 10:10:57.417329 0b38506ddea71ed618f687bf41ce0b61 INFO Retrieving payment status {paymentId=BLAK-OJT-S6M-ULK}
2022-12-28 10:10:57.601042 0b38506ddea71ed618f687bf41ce0b61 INFO Retrieved payment status {paymentId=BLAK-OJT-S6M-ULK, status=PENDING}
2022-12-28 10:10:57.601255 0b38506ddea71ed618f687bf41ce0b61 DEBUG confirmBlik: status processing started {"status":"PENDING","externalId":"2153_63ac081d8c2bf","paymentId":"BLAK-OJT-S6M-ULK"}
2022-12-28 10:10:57.605256 0b38506ddea71ed618f687bf41ce0b61 DEBUG confirmBlik: status processing ended {"status":"PENDING","externalId":"2153_63ac081d8c2bf","paymentId":"BLAK-OJT-S6M-ULK"}
2022-12-28 10:10:57.867743 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Skipped processing. Order has newer status. Time travels are prohibited. {"paymentId":"BLAK-OJT-S6M-ULK","externalId":"2153_63ac081d8c2bf","status":"NEW","modifiedAt":"2022-12-28 10:10:53","activePaymentId":"BLAK-OJT-S6M-ULK","activePaymentStatus":"PENDING","activePaymentDate":"2022-12-28 10:10:57","responseCode":202}
2022-12-28 10:11:01.972281 0b38506ddea71ed618f687bf41ce0b61 INFO Checking order's payment status {externalId=2153_63ac081d8c2bf}
2022-12-28 10:11:01.972841 0b38506ddea71ed618f687bf41ce0b61 INFO Retrieving payment status {paymentId=BLAK-OJT-S6M-ULK}
2022-12-28 10:11:02.157179 0b38506ddea71ed618f687bf41ce0b61 INFO Retrieved payment status {paymentId=BLAK-OJT-S6M-ULK, status=PENDING}
2022-12-28 10:11:02.157505 0b38506ddea71ed618f687bf41ce0b61 DEBUG Status: status processing started {"status":"PENDING","externalId":null,"paymentId":null}
2022-12-28 10:11:02.159281 0b38506ddea71ed618f687bf41ce0b61 DEBUG Status: status processing failed {"status":"PENDING","externalId":null,"paymentId":null,"exception":"Skipped processing. Order or Cart not found."}
2022-12-28 10:11:02.621844 5894f5bae910a7be2366f9518f0baedb DEBUG Nofification: Incoming notification {"paymentId":"BLAK-OJT-S6M-ULK","externalId":"2153_63ac081d8c2bf","status":"CONFIRMED","modifiedAt":"2022-12-28T10:11:02"}
2022-12-28 10:11:02.623469 5894f5bae910a7be2366f9518f0baedb INFO Adding new state to order's history {externalId=2153_63ac081d8c2bf, orderId=31768, orderReference=HGCAEHBAL, paymentId=BLAK-OJT-S6M-ULK, paymentStatus=CONFIRMED, state=2}
2022-12-28 10:11:02.732553 5894f5bae910a7be2366f9518f0baedb INFO Added new state to order's history {externalId=2153_63ac081d8c2bf, orderId=31768, orderReference=HGCAEHBAL, paymentId=BLAK-OJT-S6M-ULK, paymentStatus=CONFIRMED, state=2, historyId=6939}
2022-12-28 10:11:04.989808 0b38506ddea71ed618f687bf41ce0b61 INFO Checking order's payment status {externalId=2153_63ac081d8c2bf}
2022-12-28 14:01:33.267092 b5d4e2331663148c95a39cb6c40a5c26 DEBUG Setting optimistic lock on paynow data {cartId=2859, externalId=}
2022-12-28 14:01:33.267417 b5d4e2331663148c95a39cb6c40a5c26 WARNING Can't set optimistic lock due empty payment data {cartId=2859}
2022-12-28 14:01:33.267486 b5d4e2331663148c95a39cb6c40a5c26 INFO Creating an order from cart {cartId=2859, externalId=}
2022-12-28 14:01:33.735222 b5d4e2331663148c95a39cb6c40a5c26 INFO An order has been successfully created {cartId=2859, externalId=, orderId=31777, orderReference=GGGGLGJFP, paymentId=}
2022-12-28 14:01:33.735374 b5d4e2331663148c95a39cb6c40a5c26 DEBUG Unsetting optimistic lock on paynow data {cartId=2859, externalId=}
2022-12-28 14:01:33.735634 b5d4e2331663148c95a39cb6c40a5c26 WARNING Can't unset optimistic lock due empty payment data {cartId=2859}
2022-12-28 14:01:33.737035 b5d4e2331663148c95a39cb6c40a5c26 INFO Processing payment for order {cartId=2859, externalId=GGGGLGJFP, orderId=31777, orderReference=GGGGLGJFP}
2022-12-28 14:01:34.374397 b5d4e2331663148c95a39cb6c40a5c26 DEBUG Deactivating all payments. {"external_id":"GGGGLGJFP"}
2022-12-28 14:01:34.375287 b5d4e2331663148c95a39cb6c40a5c26 DEBUG Created new paynow data entry {"id_order":31777,"id_cart":"2859","id_payment":"PB59-4U1-S8O-D6M","order_reference":"GGGGLGJFP","external_id":"GGGGLGJFP","status":"NEW","sent_at":"2022-12-28 14:01:34"}
2022-12-28 14:01:34.375387 b5d4e2331663148c95a39cb6c40a5c26 INFO Payment has been successfully created {cartId=2859, externalId=GGGGLGJFP, paymentId=PB59-4U1-S8O-D6M, status=NEW}
2022-12-28 14:01:34.881583 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Incoming notification {"paymentId":"PB59-4U1-S8O-D6M","externalId":"GGGGLGJFP","status":"NEW","modifiedAt":"2022-12-28T14:01:34"}
2022-12-28 14:01:35.884133 685d153b262b5fdb3d140f718b90c7ae DEBUG Nofification: Skipped processing. Transition status (NEW) already consumed. {"paymentId":"PB59-4U1-S8O-D6M","externalId":"GGGGLGJFP","status":"NEW","modifiedAt":"2022-12-28 14:01:34","activePaymentId":"PB59-4U1-S8O-D6M","activePaymentStatus":"NEW","activePaymentDate":"2022-12-28 14:01:34","responseCode":202}
2022-12-28 14:01:47.031419 5894f5bae910a7be2366f9518f0baedb DEBUG Nofification: Incoming notification {"paymentId":"PB59-4U1-S8O-D6M","externalId":"GGGGLGJFP","status":"PENDING","modifiedAt":"2022-12-28T14:01:46"}
2022-12-28 14:02:46.045310 5894f5bae910a7be2366f9518f0baedb DEBUG Nofification: Incoming notification {"paymentId":"PB59-4U1-S8O-D6M","externalId":"GGGGLGJFP","status":"CONFIRMED","modifiedAt":"2022-12-28T14:02:45"}
2022-12-28 14:02:46.048142 5894f5bae910a7be2366f9518f0baedb INFO Adding new state to order's history {externalId=GGGGLGJFP, orderId=31777, orderReference=GGGGLGJFP, paymentId=PB59-4U1-S8O-D6M, paymentStatus=CONFIRMED, state=2}
2022-12-28 14:02:46.155008 5894f5bae910a7be2366f9518f0baedb INFO Added new state to order's history {externalId=GGGGLGJFP, orderId=31777, orderReference=GGGGLGJFP, paymentId=PB59-4U1-S8O-D6M, paymentStatus=CONFIRMED, state=2, historyId=6986}

[emilleszczak2]

@Cezary-sp7ukl Zachęcam do aktualizacji do wersji co najmniej 1.6.25

[kpodemski]

Dostałem raporty o podobnych problemach. Zaktualizowali właśnie wtyczkę do 1.6.34 ale z 1.6.33 gdzie i tak były problemy. Nie ważne było czy zamówienie jest składane od razu, czy po zmianie statusu, czasami, promil transakcji po prostu miał płatność ale żadnego zamówienia w systemie.