API tools faq
paste
Advertisement
fedorm

Untitled

fedorm
Aug 22nd, 2019
441
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
XML 78.09 KB | None | 0 0
raw download clone embed print report
  1. 2.08 13:47:25.274 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  2. 22.08 13:47:25.275 INFO  [TextCustomerDisplayPluginAbstract] **********************
  3. 22.08 13:47:25.275 INFO  [TextCustomerDisplayPluginAbstract] *Добро               *
  4. 22.08 13:47:25.275 INFO  [TextCustomerDisplayPluginAbstract] *пожаловать          *
  5. 22.08 13:47:25.275 INFO  [TextCustomerDisplayPluginAbstract] **********************
  6. 22.08 13:47:25.275 INFO  [TextCustomerDisplayPluginAbstract]
  7. 22.08 13:47:41.765 INFO  [DocumentSender] ping = true
  8. 22.08 13:47:47.218 INFO  [MLServiceImpl] Number of pending operations (DISSOCIATING_CARD_MANZANA): 4
  9. 22.08 13:47:47.219 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  10.     Id: MQ==
  11.     Date: 2019-08-22 01:02:38.464
  12. 22.08 13:47:47.219 TRACE [MLServiceImpl] entering getServices()
  13. 22.08 13:47:47.219 TRACE [MLServiceImpl] getting services (IN MEMORY)
  14. 22.08 13:47:47.219 TRACE [MLServiceImpl] leaving getServices(). The result size is: 1
  15. 22.08 13:47:47.220 TRACE [MLServiceImpl] Going to send DissociatingCard:
  16. 22.08 13:47:47.220 TRACE [MLServiceImpl] class ru.crystals.pos.ml.wsclient.DissociatingCard is <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  17. <DissociatingCard xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  18.     <request>
  19.         <ClientTransactionId>1</ClientTransactionId>
  20.         <Organization>Victoria</Organization>
  21.         <BusinessUnit>7189</BusinessUnit>
  22.         <POS>61</POS>
  23.         <CardNumber>99990002</CardNumber>
  24.         <DateTime>2019-08-22T13:47:47.220+03:00</DateTime>
  25.     </request>
  26. </DissociatingCard>
  27.  
  28. 22.08 13:47:47.231 WARN  [MLServiceImpl] failed to connect to a ML POS web-service
  29. com.sun.xml.internal.ws.fault.ServerSOAPFaultException: Client received SOAP Fault from server: java.lang.NullPointerException Please see the server log to find more detail regarding exact cause of the failure.
  30.     at com.sun.xml.internal.ws.fault.SOAP11Fault.getProtocolException(SOAP11Fault.java:178)
  31.     at com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createException(SOAPFaultBuilder.java:116)
  32.     at com.sun.xml.internal.ws.client.sei.StubHandler.readResponse(StubHandler.java:238)
  33.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:189)
  34.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:276)
  35.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:104)
  36.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:77)
  37.     at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(SEIStub.java:147)
  38.     at com.sun.proxy.$Proxy240.dissociatingCard(Unknown Source)
  39.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1469)
  40.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  41.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  42.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  43.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  44.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  45.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  46.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  47.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  48.     at java.lang.Thread.run(Thread.java:745)
  49. 22.08 13:47:47.233 ERROR [MLServiceImpl] leaving unbind(String): failed to connect to any of the servers [1] in spite of spending {}
  50. 22.08 13:47:47.235 ERROR [MLServiceImpl] Could not send pending operation (id: 168716; date: 2019-08-22 01:02:38.464)
  51. ru.crystals.pos.ml.exception.MLConnectionException: Все сервера ML POS не доступны
  52.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1485)
  53.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  54.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  55.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  56.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  57.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  58.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  59.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  60.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  61.     at java.lang.Thread.run(Thread.java:745)
  62. 22.08 13:47:47.236 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  63.     Id: MQ==
  64.     Date: 2019-08-22 01:01:37.043
  65. 22.08 13:47:47.236 TRACE [MLServiceImpl] entering getServices()
  66. 22.08 13:47:47.236 TRACE [MLServiceImpl] getting services (IN MEMORY)
  67. 22.08 13:47:47.236 TRACE [MLServiceImpl] leaving getServices(). The result size is: 1
  68. 22.08 13:47:47.236 TRACE [MLServiceImpl] Going to send DissociatingCard:
  69. 22.08 13:47:47.237 TRACE [MLServiceImpl] class ru.crystals.pos.ml.wsclient.DissociatingCard is <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  70. <DissociatingCard xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  71.     <request>
  72.         <ClientTransactionId>1</ClientTransactionId>
  73.         <Organization>Victoria</Organization>
  74.         <BusinessUnit>7189</BusinessUnit>
  75.         <POS>61</POS>
  76.         <CardNumber>99990002</CardNumber>
  77.         <DateTime>2019-08-22T13:47:47.236+03:00</DateTime>
  78.     </request>
  79. </DissociatingCard>
  80.  
  81. 22.08 13:47:47.286 WARN  [MLServiceImpl] failed to connect to a ML POS web-service
  82. com.sun.xml.internal.ws.fault.ServerSOAPFaultException: Client received SOAP Fault from server: java.lang.NullPointerException Please see the server log to find more detail regarding exact cause of the failure.
  83.     at com.sun.xml.internal.ws.fault.SOAP11Fault.getProtocolException(SOAP11Fault.java:178)
  84.     at com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createException(SOAPFaultBuilder.java:116)
  85.     at com.sun.xml.internal.ws.client.sei.StubHandler.readResponse(StubHandler.java:238)
  86.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:189)
  87.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:276)
  88.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:104)
  89.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:77)
  90.     at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(SEIStub.java:147)
  91.     at com.sun.proxy.$Proxy240.dissociatingCard(Unknown Source)
  92.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1469)
  93.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  94.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  95.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  96.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  97.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  98.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  99.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  100.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  101.     at java.lang.Thread.run(Thread.java:745)
  102. 22.08 13:47:47.287 ERROR [MLServiceImpl] leaving unbind(String): failed to connect to any of the servers [1] in spite of spending {}
  103. 22.08 13:47:47.289 ERROR [MLServiceImpl] Could not send pending operation (id: 168713; date: 2019-08-22 01:01:37.043)
  104. ru.crystals.pos.ml.exception.MLConnectionException: Все сервера ML POS не доступны
  105.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1485)
  106.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  107.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  108.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  109.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  110.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  111.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  112.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  113.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  114.     at java.lang.Thread.run(Thread.java:745)
  115. 22.08 13:47:47.289 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  116.     Id: MQ==
  117.     Date: 2019-08-22 01:01:48.868
  118. 22.08 13:47:47.289 TRACE [MLServiceImpl] entering getServices()
  119. 22.08 13:47:47.289 TRACE [MLServiceImpl] getting services (IN MEMORY)
  120. 22.08 13:47:47.290 TRACE [MLServiceImpl] leaving getServices(). The result size is: 1
  121. 22.08 13:47:47.290 TRACE [MLServiceImpl] Going to send DissociatingCard:
  122. 22.08 13:47:47.290 TRACE [MLServiceImpl] class ru.crystals.pos.ml.wsclient.DissociatingCard is <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  123. <DissociatingCard xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  124.     <request>
  125.         <ClientTransactionId>1</ClientTransactionId>
  126.         <Organization>Victoria</Organization>
  127.         <BusinessUnit>7189</BusinessUnit>
  128.         <POS>61</POS>
  129.         <CardNumber>99990002</CardNumber>
  130.         <DateTime>2019-08-22T13:47:47.290+03:00</DateTime>
  131.     </request>
  132. </DissociatingCard>
  133.  
  134. 22.08 13:47:47.345 WARN  [MLServiceImpl] failed to connect to a ML POS web-service
  135. com.sun.xml.internal.ws.fault.ServerSOAPFaultException: Client received SOAP Fault from server: java.lang.NullPointerException Please see the server log to find more detail regarding exact cause of the failure.
  136.     at com.sun.xml.internal.ws.fault.SOAP11Fault.getProtocolException(SOAP11Fault.java:178)
  137.     at com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createException(SOAPFaultBuilder.java:116)
  138.     at com.sun.xml.internal.ws.client.sei.StubHandler.readResponse(StubHandler.java:238)
  139.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:189)
  140.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:276)
  141.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:104)
  142.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:77)
  143.     at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(SEIStub.java:147)
  144.     at com.sun.proxy.$Proxy240.dissociatingCard(Unknown Source)
  145.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1469)
  146.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  147.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  148.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  149.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  150.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  151.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  152.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  153.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  154.     at java.lang.Thread.run(Thread.java:745)
  155. 22.08 13:47:47.345 ERROR [MLServiceImpl] leaving unbind(String): failed to connect to any of the servers [1] in spite of spending {}
  156. 22.08 13:47:47.346 ERROR [MLServiceImpl] Could not send pending operation (id: 168714; date: 2019-08-22 01:01:48.868)
  157. ru.crystals.pos.ml.exception.MLConnectionException: Все сервера ML POS не доступны
  158.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1485)
  159.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  160.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  161.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  162.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  163.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  164.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  165.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  166.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  167.     at java.lang.Thread.run(Thread.java:745)
  168. 22.08 13:47:47.347 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  169.     Id: MQ==
  170.     Date: 2019-08-22 01:01:59.057
  171. 22.08 13:47:47.347 TRACE [MLServiceImpl] entering getServices()
  172. 22.08 13:47:47.347 TRACE [MLServiceImpl] getting services (IN MEMORY)
  173. 22.08 13:47:47.347 TRACE [MLServiceImpl] leaving getServices(). The result size is: 1
  174. 22.08 13:47:47.347 TRACE [MLServiceImpl] Going to send DissociatingCard:
  175. 22.08 13:47:47.347 TRACE [MLServiceImpl] class ru.crystals.pos.ml.wsclient.DissociatingCard is <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  176. <DissociatingCard xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  177.     <request>
  178.         <ClientTransactionId>1</ClientTransactionId>
  179.         <Organization>Victoria</Organization>
  180.         <BusinessUnit>7189</BusinessUnit>
  181.         <POS>61</POS>
  182.         <CardNumber>99990005</CardNumber>
  183.         <DateTime>2019-08-22T13:47:47.347+03:00</DateTime>
  184.     </request>
  185. </DissociatingCard>
  186.  
  187. 22.08 13:47:47.398 WARN  [MLServiceImpl] failed to connect to a ML POS web-service
  188. com.sun.xml.internal.ws.fault.ServerSOAPFaultException: Client received SOAP Fault from server: java.lang.NullPointerException Please see the server log to find more detail regarding exact cause of the failure.
  189.     at com.sun.xml.internal.ws.fault.SOAP11Fault.getProtocolException(SOAP11Fault.java:178)
  190.     at com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createException(SOAPFaultBuilder.java:116)
  191.     at com.sun.xml.internal.ws.client.sei.StubHandler.readResponse(StubHandler.java:238)
  192.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:189)
  193.     at com.sun.xml.internal.ws.db.DatabindingImpl.deserializeResponse(DatabindingImpl.java:276)
  194.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:104)
  195.     at com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:77)
  196.     at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(SEIStub.java:147)
  197.     at com.sun.proxy.$Proxy240.dissociatingCard(Unknown Source)
  198.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1469)
  199.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  200.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  201.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  202.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  203.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  204.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  205.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  206.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  207.     at java.lang.Thread.run(Thread.java:745)
  208. 22.08 13:47:47.411 ERROR [MLServiceImpl] leaving unbind(String): failed to connect to any of the servers [1] in spite of spending {}
  209. 22.08 13:47:47.412 ERROR [MLServiceImpl] Could not send pending operation (id: 168715; date: 2019-08-22 01:01:59.057)
  210. ru.crystals.pos.ml.exception.MLConnectionException: Все сервера ML POS не доступны
  211.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1485)
  212.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  213.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1542)
  214.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  215.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  216.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  217.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  218.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  219.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  220.     at java.lang.Thread.run(Thread.java:745)
  221. 22.08 13:47:47.962 DEBUG [TechProcessImpl] Server online mode
  222. 22.08 13:47:49.905 INFO  [CommonLogger] Starting cleaning. All log records older than 24 мая 2019 13:47:49 will be deleted.
  223. 22.08 13:47:49.907 INFO  [CommonLogger] Cleaning done.
  224. 22.08 13:47:50.637 INFO  [CashConfigurationUpdateChecker] Current status: IN_WORK
  225. 22.08 13:47:50.643 INFO  [CashConfigurationUpdateChecker] Received patches list: []
  226. 22.08 13:47:50.648 INFO  [CashConfigurationUpdateChecker] isNeedWaitUpdateCommand: false
  227. 22.08 13:47:50.648 INFO  [CashConfigurationUpdateChecker] sleepInt(60000)
  228. 22.08 13:48:02.349 INFO  [TransferManager] OD found 0 documents to register
  229. 22.08 13:48:02.374 INFO  [DocumentSender] OD found 0 transactions to register
  230. 22.08 13:48:02.379 INFO  [DocumentSender] response message = Created
  231. 22.08 13:48:02.380 INFO  [TransferManager] Send document status : 35212 > WAIT_ACKNOWLEDGEMENT            file name : 61/Document_20190822134802_377_7189_61.ser
  232. 22.08 13:48:02.383 INFO  [CheckService] SetSentToServerStatus : 35212 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20190822134802_377_7189_61.ser
  233. 22.08 13:48:02.388 INFO  [DocumentSender] document 61/Document_20190822134802_377_7189_61.ser has been registered
  234. 22.08 13:48:02.389 INFO  [TransferManager] Send document status : 35212 > SENT            file name : 61/Document_20190822134802_377_7189_61.ser
  235. 22.08 13:48:02.393 INFO  [CheckService] SetSentToServerStatus : 35212 > SENT file name : 61/Document_20190822134802_377_7189_61.ser
  236. 22.08 13:48:02.420 INFO  [DocumentSender] OD found 0 payment transactions
  237. 22.08 13:48:11.286 INFO  [TransferManager] File 61/LoyTransaction_20190822134811_276_7189_61.ser sucessfully registered
  238. 22.08 13:48:11.286 INFO  [TransferManager] 1 transactions processed to upload; 1 transactions processed to register; Time: 13 ms.
  239. 22.08 13:48:17.965 DEBUG [TechProcessImpl] Server online mode
  240. 22.08 13:48:18.884 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  241. 22.08 13:48:18.885 INFO  [TechProcessImpl] TP searchProduct 00888
  242. 22.08 13:48:18.990 INFO  [CatalogService] Get product by item '00888' result ProductPieceEntity[name=Уксус лимонный пряный (Россия), item=00888]
  243. 22.08 13:48:18.991 INFO  [SpeedLog] getProductRestrictions  time = 1 ms
  244. 22.08 13:48:18.991 INFO  [SpeedLog] searchProduct time  time = 106 ms
  245. 22.08 13:48:19.001 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,010 sec; average speed = 0,091 sec; position test num = 4.0; product item 00888; <10>
  246. 22.08 13:48:19.001 INFO  [CommonLogger] busy = false
  247. 22.08 13:48:19.003 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  248. 22.08 13:48:19.003 INFO  [TextCustomerDisplayPluginAbstract] **********************
  249. 22.08 13:48:19.003 INFO  [TextCustomerDisplayPluginAbstract] *Уксус ли 1.00 116.67*
  250. 22.08 13:48:19.003 INFO  [TextCustomerDisplayPluginAbstract] *Итого         116.67*
  251. 22.08 13:48:19.003 INFO  [TextCustomerDisplayPluginAbstract] **********************
  252. 22.08 13:48:19.004 INFO  [TextCustomerDisplayPluginAbstract]
  253. 22.08 13:48:19.020 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  254. java.security.InvalidKeyException: Unwrapping failed
  255.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  256.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  257.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  258.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  259.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  260.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  261.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  262.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  263.     at java.lang.Thread.run(Thread.java:745)
  264. Caused by: javax.crypto.BadPaddingException: Decryption error
  265.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  266.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  267.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  268.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  269.     ... 8 more
  270. 22.08 13:48:19.033 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  271. java.security.InvalidKeyException: Unwrapping failed
  272.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  273.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  274.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  275.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  276.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  277.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  278.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  279.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  280.     at java.lang.Thread.run(Thread.java:745)
  281. Caused by: javax.crypto.BadPaddingException: Decryption error
  282.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  283.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  284.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  285.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  286.     ... 8 more
  287. 22.08 13:48:22.003 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 00888; type: ProductPieceEntity; count: 50000; sum: 583350; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  288. 22.08 13:48:22.019 INFO  [SpeedLog] TP addPosition time = 16 ms
  289. 22.08 13:48:22.030 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  290. 22.08 13:48:22.030 INFO  [TextCustomerDisplayPluginAbstract] **********************
  291. 22.08 13:48:22.030 INFO  [TextCustomerDisplayPluginAbstract] *Уксус  50.00 5833.50*
  292. 22.08 13:48:22.031 INFO  [TextCustomerDisplayPluginAbstract] *Итого        5833.50*
  293. 22.08 13:48:22.031 INFO  [TextCustomerDisplayPluginAbstract] **********************
  294. 22.08 13:48:22.031 INFO  [TextCustomerDisplayPluginAbstract]
  295. 22.08 13:48:22.034 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  296. java.security.InvalidKeyException: Unwrapping failed
  297.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  298.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  299.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  300.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  301.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  302.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  303.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  304.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  305.     at java.lang.Thread.run(Thread.java:745)
  306. Caused by: javax.crypto.BadPaddingException: Decryption error
  307.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  308.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  309.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  310.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  311.     ... 8 more
  312. 22.08 13:48:24.740 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  313. 22.08 13:48:24.740 INFO  [TechProcessImpl] TP searchProduct 9905100100001
  314. 22.08 13:48:24.741 INFO  [CatalogService] getGoodsByBarCode barcode = 9905100100001
  315. 22.08 13:48:24.743 INFO  [CatalogService] get product by barcode '9905100100001' result: null
  316. 22.08 13:48:24.743 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 9905100100001 time = 2 ms
  317. 22.08 13:48:24.743 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 9905100100001 time = 2 ms
  318. 22.08 13:48:24.743 INFO  [CatalogService] Get product by item '9905100100001' result null
  319. 22.08 13:48:24.743 INFO  [SpeedLog] searchProduct time  time = 2 ms
  320. 22.08 13:48:24.749 TRACE [SCService] entering isEnabled()
  321. 22.08 13:48:24.750 TRACE [SCService] leaving isEnabled(). the result is: true
  322. 22.08 13:48:24.750 TRACE [SCService] entering isSmChCoupon(String). The argument is: "9905100100001"
  323. 22.08 13:48:24.750 TRACE [SCService] leaving isSmChCoupon(String). The result is: false
  324. 22.08 13:48:24.750 TRACE [MLServiceImpl] entering isEnabled()
  325. 22.08 13:48:24.751 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  326. 22.08 13:48:24.751 TRACE [MLServiceImpl] entering isMlCoupon(String). The argument is: "9905100100001"
  327. 22.08 13:48:24.751 TRACE [MLServiceImpl] leaving isMlCoupon(String). The result is: false
  328. 22.08 13:48:24.753 TRACE [MLServiceImpl] entering isEnabled()
  329. 22.08 13:48:24.753 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  330. 22.08 13:48:24.753 TRACE [MLServiceImpl] entering isMlCard(String, boolean). The argument are: "9905100100001"
  331. 22.08 13:48:24.753 TRACE [MLServiceImpl] leaving isMlCard(String, boolean). The result is: false
  332. 22.08 13:48:24.753 TRACE [CardPluginEmulator] entering getCardInfo(String). The argument is: 9905100100001
  333. 22.08 13:48:24.753 TRACE [CardPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  334. 22.08 13:48:24.754 TRACE [CardPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: CardPluginEmulatorProperties{getInfoUrl=http://127.0.0.1:10071/card-plugin-processing/emulator/get-card, writeOffUrl=http://127.0.0.1:10071/card-plugin-processing/emulator/write-off, rollbackUrl=http://127.0.0.1:10071/card-plugin-processing/emulator/rollback, connectionTimeout=1000, readTimeout=1000, cardNoPrefixes=[151, 152], cardNoLength=7}; it took 1 [ms]
  335. 22.08 13:48:24.754 TRACE [CardPluginEmulator] leaving getCardInfo(String). The card (no: "9905100100001") does not belong to our processing for sure!
  336. 22.08 13:48:24.754 DEBUG [ExampleCardPlugin] entering getCardInfo(CardInfoSearchParams). The arguments are: request [ru.crystals.pos.cards.service.PluginCardSearchRequest@1f27f63]
  337. 22.08 13:48:24.755 DEBUG [ExampleCardPlugin] leaving getCardInfo(CardInfoSearchParams). The card [9905100100001] does not exist in the processing for sure
  338. 22.08 13:48:24.790 INFO  [RegistryImpl] Subscription URLs of module CARDS:
  339.     JNP : jnp://172.29.17.189:1099
  340.     HTTP : http://172.29.17.189:8090
  341. 22.08 13:48:24.791 INFO  [RegistryImpl] Try register SubscriptionURL - CARDS : JNP:jnp://172.29.17.189:1099
  342. 22.08 13:48:24.791 INFO  [RegistryImpl] Try register SubscriptionURL - CARDS : HTTP:http://172.29.17.189:8090
  343. 22.08 13:48:24.829 WARN  [BonusBalanceSupplier] Processing "ServerCardsApplication" has provided no bonus balance for card "9905100100001"
  344. 22.08 13:48:25.046 TRACE [TechProcessImpl] entering applyClientRequisites(ClientEntity). The argument is: holder [ClientEntity [id=20656; guid=110769; deleted=false]]
  345. 22.08 13:48:25.047 TRACE [TechProcessImpl] leaving applyClientRequisites(ClientEntity). Cash template prohibits usage of personal-sensitive data to send e-receipt to a card-ho;der
  346. 22.08 13:48:25.068 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  347. java.security.InvalidKeyException: Unwrapping failed
  348.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  349.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  350.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  351.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  352.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  353.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  354.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  355.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  356.     at java.lang.Thread.run(Thread.java:745)
  357. Caused by: javax.crypto.BadPaddingException: Decryption error
  358.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  359.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  360.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  361.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  362.     ... 8 more
  363. 22.08 13:48:25.261 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  364. 22.08 13:48:25.283 INFO  [CommonLogger] busy = true
  365. 22.08 13:48:25.285 TRACE [TechProcessImpl] Start warn divide alco
  366. 22.08 13:48:25.285 TRACE [TechProcessImpl] End warn devide alco
  367. 22.08 13:48:25.290 INFO  [TechProcessImpl] goToDiscounts
  368. 22.08 13:48:25.307 INFO  [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  369. 22.08 13:48:25.311 INFO  [LoyalProductFinder] Time finding items (1) = 4 ms
  370. 22.08 13:48:25.311 INFO  [LoyalProductsConverter] Time searching loyal goods for 1 productItems = 4 ms
  371. 22.08 13:48:25.311 INFO  [LoyalCalculatorConverter] Time converting 1 positions = 0 ms
  372. 22.08 13:48:25.317 TRACE [LoyaltyRestrictionsServiceImpl] checkRestrictions: no actions for requesting limits
  373. 22.08 13:48:25.317 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  374. 22.08 13:48:25.317 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  375. 22.08 13:48:25.323 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  376. 22.08 13:48:25.324 TRACE [LoyPluginEmulator] entering doDiscount(Receipt). The argument is: ru.crystals.api.commons.ReceiptPurchaseWrapper@1047b80
  377. 22.08 13:48:25.324 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  378. 22.08 13:48:25.324 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  379. 22.08 13:48:25.325 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, cardProcessingId='null'}; it took 1 [ms]
  380. 22.08 13:48:25.325 TRACE [LoyPluginEmulator] entering makeCalcCalls(List, EmulatorReceipt). The arguments are: urls: [http://127.0.0.1:10065/loy-plugin-processing/emulator/process], emulatorReceipt: emul-receipt [card: null; positions: [emul-pos [no: 1; marking: "00888"; qnty: 50000; sum: 583350; min-sum: 0; discountable: true]]]
  381. 22.08 13:48:25.363 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  382. 22.08 13:48:25.380 TRACE [LoyPluginEmulator] leaving makeCalcCalls(List, EmulatorReceipt). The result is: emul-loy-result [discounts: []; limit: null; taken-into-account: null]; it took 55 [ms]
  383. 22.08 13:48:25.380 TRACE [LoyPluginEmulator] leaving doDiscount(Receipt). The result is: LoyaltyResult{discounts=null, bonusWriteOffsLimits=null, bonusesTakenIntoAccount=null, bonusAccrualResults=null, printDocuments=null, messagesToCashier=null, extendedAttributeMap=null}; it took 56 [ms]
  384. 22.08 13:48:25.381 INFO  [LoyaltyResultSanityChecker] Checking MRP restrictions
  385. Quantity 50000
  386. Position sum 5833.50
  387. Value 0
  388. Min price 0.00
  389. 22.08 13:48:25.381 INFO  [LoyaltyResultSanityChecker] Unable to check MRP restriction: no MRP provided
  390. 22.08 13:48:25.381 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase{number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, checkSum=583350, operationType=true, discountValueTotal=0}
  391. 22.08 13:48:25.382 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: null
  392. 22.08 13:48:25.382 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase{number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, checkSum=583350, operationType=true, discountValueTotal=0}
  393. 22.08 13:48:25.382 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  394. 22.08 13:48:25.382 TRACE [SCService] entering isEnabled()
  395. 22.08 13:48:25.382 TRACE [SCService] leaving isEnabled(). the result is: true
  396. 22.08 13:48:25.382 TRACE [SCService] entering calcDiscount(Purchase). The argument is: Purchase{number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, checkSum=583350, operationType=true, discountValueTotal=0}
  397. 22.08 13:48:25.386 TRACE [SCService] Send request <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  398. <ChequeRequest xmlns="http://smartcheckout.ru/xsd/s10ren.xsd" ChequeType="Soft">
  399.     <RequestID>1566470902003</RequestID>
  400.     <Card>
  401.         <CardNumber>9905100100001</CardNumber>
  402.     </Card>
  403.     <DateTime>2019-08-22T13:48:25.382+03:00</DateTime>
  404.     <POS>61</POS>
  405.     <Number>1566470902003</Number>
  406.     <Summ>5833.50</Summ>
  407.     <Discount>0.000</Discount>
  408.     <SummDiscounted>5833.50</SummDiscounted>
  409.     <Item>
  410.         <PositionNumber>1</PositionNumber>
  411.         <Article>00888</Article>
  412.         <ArticleName>Уксус лимонный пряный (Россия)</ArticleName>
  413.         <Price>116.67</Price>
  414.         <Quantity>50.000</Quantity>
  415.         <Summ>5833.50</Summ>
  416.         <Discount>0.000</Discount>
  417.         <SummDiscounted>5833.50</SummDiscounted>
  418.     </Item>
  419. </ChequeRequest>
  420.  
  421. 22.08 13:48:25.386 TRACE [SCService] entering getServices()
  422. 22.08 13:48:25.386 TRACE [SCService] getting services (IN MEMORY)
  423. 22.08 13:48:25.386 TRACE [SCService] leaving getServices(). The result size is: 2
  424. 22.08 13:48:25.401 TRACE [SCService] Received response: <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  425. <ChequeRequestResponce xmlns="http://smartcheckout.ru/xsd/s10ren.xsd">
  426.     <RequestID>1566470902003</RequestID>
  427.     <Processed>2019-08-22T13:48:25.391+03:00</Processed>
  428.     <ReturnCode>0</ReturnCode>
  429.     <CashierMessageTO>0</CashierMessageTO>
  430.     <Summ>5833.50</Summ>
  431.     <Discount>0.000</Discount>
  432.     <SummDiscounted>5833.50</SummDiscounted>
  433.     <Item>
  434.         <PositionNumber>1</PositionNumber>
  435.         <Article>00888</Article>
  436.         <ArticleName>Уксус лимонный пряный (Россия)</ArticleName>
  437.         <Price>116.67</Price>
  438.         <MinPrice>0</MinPrice>
  439.         <Quantity>50.000</Quantity>
  440.         <Summ>5833.50</Summ>
  441.         <Discount>0.000</Discount>
  442.         <SummDiscounted>5833.50</SummDiscounted>
  443.     </Item>
  444. </ChequeRequestResponce>
  445.  
  446. 22.08 13:48:25.401 TRACE [SCReceiptConverter] ml discounts: {position-key [marking: 00888; original-price: 11667]=0}
  447. 22.08 13:48:25.401 TRACE [SCReceiptConverter] it was inferred that no SC POS discounts were given on position [key: position-key [marking: 00888; original-price: 11667]]: SC discount value is: 0
  448. 22.08 13:48:25.401 TRACE [SCService] leaving calcDiscount(Purchase). The result is: Purchase{number=null, dateCreate=null, checkSum=583350, operationType=null, discountValueTotal=0}; it took 19 [ms]
  449. 22.08 13:48:25.403 DEBUG [LoymaxUtils] Loymax request:
  450. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><Calculates><CalculateRequest PurchaseID="71890611566470905401" OperationID="43ddcf1766ce4ab69c33141ef23fe455" OperationDate="2019-08-22T13:48:25.402+03:00" ElementID="1"><Cheque ChequeDate="2019-08-22T13:48:22.003+03:00"><ChequeLine PosID="35233" Amount="5833.50" Name="Уксус лимонный пряный (Россия)" GoodsId="00888" Price="116.67" Quantity="50.000"/></Cheque></CalculateRequest></Calculates></XMLRequest>
  451.  
  452. 22.08 13:48:25.421 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  453. 22.08 13:48:25.423 DEBUG [LoymaxUtils] Loymax response:
  454. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><Calculates><CalculateResponse AvailableAmount="5833.50" AvailableBonusAmount="0" ErrorCode="0"><Cheque ChequeDate="2019-08-22T13:48:22.003+03:00"><ChequeLine PosID="35233" Amount="5833.50" Name="Уксус лимонный пряный (Россия)" GoodsId="00888" Price="116.67" Quantity="50.000" Discount="0"/></Cheque></CalculateResponse></Calculates></XMLResponse>
  455.  
  456. 22.08 13:48:25.431 TRACE [SMServiceImpl] entering calcDiscount(Purchase, long, long, ILoyTechProcess). receipt-id: 35213; action-guid: 1890143020
  457. 22.08 13:48:25.434 TRACE [SMServiceEmulator] entering getCurrentOffers(String, String). The arguments are: authHeader [Bearer MB>B B>:5= 25G5=], clientGUID [{"clientIdentifier":{"type":"GUID","id":"110769"},"timeZoneId":"Europe/Moscow"}]
  458. 22.08 13:48:25.435 TRACE [SMServiceEmulator] entering getDelay(long, XmlRequestTypeEnum). The arguments are: guid [110769], requestType [OFFERS]
  459. 22.08 13:48:25.435 INFO  [SMServiceEmulator] reading SM-base...
  460. 22.08 13:48:25.435 DEBUG [SMServiceEmulator] The array a XmlSmBase object to be extracted from is neither empty nor null - its length is: 12627
  461. 22.08 13:48:25.475 DEBUG [SMServiceEmulator] Unmarshalling was complete successfully!
  462. 22.08 13:48:25.475 TRACE [SMServiceEmulator] leaving getDelay(long, XmlRequestTypeEnum). The result is: 500
  463. 22.08 13:48:25.975 TRACE [SMServiceEmulator] No need to throw an exception for [holder-guid: 110769; request-type: OFFERS]
  464. 22.08 13:48:25.975 TRACE [SMServiceEmulator] leaving getCurrentOffers(String, String)
  465. 22.08 13:48:25.978 TRACE [SMServiceImpl] SM-offers received: offers-container [client-guid: "110769"; offers: []]
  466. 22.08 13:48:25.978 TRACE [SetMachineCalculator] entering applyDiscount(OfferDTO, Purchase). The arguments are: discount [offers-container [client-guid: "110769"; offers: []]], receipt [Purchase{number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, checkSum=583350, operationType=true, discountValueTotal=0}], actionGuid [1890143020]
  467. 22.08 13:48:25.978 TRACE [SetMachineCalculator] leaving applyDiscount(OfferDTO, Purchase): no offers
  468. 22.08 13:48:25.978 TRACE [SMServiceImpl] leaving calcDiscount(Purchase, long, long, ILoyTechProcess). It took 547 [ms]
  469. 22.08 13:48:25.990 TRACE [LoyaltyRestrictionsServiceImpl] checkRestrictions: request = {"clientId":"110769","timeZoneId":"Europe/Moscow","actions":["SR10_78012"]}
  470. 22.08 13:48:26.008 ERROR [LoyaltyRestrictionsServiceImpl] checkRestrictions: failed. Request: {"clientId":"110769","timeZoneId":"Europe/Moscow","actions":["SR10_78012"]}
  471. org.springframework.web.client.HttpClientErrorException: 404 Not Found
  472.     at org.springframework.web.client.DefaultResponseErrorHandler.handleError(DefaultResponseErrorHandler.java:91)
  473.     at org.springframework.web.client.RestTemplate.handleResponse(RestTemplate.java:616)
  474.     at org.springframework.web.client.RestTemplate.doExecute(RestTemplate.java:572)
  475.     at org.springframework.web.client.RestTemplate.execute(RestTemplate.java:532)
  476.     at org.springframework.web.client.RestTemplate.postForObject(RestTemplate.java:332)
  477.     at ru.crystals.pos.loyalty.LoyaltyRestrictionsServiceImpl.checkRestrictions(LoyaltyRestrictionsServiceImpl.java:204)
  478.     at ru.crystals.loyal.providers.ClassicSetLoyProvider.process(ClassicSetLoyProvider.java:103)
  479.     at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:116)
  480.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:767)
  481.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:694)
  482.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:807)
  483.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:162)
  484.     at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3786)
  485.     at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3349)
  486.     at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1565)
  487.     at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1536)
  488.     at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1433)
  489.     at ru.crystals.pos.visualization.components.MainWindow.subTotal(MainWindow.java:802)
  490.     at ru.crystals.pos.visualization.Factory.eventFunctionKey(Factory.java:592)
  491.     at ru.crystals.pos.techprocess.TechProcessImpl.eventFunctionKey(TechProcessImpl.java:4400)
  492.     at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:165)
  493.     at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  494.     at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  495.     at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  496.     at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  497.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  498.     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  499.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  500.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  501.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  502.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  503.     at java.lang.Thread.run(Thread.java:745)
  504. 22.08 13:48:26.009 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  505. 22.08 13:48:26.009 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  506. 22.08 13:48:26.015 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  507. 22.08 13:48:26.017 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  508. 22.08 13:48:26.017 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  509. 22.08 13:48:26.018 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  510. 22.08 13:48:26.064 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  511. 22.08 13:48:26.065 ERROR [DefaultCurrencyHandler] roundEndPriceForSale(BigDecimal, long, long): failed to calc end price through exact sum [291675] using rounding mode: CEILING: result * count == 5834 * 50000 == 291700 what differs from the control (rounded already) end sum : 291675
  512. 22.08 13:48:26.066 TRACE [LoyaltyRestrictionsServiceImpl] checkRestrictions: no actions for requesting limits
  513. 22.08 13:48:26.066 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  514. 22.08 13:48:26.066 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  515. 22.08 13:48:26.066 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  516. 22.08 13:48:26.067 ERROR [DefaultCurrencyHandler] roundEndPriceForSale(BigDecimal, long, long): failed to calc end price through exact sum [291675] using rounding mode: CEILING: result * count == 5834 * 50000 == 291700 what differs from the control (rounded already) end sum : 291675
  517. 22.08 13:48:26.068 ERROR [DefaultCurrencyHandler] roundEndPriceForSale(BigDecimal, long, long): failed to calc end price through exact sum [291675] using rounding mode: CEILING: result * count == 5834 * 50000 == 291700 what differs from the control (rounded already) end sum : 291675
  518. 22.08 13:48:26.075 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  519. 22.08 13:48:26.076 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  520. 22.08 13:48:26.076 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  521. 22.08 13:48:26.078 INFO  [FL54WeightPositionsTuner] Can't restore the original purchase sum, it has changed by 25.
  522. 22.08 13:48:26.078 INFO  [SetApiPluginLoyProvider] Plugin "ru.crystals.loy.plugin.emulator" does not do post discount tuning
  523. 22.08 13:48:26.078 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase{number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, checkSum=583350, operationType=true, discountValueTotal=0}
  524. 22.08 13:48:26.079 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: null
  525. 22.08 13:48:26.079 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase{number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, checkSum=583350, operationType=true, discountValueTotal=0}
  526. 22.08 13:48:26.079 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  527. 22.08 13:48:26.083 INFO  [LoyalServiceImpl] Discount calculation (Purchase #6; Positions: 1
  528. 22.08 13:48:26.084 WARN  [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  529. 22.08 13:48:26.093 INFO  [LoyalBridgeServiceImpl] Total time of discount calculation = 786 ms
  530. 22.08 13:48:26.093 INFO  [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  531. 22.08 13:48:26.101 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  532. java.security.InvalidKeyException: Unwrapping failed
  533.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  534.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  535.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  536.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  537.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  538.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  539.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  540.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  541.     at java.lang.Thread.run(Thread.java:745)
  542. Caused by: javax.crypto.BadPaddingException: Decryption error
  543.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  544.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  545.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  546.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  547.     ... 8 more
  548. 22.08 13:48:26.109 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  549. 22.08 13:48:26.109 INFO  [TextCustomerDisplayPluginAbstract] **********************
  550. 22.08 13:48:26.109 INFO  [TextCustomerDisplayPluginAbstract] *Скидка       2917.00*
  551. 22.08 13:48:26.109 INFO  [TextCustomerDisplayPluginAbstract] *Итого        2916.50*
  552. 22.08 13:48:26.109 INFO  [TextCustomerDisplayPluginAbstract] **********************
  553. 22.08 13:48:26.109 INFO  [TextCustomerDisplayPluginAbstract]
  554. 22.08 13:48:26.114 INFO  [SpeedLog] TechProcess discounts processing on cheque with 1 positions [sale] time = 824 ms
  555. 22.08 13:48:26.115 INFO  [CommonLogger] value = 291700
  556. 22.08 13:48:26.150 TRACE [TechProcessImpl] Start warn divide amount
  557. 22.08 13:48:26.150 INFO  [TechProcessImpl] goToPayments
  558. 22.08 13:48:26.164 INFO  [CheckContainer] startPayments() surcharge = 291650
  559. 22.08 13:48:26.169 INFO  [CheckContainer] isPaymentComplete = false
  560. 22.08 13:48:26.169 INFO  [CheckContainer] prevState = ADD_POSITION
  561. 22.08 13:48:26.170 INFO  [CheckContainer] paid = 0
  562. 22.08 13:48:26.170 INFO  [CheckContainer] surcharge = 291650
  563. 22.08 13:48:26.170 INFO  [CheckContainer] getPaymentFiscalizeError = NONE
  564. 22.08 13:48:26.170 INFO  [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  565. 22.08 13:48:26.179 INFO  [CommonLogger] busy = false
  566. 22.08 13:48:26.179 INFO  [SpeedLog] (SUBTOTAL) time = 0,895;
  567. 22.08 13:48:26.180 INFO  [CommonLogger] busy = false
  568. 22.08 13:48:27.028 INFO  [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=50, character=2]
  569. 22.08 13:48:27.484 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  570. 22.08 13:48:27.612 INFO  [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=51, character=3]
  571. 22.08 13:48:27.708 INFO  [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=48, character=0]
  572. 22.08 13:48:27.861 INFO  [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=48, character=0]
  573. 22.08 13:48:28.052 INFO  [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=48, character=0]
  574. 22.08 13:48:28.372 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  575. 22.08 13:48:28.372 INFO  [CommonLogger] doProcessPayment() in class ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  576. 22.08 13:48:28.372 INFO  [TechProcessImpl] addPayment CashPaymentEntity 300000
  577. 22.08 13:48:28.389 TRACE [TechProcessEventsImpl] entering purchaseChangedAtPaymentsStage(PurchaseEntity, List). The arguments are: purchase [PurchaseEntity [id=35213, number=null, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]], notifications [[]]
  578. 22.08 13:48:28.403 INFO  [CommonLogger] value = 291700
  579. 22.08 13:48:28.420 TRACE [TechProcessEventsImpl] leaving purchaseChangedAtPaymentsStage(PurchaseEntity, List). It took 31 [ms]
  580. 22.08 13:48:28.424 INFO  [TechProcessShift] fiscalizeCheck PurchaseEntity [id=35213, number=null, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  581. 22.08 13:48:28.431 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  582. java.security.InvalidKeyException: Unwrapping failed
  583.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  584.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  585.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  586.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  587.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  588.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  589.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  590.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  591.     at java.lang.Thread.run(Thread.java:745)
  592. Caused by: javax.crypto.BadPaddingException: Decryption error
  593.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  594.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  595.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  596.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  597.     ... 8 more
  598. 22.08 13:48:28.432 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  599. 22.08 13:48:28.446 INFO  [TextCustomerDisplayPluginAbstract] **********************
  600. 22.08 13:48:28.446 INFO  [TextCustomerDisplayPluginAbstract] *Оплачено     3000.00*
  601. 22.08 13:48:28.446 INFO  [TextCustomerDisplayPluginAbstract] *Cдача          83.50*
  602. 22.08 13:48:28.446 INFO  [TextCustomerDisplayPluginAbstract] **********************
  603. 22.08 13:48:28.446 INFO  [TextCustomerDisplayPluginAbstract]
  604. 22.08 13:48:28.469 INFO  [FiscalPrinter] getLastKpk
  605. 22.08 13:48:28.469 INFO  [FiscalPrinter] LastKpk = 1183
  606. 22.08 13:48:28.476 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  607. java.security.InvalidKeyException: Unwrapping failed
  608.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  609.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  610.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  611.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  612.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  613.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  614.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  615.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  616.     at java.lang.Thread.run(Thread.java:745)
  617. Caused by: javax.crypto.BadPaddingException: Decryption error
  618.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  619.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  620.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  621.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  622.     ... 8 more
  623. 22.08 13:48:28.542 INFO  [CFTBridgeImpl] Bonuses can not be charged to the card. Purchase does not contain Sberbank cards. Purchase: PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  624. 22.08 13:48:28.627 WARN  [SetApiPluginLoyProvider] No LoyaltyPlugin "ru.crystals.loy.plugin.emulator" found for purchase id 35213
  625. 22.08 13:48:28.627 INFO  [FiscalPrinter] printCheck
  626. 22.08 13:48:28.627 INFO  [FiscalPrinter] getLastKpk
  627. 22.08 13:48:28.627 INFO  [FiscalPrinter] LastKpk = 1183
  628. 22.08 13:48:28.627 INFO  [FiscalPrinter] Fiscal interrupt: null
  629. 22.08 13:48:28.627 INFO  [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale.xml
  630. 22.08 13:48:28.628 INFO  [DocumentTemplate] =====logo=====
  631. 22.08 13:48:28.628 INFO  [DocumentTemplate] content: null
  632. 22.08 13:48:28.633 INFO  [DocumentTemplate] =====header=====
  633. 22.08 13:48:28.633 INFO  [DocumentTemplate]                    jr. name                   ||NORMAL
  634. 22.08 13:48:28.633 INFO  [DocumentTemplate]          199100, Spb, Savushkina, 112         ||NORMAL
  635. 22.08 13:48:28.633 INFO  [DocumentTemplate] -----------------------------------------     ||NORMAL
  636. 22.08 13:48:28.633 INFO  [DocumentTemplate] Смена:65                     ЭКЛЗ:c2190c56-ef0||NORMAL
  637. 22.08 13:48:28.633 INFO  [DocumentTemplate] Номер кассы:61                ИНН:  7802781104||NORMAL
  638. 22.08 13:48:28.633 INFO  [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  639. 22.08 13:48:28.633 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  640. 22.08 13:48:28.633 INFO  [DocumentTemplate] ЧЕК ПРОДАЖИ             6    22-08-2019  13:48||NORMAL
  641. 22.08 13:48:28.633 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  642. 22.08 13:48:28.635 INFO  [DocumentTemplate] =====position=====
  643. 22.08 13:48:28.635 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  644. 22.08 13:48:28.635 INFO  [DocumentTemplate] Уксус лимонный пряный  58.33 *50.00   =2916.50||SMALL
  645. 22.08 13:48:28.635 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  646. 22.08 13:48:28.635 INFO  [DocumentTemplate] =====payment=====
  647. 22.08 13:48:28.635 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  648. 22.08 13:48:28.635 INFO  [DocumentTemplate] НАЛИЧНЫЕ                              =3000.00||NORMAL
  649. 22.08 13:48:28.636 INFO  [DocumentTemplate] =====discount=====
  650. 22.08 13:48:28.636 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  651. 22.08 13:48:28.636 INFO  [DocumentTemplate] ВАША СКИДКА СОСТАВИЛА, руб            =2917.00||DOUBLEHEIGHT
  652. 22.08 13:48:28.636 INFO  [DocumentTemplate]                                               ||NORMAL
  653. 22.08 13:48:28.636 INFO  [DocumentTemplate]              СПАСИБО ЗА ПОКУПКУ!              ||NORMAL
  654. 22.08 13:48:28.636 INFO  [DocumentTemplate]                                               ||NORMAL
  655. 22.08 13:48:28.636 INFO  [DocumentTemplate]                     Цены                      ||NORMAL
  656. 22.08 13:48:28.636 INFO  [DocumentTemplate] указаны с учетом скидки по карте постоянного  ||NORMAL
  657. 22.08 13:48:28.636 INFO  [DocumentTemplate]                  покупателя                   ||NORMAL
  658. 22.08 13:48:28.636 INFO  [DocumentTemplate]                 9905100100001                 ||NORMAL
  659. 22.08 13:48:28.636 INFO  [DocumentTemplate]                                               ||NORMAL
  660. 22.08 13:48:28.636 INFO  [DocumentTemplate] =====tax=====
  661. 22.08 13:48:28.640 INFO  [DocumentTemplate] content: null
  662. 22.08 13:48:28.640 INFO  [DocumentTemplate] =====total=====
  663. 22.08 13:48:28.640 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  664. 22.08 13:48:28.640 INFO  [DocumentTemplate] Итого без скидки          5833.50             ||NORMAL
  665. 22.08 13:48:28.640 INFO  [DocumentTemplate] Скидка     2917.00                            ||NORMAL
  666. 22.08 13:48:28.640 INFO  [DocumentTemplate] Итого к оплате  2916.50                       ||NORMAL
  667. 22.08 13:48:28.641 INFO  [DocumentTemplate] СДАЧА                                   =83.50||NORMAL
  668. 22.08 13:48:28.641 INFO  [DocumentTemplate] =====bonuses=====
  669. 22.08 13:48:28.641 INFO  [DocumentTemplate] =====includedSlips=====
  670. 22.08 13:48:28.641 INFO  [DocumentTemplate] content: null
  671. 22.08 13:48:28.641 INFO  [DocumentTemplate] =====fiscal=====
  672. 22.08 13:48:28.641 INFO  [DocumentTemplate] content: null
  673. 22.08 13:48:28.641 INFO  [DocumentTemplate] =====footer=====
  674. 22.08 13:48:28.641 INFO  [DocumentTemplate] Док N:6                     22-08-2019 13:48  ||NORMAL
  675. 22.08 13:48:28.641 INFO  [DocumentTemplate] Спасибо за покупку!                           ||NORMAL
  676. 22.08 13:48:28.641 INFO  [DocumentTemplate] =====cut=====
  677. 22.08 13:48:28.641 INFO  [DocumentTemplate] content: null
  678. 22.08 13:48:28.755 INFO  [FiscalPrinter] getLastKpk
  679. 22.08 13:48:28.755 INFO  [FiscalPrinter] LastKpk = 1184
  680. 22.08 13:48:28.755 INFO  [FiscalPrinter] getLastDocNum
  681. 22.08 13:48:28.764 INFO  [CheckService] fiscalizeCheck: PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED]
  682. 22.08 13:48:28.774 TRACE [ExternalProcessingsImpl] entering purchaseFiscalized(PurchaseEntity)
  683. 22.08 13:48:28.774 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of PlastekServices.
  684. 22.08 13:48:28.774 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of externalLoyaltyService.
  685. 22.08 13:48:28.780 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  686. java.security.InvalidKeyException: Unwrapping failed
  687.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  688.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  689.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  690.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  691.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  692.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  693.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  694.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  695.     at java.lang.Thread.run(Thread.java:745)
  696. Caused by: javax.crypto.BadPaddingException: Decryption error
  697.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  698.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  699.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  700.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  701.     ... 8 more
  702. 22.08 13:48:28.788 TRACE [ExternalProcessingsImpl] about to notify ext-loy-providers that the current receipt [PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED]] was fiscalized...
  703. 22.08 13:48:28.799 WARN  [SetApiPluginLoyProvider] No LoyaltyPlugin "ru.crystals.loy.plugin.emulator" found for purchase id 35213
  704. 22.08 13:48:28.799 TRACE [LoyPluginEmulator] entering eventReceiptFiscalized(Receipt, LoyaltyResult). The arguments are: receipt [ru.crystals.api.commons.ReceiptPurchaseEntityWrapper@236430], loyResults [null]
  705. 22.08 13:48:28.799 TRACE [LoyPluginEmulator] leaving eventReceiptFiscalized(Receipt, LoyaltyResult)
  706. 22.08 13:48:28.799 INFO  [SetApiLoyFeedbackProcessor] Scheduling feedback of provider "ru.crystals.loy.plugin.emulator" for purchase id "35213"
  707. 22.08 13:48:28.801 INFO  [SetApiLoyFeedbackProcessor] Saved feedback for "ru.crystals.loy.plugin.emulator", check id 35213, assigned id 5951
  708. 22.08 13:48:28.802 DEBUG [LoymaxUtils] Loymax request:
  709. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><Balances><BalanceRequest OperationID="b04b2ad53a1f404687102d7b192547b7" OperationDate="2019-08-22T13:48:28.801+03:00" ElementID="1"><Identifier Type="Auto" Value="9905100100001"/></BalanceRequest></Balances></XMLRequest>
  710.  
  711. 22.08 13:48:28.914 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  712. 22.08 13:48:28.917 DEBUG [LoymaxUtils] Loymax response:
  713. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><Balances><BalanceResponse OperationID="b04b2ad53a1f404687102d7b192547b7" ErrorCode="201" ErrorMessage="Не найдена карта"/></Balances></XMLResponse>
  714.  
  715. 22.08 13:48:28.918 DEBUG [LoymaxUtils] Loymax request:
  716. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><ConfirmPurchases><ConfirmPurchaseRequest PurchaseID="71890611566470905401" OperationID="8ccdeb6d57414975828f32720bd8ed24" OperationDate="2019-08-22T13:48:28.801+03:00" ElementID="1"/></ConfirmPurchases></XMLRequest>
  717.  
  718. 22.08 13:48:28.932 DEBUG [LoymaxUtils] Loymax response:
  719. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><ConfirmPurchases><ConfirmPurchaseResponse OperationID="8ccdeb6d57414975828f32720bd8ed24" ErrorCode="0"/></ConfirmPurchases></XMLResponse>
  720.  
  721. 22.08 13:48:28.933 TRACE [LoyaltyRestrictionsServiceImpl] createApplyRestrictionsRequest: no action-results for sending to processing.
  722. 22.08 13:48:28.933 TRACE [SMServiceImpl] entering getPromise(PurchaseEntity). The argument is: PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED]
  723. 22.08 13:48:28.943 ERROR [SMServiceImpl] leaving getPromise(PurchaseEntity): failed to get SM-promises [body: <html><head><title>Grizzly 2.3.28</title><style><!--div.header {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#003300;font-size:22px;-moz-border-radius-topleft: 10px;border-top-left-radius: 10px;-moz-border-radius-topright: 10px;border-top-right-radius: 10px;padding-left: 5px}div.body {font-family:Tahoma,Arial,sans-serif;color:black;background-color:#FFFFCC;font-size:16px;padding-top:10px;padding-bottom:10px;padding-left:10px}div.footer {font-family:Tahoma,Arial,sans-serif;color:white;background-color:#666633;font-size:14px;-moz-border-radius-bottomleft: 10px;border-bottom-left-radius: 10px;-moz-border-radius-bottomright: 10px;border-bottom-right-radius: 10px;padding-left: 5px}BODY {font-family:Tahoma,Arial,sans-serif;color:black;background-color:white;}B {font-family:Tahoma,Arial,sans-serif;color:black;}A {color : black;}HR {color : #999966;}--></style> </head><body><div class="header">Request failed.</div><div class="body">Request failed.</div><div class="footer">Grizzly 2.3.28</div></body></html>]
  724. ru.crystals.setmachine.promo.exception.SMClientException: Failed to commit offers
  725.     at ru.crystals.setmachine.promo.api.v2.SMClient.confirmReceipt(SMClient.java:227)
  726.     at ru.crystals.pos.sm.SMServiceImpl.getPromise(SMServiceImpl.java:191)
  727.     at ru.crystals.pos.loyal.sm.SetMachineLoyProvider.purchaseFiscalized(SetMachineLoyProvider.java:214)
  728.     at ru.crystals.loyal.providers.LoyProvider.purchaseFiscalized(LoyProvider.java:139)
  729.     at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.purchaseFiscalized(LoyProvidersRegistryImpl.java:354)
  730.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.purchaseFiscalized(LoyalServiceImpl.java:1514)
  731.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.purchaseFiscalized(LoyalBridgeServiceImpl.java:189)
  732.     at ru.crystals.pos.techprocess.ExternalProcessingsImpl.purchaseFiscalized(ExternalProcessingsImpl.java:212)
  733.     at ru.crystals.pos.techprocess.TechProcessShift.printCheck(TechProcessShift.java:1791)
  734.     at ru.crystals.pos.techprocess.TechProcessShift.fiscalizeCheck(TechProcessShift.java:1536)
  735.     at ru.crystals.pos.techprocess.TechProcessShiftProxy.fiscalizeCheck(TechProcessShiftProxy.java:700)
  736.     at ru.crystals.pos.techprocess.TechProcessImpl.fiscalizeCheck(TechProcessImpl.java:941)
  737.     at ru.crystals.pos.visualization.check.CheckContainer.paymentComplete(CheckContainer.java:2724)
  738.     at ru.crystals.pos.visualization.components.MainWindow.paymentComplete(MainWindow.java:849)
  739.     at ru.crystals.pos.visualization.Factory.eventPaymentComplete(Factory.java:880)
  740.     at ru.crystals.pos.techprocess.TechProcessImpl.paymentComplete(TechProcessImpl.java:4493)
  741.     at ru.crystals.pos.check.service.CheckServiceImpl.addPayment(CheckServiceImpl.java:3968)
  742.     at ru.crystals.pos.techprocess.TechProcessImpl.addPayment(TechProcessImpl.java:3943)
  743.     at ru.crystals.pos.visualization.payments.PaymentContainer.doProcessPayment(PaymentContainer.java:387)
  744.     at ru.crystals.pos.visualization.payments.PaymentContainer.processPayment(PaymentContainer.java:347)
  745.     at ru.crystals.pos.visualization.payments.cash.CashPaymentContainer.enter(CashPaymentContainer.java:94)
  746.     at ru.crystals.pos.visualization.components.CompositeContainer.enter(CompositeContainer.java:97)
  747.     at ru.crystals.pos.visualization.check.CheckContainer.enter(CheckContainer.java:1916)
  748.     at ru.crystals.pos.visualization.components.MainWindow.enter(MainWindow.java:1090)
  749.     at ru.crystals.pos.visualization.Factory.eventControlKey(Factory.java:536)
  750.     at ru.crystals.pos.techprocess.TechProcessImpl.eventControlKey(TechProcessImpl.java:4371)
  751.     at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:167)
  752.     at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  753.     at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  754.     at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  755.     at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  756.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  757.     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  758.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  759.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  760.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  761.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  762.     at java.lang.Thread.run(Thread.java:745)
  763. 22.08 13:48:28.954 TRACE [ExternalProcessingsImpl] leaving purchaseFiscalized(PurchaseEntity). It took 180 [ms]
  764. 22.08 13:48:28.961 INFO  [FiscalPrinter] printServiceDocument
  765. 22.08 13:48:28.961 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  766. 22.08 13:48:28.975 INFO  [FiscalPrinter] printDiscountsReport
  767. 22.08 13:48:28.975 INFO  [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/discounts-report.xml
  768. 22.08 13:48:28.975 INFO  [DocumentTemplate] =====logo=====
  769. 22.08 13:48:28.976 INFO  [DocumentTemplate] content: null
  770. 22.08 13:48:28.976 INFO  [DocumentTemplate] =====header=====
  771. 22.08 13:48:28.976 INFO  [DocumentTemplate]                                               ||NORMAL
  772. 22.08 13:48:28.976 INFO  [DocumentTemplate]                                               ||NORMAL
  773. 22.08 13:48:28.976 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  774. 22.08 13:48:28.976 INFO  [DocumentTemplate] Смена:65                     ЭКЛЗ:c2190c56-ef0||NORMAL
  775. 22.08 13:48:28.976 INFO  [DocumentTemplate] Номер кассы:61                ИНН:  7802781104||NORMAL
  776. 22.08 13:48:28.976 INFO  [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  777. 22.08 13:48:28.976 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  778. 22.08 13:48:28.976 INFO  [DocumentTemplate] ОТЧЕТ ПО СКИДКАМ        6    22-08-2019  13:48||NORMAL
  779. 22.08 13:48:28.976 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  780. 22.08 13:48:28.976 INFO  [DocumentTemplate] =====groupByGoods=====
  781. 22.08 13:48:28.977 INFO  [DocumentTemplate] content: null
  782. 22.08 13:48:28.977 INFO  [DocumentTemplate] =====groupByAA=====
  783. 22.08 13:48:28.977 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  784. 22.08 13:48:28.978 INFO  [DocumentTemplate] По акциям           ||NORMAL
  785. 22.08 13:48:28.978 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  786. 22.08 13:48:28.978 INFO  [DocumentTemplate] SR-759 процессинг Loymax                      ||SMALL
  787. 22.08 13:48:28.978 INFO  [DocumentTemplate]   Уксус лимонный пряный (Россия*50.00    =0.25||SMALL
  788. 22.08 13:48:28.978 INFO  [DocumentTemplate] SR-1177: Скидка по мобильному телефону        ||SMALL
  789. 22.08 13:48:28.978 INFO  [DocumentTemplate]   Уксус лимонный пряный (Россия*50.00 =2916.75||SMALL
  790. 22.08 13:48:28.978 INFO  [DocumentTemplate] =====checkDiscounts=====
  791. 22.08 13:48:28.978 INFO  [DocumentTemplate] content: null
  792. 22.08 13:48:28.978 INFO  [DocumentTemplate] =====total=====
  793. 22.08 13:48:28.978 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  794. 22.08 13:48:28.979 INFO  [DocumentTemplate] ИТОГО СКИДКА НА ЧЕК                   =2917.00||NORMAL
  795. 22.08 13:48:28.979 INFO  [DocumentTemplate] =====fiscal=====
  796. 22.08 13:48:28.979 INFO  [DocumentTemplate] content: null
  797. 22.08 13:48:28.979 INFO  [DocumentTemplate] =====footer=====
  798. 22.08 13:48:28.979 INFO  [DocumentTemplate] Док N:6                     22-08-2019 13:48  ||NORMAL
  799. 22.08 13:48:28.979 INFO  [DocumentTemplate] Спасибо за покупку!                           ||NORMAL
  800. 22.08 13:48:28.979 INFO  [DocumentTemplate] =====cut=====
  801. 22.08 13:48:28.979 INFO  [DocumentTemplate] content: null
  802. 22.08 13:48:29.019 ERROR [ExternalEncryptedEventPacket] Thread-45   Ошибка расшифровки пакета
  803. java.security.InvalidKeyException: Unwrapping failed
  804.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445)
  805.     at javax.crypto.Cipher.unwrap(Cipher.java:2550)
  806.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57)
  807.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48)
  808.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44)
  809.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168)
  810.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91)
  811.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71)
  812.     at java.lang.Thread.run(Thread.java:745)
  813. Caused by: javax.crypto.BadPaddingException: Decryption error
  814.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380)
  815.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291)
  816.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363)
  817.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440)
  818.     ... 8 more
  819. 22.08 13:48:29.027 TRACE [SCService] entering isEnabled()
  820. 22.08 13:48:29.027 TRACE [SCService] leaving isEnabled(). the result is: true
  821. 22.08 13:48:29.028 TRACE [SCService] Entering commitDiscounts (LoyTransactionEntity, PurchaseEntity). The arguments are loy-tx [shop: 7189, cash: 61, inn: 7802781104, shift: 65, purchase-number: (NULL), discount-value: 291700, discount-positions: [discount-position [position-order: 1, discount-amount: 25, adv-action: adv-action [guid: -1673510823, action-type: DEFAULT, apply-mode: UNCONDITIONAL]], discount-position [position-order: 1, discount-amount: 291675, adv-action: adv-action [guid: -1538204597, action-type: DISCOUNT, apply-mode: AUTOMATIC]]]], PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED]
  822. 22.08 13:48:29.028 TRACE [SCService] Stop send Personal Offer
  823. 22.08 13:48:29.028 TRACE [SCService] Remove Personal offer feedback
  824. 22.08 13:48:29.029 INFO  [SCReceiptConverter] Entering createFiscalCheque(purchaseEntity, loyTransactionEntity). The arguments are PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED] , loy-tx [shop: 7189, cash: 61, inn: 7802781104, shift: 65, purchase-number: (NULL), discount-value: 291700, discount-positions: [discount-position [position-order: 1, discount-amount: 25, adv-action: adv-action [guid: -1673510823, action-type: DEFAULT, apply-mode: UNCONDITIONAL]], discount-position [position-order: 1, discount-amount: 291675, adv-action: adv-action [guid: -1538204597, action-type: DISCOUNT, apply-mode: AUTOMATIC]]]]
  825. 22.08 13:48:29.030 TRACE [SCService] Send request <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  826. <ChequeRequest xmlns="http://smartcheckout.ru/xsd/s10ren.xsd" ChequeType="Fiscal">
  827.     <RequestID>1566470902003</RequestID>
  828.     <Defer>false</Defer>
  829.     <Card>
  830.         <CardNumber>9905100100001</CardNumber>
  831.     </Card>
  832.     <DateTime>2019-08-22T13:48:29.029+03:00</DateTime>
  833.     <POS>61</POS>
  834.     <Number>1566470902003</Number>
  835.     <Summ>5833.50</Summ>
  836.     <Discount>50.004</Discount>
  837.     <SummDiscounted>2916.50</SummDiscounted>
  838.     <Item>
  839.         <PositionNumber>1</PositionNumber>
  840.         <Article>00888</Article>
  841.         <ArticleName>Уксус лимонный пряный (Россия)</ArticleName>
  842.         <Price>116.67</Price>
  843.         <Quantity>50.000</Quantity>
  844.         <Summ>5833.50</Summ>
  845.         <Discount>50.004</Discount>
  846.         <SummDiscounted>2916.50</SummDiscounted>
  847.         <ExtendedAttribute>
  848.             <Key>SR-759 процессинг Loymax</Key>
  849.             <Value>0.004</Value>
  850.         </ExtendedAttribute>
  851.         <ExtendedAttribute>
  852.             <Key>SR-1177: Скидка по мобильному телефону</Key>
  853.             <Value>50.000</Value>
  854.         </ExtendedAttribute>
  855.     </Item>
  856. </ChequeRequest>
  857.  
  858. 22.08 13:48:29.030 TRACE [SCService] entering getServices()
  859. 22.08 13:48:29.030 TRACE [SCService] getting services (IN MEMORY)
  860. 22.08 13:48:29.030 TRACE [SCService] leaving getServices(). The result size is: 2
  861. 22.08 13:48:29.038 TRACE [MLServiceImpl] entering isEnabled()
  862. 22.08 13:48:29.042 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  863. 22.08 13:48:29.042 TRACE [MLServiceImpl] entering getMlCardNo(PurchaseEntity). The argument is: PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED]
  864. 22.08 13:48:29.042 TRACE [MLServiceImpl] leaving getMlCardNo(PurchaseEntity). The result is: null
  865. 22.08 13:48:29.042 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=UNCOMMITED]
  866. 22.08 13:48:29.042 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  867. 22.08 13:48:29.041 TRACE [SCService] Received response: <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  868. <ChequeRequestResponce xmlns="http://smartcheckout.ru/xsd/s10ren.xsd">
  869.     <RequestID>1566470902003</RequestID>
  870.     <Processed>2019-08-22T13:48:29.036+03:00</Processed>
  871.     <ReturnCode>0</ReturnCode>
  872.     <CashierMessageTO>0</CashierMessageTO>
  873.     <Summ>5833.50</Summ>
  874.     <Discount>50.004</Discount>
  875.     <SummDiscounted>2916.50</SummDiscounted>
  876.     <Item>
  877.         <PositionNumber>1</PositionNumber>
  878.         <Article>00888</Article>
  879.         <ArticleName>Уксус лимонный пряный (Россия)</ArticleName>
  880.         <Price>116.67</Price>
  881.         <MinPrice>0</MinPrice>
  882.         <Quantity>50.000</Quantity>
  883.         <Summ>5833.50</Summ>
  884.         <Discount>50.004</Discount>
  885.         <SummDiscounted>2916.50</SummDiscounted>
  886.         <ExtendedAttribute>
  887.             <Key>SR-759 процессинг Loymax</Key>
  888.             <Value>0.004</Value>
  889.         </ExtendedAttribute>
  890.         <ExtendedAttribute>
  891.             <Key>SR-1177: Скидка по мобильному телефону</Key>
  892.             <Value>50.000</Value>
  893.         </ExtendedAttribute>
  894.     </Item>
  895. </ChequeRequestResponce>
  896.  
  897. 22.08 13:48:29.048 INFO  [TechProcessShift] Check = PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=NO_SENT]
  898.  checkNum = 0
  899.  checkModule.getCurrentPurchaseEntry().getCurrentState() = REG_CHECK
  900. 22.08 13:48:29.048 TRACE [ExternalProcessingsImpl] afterFiscalize: no implementation of externalLoyaltyService
  901. 22.08 13:48:29.064 INFO  [SpeedLog] TechProcess fiscalizeCheck operationType = true, positions count = 1  time = 640 ms
  902. 22.08 13:48:31.773 INFO  [DocumentSender] ping = true
  903. 22.08 13:48:33.687 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  904. 22.08 13:48:33.699 INFO  [TechProcessImpl] saveCheck PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=NO_SENT]
  905. 22.08 13:48:33.699 INFO  [CheckService] saveCheck: PurchaseEntity [id=35213, number=6, dateCreate=Thu Aug 22 13:48:22 MSK 2019, dateCommit=Thu Aug 22 13:48:28 MSK 2019, fiscalDocNum=1184;2305, sentToServerStatus=NO_SENT]
  906. 22.08 13:48:33.702 INFO  [TechProcessImpl] Select seller [null]
  907. 22.08 13:48:33.742 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  908. 22.08 13:48:33.742 INFO  [TextCustomerDisplayPluginAbstract] **********************
  909. 22.08 13:48:33.742 INFO  [TextCustomerDisplayPluginAbstract] *Добро               *
  910. 22.08 13:48:33.742 INFO  [TextCustomerDisplayPluginAbstract] *пожаловать          *
  911. 22.08 13:48:33.742 INFO  [TextCustomerDisplayPluginAbstract] **********************
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!