API tools faq
paste
Advertisement
fedorm

fedorm

fedorm
Sep 7th, 2018
220
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
XML 51.73 KB | None | 0 0
raw download clone embed print report
  1. 07.09 14:38:04 INFO  [SpeedLog] TechProcess authenticating user time = 573 ms
  2. 07.09 14:38:04 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  3. 07.09 14:38:04 INFO  [TextCustomerDisplayPluginAbstract] **********************
  4. 07.09 14:38:04 INFO  [TextCustomerDisplayPluginAbstract] *Добро               *
  5. 07.09 14:38:04 INFO  [TextCustomerDisplayPluginAbstract] *пожаловать          *
  6. 07.09 14:38:04 INFO  [TextCustomerDisplayPluginAbstract] **********************
  7. 07.09 14:38:04 INFO  [TextCustomerDisplayPluginAbstract]
  8. 07.09 14:38:04 INFO  [Scale] BarcodeScannerImpl - barcode = 2612341234
  9. 07.09 14:38:04 INFO  [TechProcessImpl] eventBarcodeScanner 2612341234
  10. 07.09 14:38:04 INFO  [LoyalServiceImpl] looking up Loy Providers Registry...
  11. 07.09 14:38:05 TRACE [MLServiceImpl] entering isEnabled()
  12. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  13. 07.09 14:38:05 TRACE [MLServiceImpl] entering isMlCoupon(String). The argument is: "2612341234"
  14. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isMlCoupon(String). The result is: false
  15. 07.09 14:38:05 TRACE [SCService] entering isEnabled()
  16. 07.09 14:38:05 TRACE [SCService] leaving isEnabled(). the result is: true
  17. 07.09 14:38:05 TRACE [SCService] entering isSmChCoupon(String). The argument is: "2612341234"
  18. 07.09 14:38:05 TRACE [SCService] leaving isSmChCoupon(String). The result is: false
  19. 07.09 14:38:05 TRACE [MLServiceImpl] entering isEnabled()
  20. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  21. 07.09 14:38:05 TRACE [SCService] entering isEnabled()
  22. 07.09 14:38:05 TRACE [SCService] leaving isEnabled(). the result is: true
  23. 07.09 14:38:05 INFO  [CheckContainer] Barcode 2612341234 has been received by visualisation
  24. 07.09 14:38:05 INFO  [TechProcessImpl] TP searchProduct 2612341234
  25. 07.09 14:38:05 INFO  [CatalogService] getGoodsByBarCode barcode = 2612341234
  26. 07.09 14:38:05 INFO  [CatalogService] get product by barcode '2612341234' result: null
  27. 07.09 14:38:05 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 2612341234 time = 11 ms
  28. 07.09 14:38:05 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 2612341234 time = 18 ms
  29. 07.09 14:38:05 INFO  [CatalogService] Get product by item '2612341234' result null
  30. 07.09 14:38:05 INFO  [SpeedLog] searchProduct time  time = 32 ms
  31. 07.09 14:38:05 TRACE [MLServiceImpl] entering isEnabled()
  32. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  33. 07.09 14:38:05 TRACE [MLServiceImpl] entering isMlCoupon(String). The argument is: "2612341234"
  34. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isMlCoupon(String). The result is: false
  35. 07.09 14:38:05 TRACE [SCService] entering isEnabled()
  36. 07.09 14:38:05 TRACE [SCService] leaving isEnabled(). the result is: true
  37. 07.09 14:38:05 TRACE [SCService] entering isSmChCoupon(String). The argument is: "2612341234"
  38. 07.09 14:38:05 TRACE [SCService] leaving isSmChCoupon(String). The result is: false
  39. 07.09 14:38:05 TRACE [MLServiceImpl] entering isEnabled()
  40. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  41. 07.09 14:38:05 TRACE [SCService] entering isEnabled()
  42. 07.09 14:38:05 TRACE [SCService] leaving isEnabled(). the result is: true
  43. 07.09 14:38:05 TRACE [MLServiceImpl] entering isEnabled()
  44. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  45. 07.09 14:38:05 TRACE [MLServiceImpl] entering isMlCard(String, boolean). The argument are: "2612341234"
  46. 07.09 14:38:05 TRACE [MLServiceImpl] leaving isMlCard(String, boolean). The result is: true
  47. 07.09 14:38:05 INFO  [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1713,"cash":60,"mode":35,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":70,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":0.0,"cardType":"","cardNumber":"2612341234","discount":0.0,"discountAll":0.0,"dateTime":"07.09.2018 14:38:05.309","offline":false}]}
  48. 07.09 14:38:06 INFO  [Scale] BarcodeScannerImpl - barcode = 8805010000000
  49. 07.09 14:38:06 INFO  [TechProcessImpl] eventBarcodeScanner 8805010000000
  50. 07.09 14:38:06 TRACE [MLServiceImpl] entering isEnabled()
  51. 07.09 14:38:06 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  52. 07.09 14:38:06 TRACE [MLServiceImpl] entering isMlCoupon(String). The argument is: "8805010000000"
  53. 07.09 14:38:06 TRACE [MLServiceImpl] leaving isMlCoupon(String). The result is: false
  54. 07.09 14:38:06 TRACE [SCService] entering isEnabled()
  55. 07.09 14:38:06 TRACE [SCService] leaving isEnabled(). the result is: true
  56. 07.09 14:38:06 TRACE [SCService] entering isSmChCoupon(String). The argument is: "8805010000000"
  57. 07.09 14:38:06 TRACE [SCService] leaving isSmChCoupon(String). The result is: false
  58. 07.09 14:38:06 TRACE [MLServiceImpl] entering isEnabled()
  59. 07.09 14:38:06 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  60. 07.09 14:38:06 TRACE [SCService] entering isEnabled()
  61. 07.09 14:38:06 TRACE [SCService] leaving isEnabled(). the result is: true
  62. 07.09 14:38:06 INFO  [CheckContainer] Barcode 8805010000000 has been received by visualisation
  63. 07.09 14:38:06 INFO  [TechProcessImpl] TP searchProduct 8805010000000
  64. 07.09 14:38:06 INFO  [CatalogService] getGoodsByBarCode barcode = 8805010000000
  65. 07.09 14:38:06 INFO  [CatalogService] get product by barcode '8805010000000' result: ProductPieceEntity[name=Шоколад яблочный сладкий (Ю. Корея), item=05010]
  66. 07.09 14:38:06 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 8805010000000 time = 9 ms
  67. 07.09 14:38:06 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 8805010000000 time = 10 ms
  68. 07.09 14:38:06 INFO  [SpeedLog] getProductRestrictions  time = 7 ms
  69. 07.09 14:38:06 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 8805010000000 time = 27 ms
  70. 07.09 14:38:06 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 8805010000000 time = 27 ms
  71. 07.09 14:38:06 INFO  [SpeedLog] searchProduct time  time = 27 ms
  72. 07.09 14:38:07 INFO  [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1713,"cash":60,"mode":4,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":70,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":0.0,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"07.09.2018 14:38:07.103","offline":false}]}
  73. 07.09 14:38:07 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,353 sec; average speed = 0,353 sec; position test num = 1.0; prooduct item 05010; <353>
  74. 07.09 14:38:07 INFO  [CommonLogger] busy = false
  75. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  76. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] **********************
  77. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] *Шоколад ябл 1 652.53*
  78. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] *Итого         652.53*
  79. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] **********************
  80. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract]
  81. 07.09 14:38:07 INFO  [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1713,"cash":60,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":70,"pos":1,"barcode":"8805010000000","item":"05010","name":"Шоколад яблочный сладкий (Ю. К","price":652.53,"quant":1.0,"sum":652.53,"sumAll":652.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"07.09.2018 14:38:07.112","offline":false}]}
  82. 07.09 14:38:07 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 05010; type: ProductPieceEntity; count: 1000; sum: 65253; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  83. 07.09 14:38:07 INFO  [SpeedLog] TP addPosition time = 34 ms
  84. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  85. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] **********************
  86. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] *Шоколад ябл 1 652.53*
  87. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] *Итого         652.53*
  88. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract] **********************
  89. 07.09 14:38:07 INFO  [TextCustomerDisplayPluginAbstract]
  90. 07.09 14:38:07 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  91. 07.09 14:38:09 INFO  [Scale] BarcodeScannerImpl - barcode = 4903015000002
  92. 07.09 14:38:09 INFO  [TechProcessImpl] eventBarcodeScanner 4903015000002
  93. 07.09 14:38:09 TRACE [MLServiceImpl] entering isEnabled()
  94. 07.09 14:38:09 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  95. 07.09 14:38:09 TRACE [MLServiceImpl] entering isMlCoupon(String). The argument is: "4903015000002"
  96. 07.09 14:38:09 TRACE [MLServiceImpl] leaving isMlCoupon(String). The result is: false
  97. 07.09 14:38:09 TRACE [SCService] entering isEnabled()
  98. 07.09 14:38:09 TRACE [SCService] leaving isEnabled(). the result is: true
  99. 07.09 14:38:09 TRACE [SCService] entering isSmChCoupon(String). The argument is: "4903015000002"
  100. 07.09 14:38:09 TRACE [SCService] leaving isSmChCoupon(String). The result is: false
  101. 07.09 14:38:09 TRACE [MLServiceImpl] entering isEnabled()
  102. 07.09 14:38:09 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  103. 07.09 14:38:09 TRACE [SCService] entering isEnabled()
  104. 07.09 14:38:09 TRACE [SCService] leaving isEnabled(). the result is: true
  105. 07.09 14:38:09 INFO  [CheckContainer] Barcode 4903015000002 has been received by visualisation
  106. 07.09 14:38:09 INFO  [TechProcessImpl] TP searchProduct 4903015000002
  107. 07.09 14:38:09 INFO  [CatalogService] getGoodsByBarCode barcode = 4903015000002
  108. 07.09 14:38:09 INFO  [CatalogService] get product by barcode '4903015000002' result: ProductPieceEntity[name=Пряник яблочный сладкий (Япония), item=03015]
  109. 07.09 14:38:09 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 4903015000002 time = 3 ms
  110. 07.09 14:38:09 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 4903015000002 time = 4 ms
  111. 07.09 14:38:09 INFO  [SpeedLog] getProductRestrictions  time = 1 ms
  112. 07.09 14:38:09 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 4903015000002 time = 5 ms
  113. 07.09 14:38:09 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 4903015000002 time = 5 ms
  114. 07.09 14:38:09 INFO  [SpeedLog] searchProduct time  time = 5 ms
  115. 07.09 14:38:09 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,025 sec; average speed = 0,189 sec; position test num = 2.0; prooduct item 03015; <25>
  116. 07.09 14:38:09 INFO  [CommonLogger] busy = false
  117. 07.09 14:38:09 INFO  [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1713,"cash":60,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":70,"pos":2,"barcode":"4903015000002","item":"03015","name":"Пряник яблочный сладкий (Япони","price":457.53,"quant":1.0,"sum":457.53,"sumAll":1110.06,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"07.09.2018 14:38:09.273","offline":false}]}
  118. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  119. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] **********************
  120. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] *Пряник ябло 1 457.53*
  121. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] *Итого        1110.06*
  122. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] **********************
  123. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract]
  124. 07.09 14:38:09 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 03015; type: ProductPieceEntity; count: 1000; sum: 45753; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  125. 07.09 14:38:09 INFO  [SpeedLog] TP addPosition time = 25 ms
  126. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  127. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] **********************
  128. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] *Пряник ябло 1 457.53*
  129. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] *Итого        1110.06*
  130. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract] **********************
  131. 07.09 14:38:09 INFO  [TextCustomerDisplayPluginAbstract]
  132. 07.09 14:38:09 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  133. 07.09 14:38:09 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  134. 07.09 14:38:09 INFO  [FilesChecker] Catalog goods transport event
  135. 07.09 14:38:09 INFO  [FilesChecker]    goods types for report count: 0
  136. 07.09 14:38:09 INFO  [FilesChecker]    catalog goods files to cash count: 0
  137. 07.09 14:38:09 INFO  [CommonLogger] busy = true
  138. 07.09 14:38:09 TRACE [TechProcessImpl] Start warn divide alco
  139. 07.09 14:38:09 TRACE [TechProcessImpl] End warn devide alco
  140. 07.09 14:38:09 INFO  [TechProcessImpl] goToDiscounts
  141. 07.09 14:38:09 INFO  [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  142. 07.09 14:38:10 INFO  [LoyalProductFinder] Time finding items (2) = 75 ms
  143. 07.09 14:38:10 INFO  [LoyalProductsConverter] Time searching loyal goods for 2 productItems = 75 ms
  144. 07.09 14:38:10 INFO  [LoyalCalculatorConverter] Time converting 2 positions = 19 ms
  145. 07.09 14:38:10 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  146. 07.09 14:38:10 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  147. 07.09 14:38:10 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  148. 07.09 14:38:10 TRACE [SCService] entering isEnabled()
  149. 07.09 14:38:10 TRACE [SCService] leaving isEnabled(). the result is: true
  150. 07.09 14:38:10 TRACE [SCService] entering calcDiscount(Purchase). The argument is: Purchase:[number=null;dateCreate=2018-09-07 14:38:05.286;operationType=true;checkSum=111006;discount=0]
  151. 07.09 14:38:10 TRACE [SCService] Send request <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  152. <ChequeRequest xmlns="http://smartcheckout.ru/xsd/s10ren.xsd" ChequeType="Soft">
  153.     <RequestID>1536320285286</RequestID>
  154.     <Card>
  155.         <CardNumber>2612341234</CardNumber>
  156.     </Card>
  157.     <DateTime>2018-09-07T14:38:10.662+03:00</DateTime>
  158.     <POS>60</POS>
  159.     <Number>1536320285286</Number>
  160.     <Summ>1110.06</Summ>
  161.     <Discount>0.000</Discount>
  162.     <SummDiscounted>1110.06</SummDiscounted>
  163.     <Item>
  164.         <PositionNumber>1</PositionNumber>
  165.         <Article>05010</Article>
  166.         <ArticleName>Шоколад яблочный сладкий (Ю. Корея)</ArticleName>
  167.         <Price>652.53</Price>
  168.         <Quantity>1.000</Quantity>
  169.         <Summ>652.53</Summ>
  170.         <Discount>0.000</Discount>
  171.         <SummDiscounted>652.53</SummDiscounted>
  172.     </Item>
  173.     <Item>
  174.         <PositionNumber>2</PositionNumber>
  175.         <Article>03015</Article>
  176.         <ArticleName>Пряник яблочный сладкий (Япония)</ArticleName>
  177.         <Price>457.53</Price>
  178.         <Quantity>1.000</Quantity>
  179.         <Summ>457.53</Summ>
  180.         <Discount>0.000</Discount>
  181.         <SummDiscounted>457.53</SummDiscounted>
  182.     </Item>
  183. </ChequeRequest>
  184.  
  185. 07.09 14:38:10 TRACE [SCService] entering getServices()
  186. 07.09 14:38:10 INFO  [SCService] About to create stubs to web-services
  187. 07.09 14:38:11 DEBUG [TechProcessImpl] Server online mode
  188. 07.09 14:38:11 INFO  [TransferManager] Nothing yet not processed on server to resend
  189. 07.09 14:38:11 TRACE [SCService] service [url: "http://127.0.0.1:8888/smch/emulator?wsdl"] was created... about to set connetion-timeouts [connect: 500 [ms]; request: 2000 [ms]]
  190. 07.09 14:38:11 TRACE [SCService] connection timeouts were set
  191. 07.09 14:38:11 TRACE [SCService] service [url: "http://127.0.0.1:8888/smch/emulator?wsdl"] was created... about to set connetion-timeouts [connect: 500 [ms]; request: 2000 [ms]]
  192. 07.09 14:38:11 TRACE [SCService] connection timeouts were set
  193. 07.09 14:38:11 TRACE [SCService] leaving getServices(). The result size is: 2
  194. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] Resend stale feedback
  195. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] looking up LoyFeedbackDao...
  196. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = ml
  197. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for ml
  198. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = set-loy-provider
  199. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for set-loy-provider
  200. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = smch
  201. 07.09 14:38:12 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for smch
  202. 07.09 14:38:12 TRACE [SCService] Received response: <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  203. <ChequeRequestResponce xmlns="http://smartcheckout.ru/xsd/s10ren.xsd">
  204.     <RequestID>1536320285286</RequestID>
  205.     <Processed>2018-09-07T14:38:12.025+03:00</Processed>
  206.     <ReturnCode>0</ReturnCode>
  207.     <CashierMessageTO>0</CashierMessageTO>
  208.     <Summ>1110.06</Summ>
  209.     <Discount>0.000</Discount>
  210.     <SummDiscounted>1110.06</SummDiscounted>
  211.     <Item>
  212.         <PositionNumber>1</PositionNumber>
  213.         <Article>05010</Article>
  214.         <ArticleName>Шоколад яблочный сладкий (Ю. Корея)</ArticleName>
  215.         <Price>652.53</Price>
  216.         <MinPrice>0</MinPrice>
  217.         <Quantity>1.000</Quantity>
  218.         <Summ>652.53</Summ>
  219.         <Discount>0.000</Discount>
  220.         <SummDiscounted>652.53</SummDiscounted>
  221.     </Item>
  222.     <Item>
  223.         <PositionNumber>2</PositionNumber>
  224.         <Article>03015</Article>
  225.         <ArticleName>Пряник яблочный сладкий (Япония)</ArticleName>
  226.         <Price>457.53</Price>
  227.         <MinPrice>0</MinPrice>
  228.         <Quantity>1.000</Quantity>
  229.         <Summ>457.53</Summ>
  230.         <Discount>0.000</Discount>
  231.         <SummDiscounted>457.53</SummDiscounted>
  232.     </Item>
  233. </ChequeRequestResponce>
  234.  
  235. 07.09 14:38:12 TRACE [SCReceiptConverter] ml discounts: {position-key [marking: 03015; original-price: 45753]=0, position-key [marking: 05010; original-price: 65253]=0}
  236. 07.09 14:38:12 TRACE [SCReceiptConverter] it was inferred that no SC POS discounts were given on position [key: position-key [marking: 03015; original-price: 45753]]: SC discount value is: 0
  237. 07.09 14:38:12 TRACE [SCReceiptConverter] it was inferred that no SC POS discounts were given on position [key: position-key [marking: 05010; original-price: 65253]]: SC discount value is: 0
  238. 07.09 14:38:12 TRACE [SCService] leaving calcDiscount(Purchase). The result is: Purchase:[number=null;dateCreate=(NULL);operationType=null;checkSum=111006;discount=0]; it took 1411 [ms]
  239. 07.09 14:38:12 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=null;dateCreate=2018-09-07 14:38:05.286;operationType=true;checkSum=111006;discount=0]
  240. 07.09 14:38:12 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: 2612341234
  241. 07.09 14:38:12 TRACE [MLServiceImpl] entering isEnabled()
  242. 07.09 14:38:12 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  243. 07.09 14:38:12 TRACE [MLServiceImpl] entering calcDiscount(Purchase). The argument is: Purchase:[number=null;dateCreate=2018-09-07 14:38:05.286;operationType=true;checkSum=111006;discount=0]
  244. 07.09 14:38:12 TRACE [MLServiceImpl] entering getFakeCardNo()
  245. 07.09 14:38:12 TRACE [MLServiceImpl] leaving getFakeCardNo(). The result is: "2612341234"
  246. 07.09 14:38:12 TRACE [MLServiceImpl] Send request <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  247. <ProcessRequest xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  248.     <request>
  249.         <ChequeRequest ChequeType="Soft">
  250.             <RequestID>1713_60_66_70</RequestID>
  251.             <Card>
  252.                 <CardNumber>2612341234</CardNumber>
  253.             </Card>
  254.             <DateTime>2018-09-07T14:38:12.091+03:00</DateTime>
  255.             <Organization>Victoria</Organization>
  256.             <BusinessUnit>1713</BusinessUnit>
  257.             <POS>60</POS>
  258.             <Number>66_70</Number>
  259.             <OperationType>Sale</OperationType>
  260.             <Summ>1110.06</Summ>
  261.             <Discount>0.000</Discount>
  262.             <SummDiscounted>1110.06</SummDiscounted>
  263.             <Item>
  264.                 <PositionNumber>1</PositionNumber>
  265.                 <Article>05010</Article>
  266.                 <ArticleName>Шоколад яблочный сладкий (Ю. Корея)</ArticleName>
  267.                 <Price>652.53</Price>
  268.                 <Quantity>1.000</Quantity>
  269.                 <Summ>652.53</Summ>
  270.                 <Discount>0.000</Discount>
  271.                 <SummDiscounted>652.53</SummDiscounted>
  272.             </Item>
  273.             <Item>
  274.                 <PositionNumber>2</PositionNumber>
  275.                 <Article>03015</Article>
  276.                 <ArticleName>Пряник яблочный сладкий (Япония)</ArticleName>
  277.                 <Price>457.53</Price>
  278.                 <Quantity>1.000</Quantity>
  279.                 <Summ>457.53</Summ>
  280.                 <Discount>0.000</Discount>
  281.                 <SummDiscounted>457.53</SummDiscounted>
  282.             </Item>
  283.         </ChequeRequest>
  284.     </request>
  285. </ProcessRequest>
  286.  
  287. 07.09 14:38:12 TRACE [MLServiceImpl] entering getServices()
  288. 07.09 14:38:12 INFO  [MLServiceImpl] About to create stubs to web-services
  289. 07.09 14:38:13 TRACE [MLServiceImpl] service [url: "http://172.29.16.173:60324/sap/manzana?wsdl"] was created... about to set connetion-timeouts [connect: 10000 [ms]; request: 5000 [ms]]
  290. 07.09 14:38:13 TRACE [MLServiceImpl] connection timeouts were set
  291. 07.09 14:38:13 TRACE [MLServiceImpl] leaving getServices(). The result size is: 1
  292. 07.09 14:38:13 TRACE [MLServiceImpl] class ru.crystals.pos.ml.wsclient.ProcessRequestResponse is <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  293. <ProcessRequestResponse xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  294.     <ProcessRequestResult>
  295.         <ChequeResponse>
  296.             <ReturnCode>0</ReturnCode>
  297.             <Message>OK</Message>
  298.             <Summ>1110.06</Summ>
  299.             <Discount>12.612</Discount>
  300.             <SummDiscounted>970.0600</SummDiscounted>
  301.             <Item>
  302.                 <PositionNumber>1</PositionNumber>
  303.                 <Article>05010</Article>
  304.                 <ArticleName>Шоколад яблочный сладкий (Ю. Корея)</ArticleName>
  305.                 <Price>652.53</Price>
  306.                 <Quantity>1.000</Quantity>
  307.                 <Summ>652.53</Summ>
  308.                 <Discount>9.19</Discount>
  309.                 <SummDiscounted>592.5300</SummDiscounted>
  310.                 <ExtendedAttribute>
  311.                     <Key>First_ml_ACTION</Key>
  312.                     <Value>20</Value>
  313.                 </ExtendedAttribute>
  314.             </Item>
  315.             <Item>
  316.                 <PositionNumber>2</PositionNumber>
  317.                 <Article>03015</Article>
  318.                 <ArticleName>Пряник яблочный сладкий (Япония)</ArticleName>
  319.                 <Price>457.53</Price>
  320.                 <Quantity>1.000</Quantity>
  321.                 <Summ>457.53</Summ>
  322.                 <Discount>17.49</Discount>
  323.                 <SummDiscounted>377.5300</SummDiscounted>
  324.                 <ExtendedAttribute>
  325.                     <Key>First_ml_ACTION</Key>
  326.                     <Value>20</Value>
  327.                 </ExtendedAttribute>
  328.             </Item>
  329.         </ChequeResponse>
  330.     </ProcessRequestResult>
  331. </ProcessRequestResponse>
  332.  
  333. 07.09 14:38:13 TRACE [MLServiceImpl] entering getFakeCardNo()
  334. 07.09 14:38:13 TRACE [MLServiceImpl] leaving getFakeCardNo(). The result is: "2612341234"
  335. 07.09 14:38:13 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=null;dateCreate=2018-09-07 14:38:05.286;operationType=true;checkSum=111006;discount=0]
  336. 07.09 14:38:13 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: 2612341234
  337. 07.09 14:38:13 TRACE [MLReceiptConvertor] applying stategy: ml-stategy-add
  338. 07.09 14:38:13 TRACE [BaseMLDiscountsApplier] entering applyMlDiscounts(ChequeResponseBase, Purchase, long, String, Purchase). The arguments are: response [<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  339. <ns2:cheque-reponse xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd" xmlns:ns2="ru.crystals.pos.ml.wsclient">
  340.     <ReturnCode>0</ReturnCode>
  341.     <Message>OK</Message>
  342.     <Summ>1110.06</Summ>
  343.     <Discount>12.612</Discount>
  344.     <SummDiscounted>970.0600</SummDiscounted>
  345.     <Item>
  346.         <PositionNumber>1</PositionNumber>
  347.         <Article>05010</Article>
  348.         <ArticleName>Шоколад яблочный сладкий (Ю. Корея)</ArticleName>
  349.         <Price>652.53</Price>
  350.         <Quantity>1.000</Quantity>
  351.         <Summ>652.53</Summ>
  352.         <Discount>9.19</Discount>
  353.         <SummDiscounted>592.5300</SummDiscounted>
  354.         <ExtendedAttribute>
  355.             <Key>First_ml_ACTION</Key>
  356.             <Value>20</Value>
  357.         </ExtendedAttribute>
  358.     </Item>
  359.     <Item>
  360.         <PositionNumber>2</PositionNumber>
  361.         <Article>03015</Article>
  362.         <ArticleName>Пряник яблочный сладкий (Япония)</ArticleName>
  363.         <Price>457.53</Price>
  364.         <Quantity>1.000</Quantity>
  365.         <Summ>457.53</Summ>
  366.         <Discount>17.49</Discount>
  367.         <SummDiscounted>377.5300</SummDiscounted>
  368.         <ExtendedAttribute>
  369.             <Key>First_ml_ACTION</Key>
  370.             <Value>20</Value>
  371.         </ExtendedAttribute>
  372.     </Item>
  373. </ns2:cheque-reponse>
  374. ], receipt [Purchase:[number=null;dateCreate=(NULL);operationType=null;checkSum=111006;discount=0]], actionGuid [0], cardNo [2612341234], originalReceipt [Purchase:[number=null;dateCreate=2018-09-07 14:38:05.286;operationType=true;checkSum=111006;discount=0]]
  375. 07.09 14:38:13 TRACE [StrategyAddMLDiscountsApplier] entering getMlDiscounts(ChequeResponseBase, Purchase). The arguments are: response [<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  376. <ns2:cheque-reponse xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd" xmlns:ns2="ru.crystals.pos.ml.wsclient">
  377.     <ReturnCode>0</ReturnCode>
  378.     <Message>OK</Message>
  379.     <Summ>1110.06</Summ>
  380.     <Discount>12.612</Discount>
  381.     <SummDiscounted>970.0600</SummDiscounted>
  382.     <Item>
  383.         <PositionNumber>1</PositionNumber>
  384.         <Article>05010</Article>
  385.         <ArticleName>Шоколад яблочный сладкий (Ю. Корея)</ArticleName>
  386.         <Price>652.53</Price>
  387.         <Quantity>1.000</Quantity>
  388.         <Summ>652.53</Summ>
  389.         <Discount>9.19</Discount>
  390.         <SummDiscounted>592.5300</SummDiscounted>
  391.         <ExtendedAttribute>
  392.             <Key>First_ml_ACTION</Key>
  393.             <Value>20</Value>
  394.         </ExtendedAttribute>
  395.     </Item>
  396.     <Item>
  397.         <PositionNumber>2</PositionNumber>
  398.         <Article>03015</Article>
  399.         <ArticleName>Пряник яблочный сладкий (Япония)</ArticleName>
  400.         <Price>457.53</Price>
  401.         <Quantity>1.000</Quantity>
  402.         <Summ>457.53</Summ>
  403.         <Discount>17.49</Discount>
  404.         <SummDiscounted>377.5300</SummDiscounted>
  405.         <ExtendedAttribute>
  406.             <Key>First_ml_ACTION</Key>
  407.             <Value>20</Value>
  408.         </ExtendedAttribute>
  409.     </Item>
  410. </ns2:cheque-reponse>
  411. ], originalReceipt [Purchase:[number=null;dateCreate=2018-09-07 14:38:05.286;operationType=true;checkSum=111006;discount=0]]
  412. 07.09 14:38:13 TRACE [StrategyAddMLDiscountsApplier] leaving getMlDiscounts(ChequeResponseBase, Purchase). The result is: {position-key [marking: 03015; original-price: 45753]=8000, position-key [marking: 05010; original-price: 65253]=6000}
  413. 07.09 14:38:13 TRACE [StrategyAddMLDiscountsApplier] reduceAlienDiscounts: the strategy forbids to do that
  414. 07.09 14:38:13 TRACE [BaseMLDiscountsApplier] leaving applyMlDiscounts(ChequeResponseBase, Purchase, long, String, Purchase)
  415. 07.09 14:38:13 INFO  [MLServiceImpl] looking up LoyFeedbackDao...
  416. 07.09 14:38:13 ERROR [TransactionHandler]
  417. java.lang.reflect.InvocationTargetException
  418.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  419.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  420.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  421.     at java.lang.reflect.Method.invoke(Method.java:498)
  422.     at ru.crystals.pos.datasource.jdbc.TransactionHandler.invoke(TransactionHandler.java:26)
  423.     at com.sun.proxy.$Proxy203.saveOrUpdate(Unknown Source)
  424.     at ru.crystals.pos.ml.MLServiceImpl.calcDiscount(MLServiceImpl.java:977)
  425.     at ru.crystals.pos.loyal.ml.ManzanaLoyProvider.process(ManzanaLoyProvider.java:68)
  426.     at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:123)
  427.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:748)
  428.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:667)
  429.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:766)
  430.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:355)
  431.     at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3542)
  432.     at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3108)
  433.     at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1477)
  434.     at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1448)
  435.     at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1345)
  436.     at ru.crystals.pos.visualization.components.MainWindow.subTotal(MainWindow.java:833)
  437.     at ru.crystals.pos.visualization.Factory.eventFunctionKey(Factory.java:538)
  438.     at ru.crystals.pos.techprocess.TechProcessImpl.eventFunctionKey(TechProcessImpl.java:4155)
  439.     at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:165)
  440.     at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  441.     at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  442.     at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  443.     at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  444.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  445.     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  446.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  447.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  448.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  449.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  450.     at java.lang.Thread.run(Thread.java:745)
  451. Caused by: java.lang.RuntimeException: org.postgresql.util.PSQLException: ERROR: null value in column "doc_number" violates not-null constraint
  452.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.executeUpdate(JDBCMapperImpl.java:731)
  453.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.insert(JDBCMapperImpl.java:557)
  454.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.replaceBySQL(JDBCMapperImpl.java:653)
  455.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.replace(JDBCMapperImpl.java:687)
  456.     at ru.crystals.pos.loyal.cash.persistence.JdbcBackedLoyFeedbackDao.saveOrUpdate(JdbcBackedLoyFeedbackDao.java:105)
  457.     ... 33 more
  458. Caused by: org.postgresql.util.PSQLException: ERROR: null value in column "doc_number" violates not-null constraint
  459.     at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2433)
  460.     at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2178)
  461.     at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:306)
  462.     at org.postgresql.jdbc.PgStatement.executeInternal(PgStatement.java:441)
  463.     at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:365)
  464.     at org.postgresql.jdbc.PgPreparedStatement.executeWithFlags(PgPreparedStatement.java:155)
  465.     at org.postgresql.jdbc.PgPreparedStatement.executeUpdate(PgPreparedStatement.java:132)
  466.     at com.zaxxer.hikari.pool.ProxyPreparedStatement.executeUpdate(ProxyPreparedStatement.java:61)
  467.     at com.zaxxer.hikari.pool.HikariProxyPreparedStatement.executeUpdate(HikariProxyPreparedStatement.java)
  468.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.executeUpdate(JDBCMapperImpl.java:719)
  469.     ... 37 more
  470. 07.09 14:38:13 ERROR [LoyalServiceImpl] Error discount calculation for purchase PurchaseEntity [id=10740, number=null, dateCreate=Fri Sep 07 14:38:05 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  471. java.lang.reflect.UndeclaredThrowableException
  472.     at com.sun.proxy.$Proxy203.saveOrUpdate(Unknown Source)
  473.     at ru.crystals.pos.ml.MLServiceImpl.calcDiscount(MLServiceImpl.java:977)
  474.     at ru.crystals.pos.loyal.ml.ManzanaLoyProvider.process(ManzanaLoyProvider.java:68)
  475.     at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:123)
  476.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:748)
  477.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:667)
  478.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:766)
  479.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:355)
  480.     at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3542)
  481.     at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3108)
  482.     at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1477)
  483.     at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1448)
  484.     at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1345)
  485.     at ru.crystals.pos.visualization.components.MainWindow.subTotal(MainWindow.java:833)
  486.     at ru.crystals.pos.visualization.Factory.eventFunctionKey(Factory.java:538)
  487.     at ru.crystals.pos.techprocess.TechProcessImpl.eventFunctionKey(TechProcessImpl.java:4155)
  488.     at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:165)
  489.     at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  490.     at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  491.     at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  492.     at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  493.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  494.     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  495.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  496.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  497.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  498.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  499.     at java.lang.Thread.run(Thread.java:745)
  500. Caused by: java.lang.reflect.InvocationTargetException
  501.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  502.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  503.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  504.     at java.lang.reflect.Method.invoke(Method.java:498)
  505.     at ru.crystals.pos.datasource.jdbc.TransactionHandler.invoke(TransactionHandler.java:26)
  506.     ... 28 more
  507. Caused by: java.lang.RuntimeException: org.postgresql.util.PSQLException: ERROR: null value in column "doc_number" violates not-null constraint
  508.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.executeUpdate(JDBCMapperImpl.java:731)
  509.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.insert(JDBCMapperImpl.java:557)
  510.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.replaceBySQL(JDBCMapperImpl.java:653)
  511.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.replace(JDBCMapperImpl.java:687)
  512.     at ru.crystals.pos.loyal.cash.persistence.JdbcBackedLoyFeedbackDao.saveOrUpdate(JdbcBackedLoyFeedbackDao.java:105)
  513.     ... 33 more
  514. Caused by: org.postgresql.util.PSQLException: ERROR: null value in column "doc_number" violates not-null constraint
  515.     at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2433)
  516.     at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2178)
  517.     at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:306)
  518.     at org.postgresql.jdbc.PgStatement.executeInternal(PgStatement.java:441)
  519.     at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:365)
  520.     at org.postgresql.jdbc.PgPreparedStatement.executeWithFlags(PgPreparedStatement.java:155)
  521.     at org.postgresql.jdbc.PgPreparedStatement.executeUpdate(PgPreparedStatement.java:132)
  522.     at com.zaxxer.hikari.pool.ProxyPreparedStatement.executeUpdate(ProxyPreparedStatement.java:61)
  523.     at com.zaxxer.hikari.pool.HikariProxyPreparedStatement.executeUpdate(HikariProxyPreparedStatement.java)
  524.     at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.executeUpdate(JDBCMapperImpl.java:719)
  525.     ... 37 more
  526. 07.09 14:38:13 INFO  [LoyalBridgeServiceImpl] Total time of discount calculation = 3670 ms
  527. 07.09 14:38:13 INFO  [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  528. 07.09 14:38:13 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  529. 07.09 14:38:13 INFO  [TextCustomerDisplayPluginAbstract] **********************
  530. 07.09 14:38:13 INFO  [TextCustomerDisplayPluginAbstract] *Скидка          0.00*
  531. 07.09 14:38:13 INFO  [TextCustomerDisplayPluginAbstract] *Итого        1110.06*
  532. 07.09 14:38:13 INFO  [TextCustomerDisplayPluginAbstract] **********************
  533. 07.09 14:38:13 INFO  [TextCustomerDisplayPluginAbstract]
  534. 07.09 14:38:13 INFO  [SpeedLog] TechProcess discounts processing on cheque with 2 positions [sale] time = 3734 ms
  535. 07.09 14:38:13 INFO  [CommonLogger] value = 0
  536. 07.09 14:38:13 WARN  [HibernateBackedLoyTxDao] leaving getLoyTxByReceipt(PurchaseEntity): at least one of the mandaroty fields (either doc-num: null, or operation-type: true, or shop-num: null, or shift-num: null, or cash-num: null) of the receipt [PurchaseEntity [id=10740, number=null, dateCreate=Fri Sep 07 14:38:05 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]] is NULL! So, NULL will be returned!
  537. 07.09 14:38:13 TRACE [TechProcessImpl] Start warn divide amount
  538. 07.09 14:38:13 INFO  [TechProcessImpl] goToPayments
  539. 07.09 14:38:13 INFO  [CheckContainer] startPayments() surcharge = 111006
  540. 07.09 14:38:13 INFO  [CheckContainer] isPaymentComplete = false
  541. 07.09 14:38:13 INFO  [CheckContainer] prevState = ADD_POSITION
  542. 07.09 14:38:13 INFO  [CheckContainer] paid = 0
  543. 07.09 14:38:13 INFO  [CheckContainer] surcharge = 111006
  544. 07.09 14:38:13 INFO  [CheckContainer] getPaymentFiscalizeError = NONE
  545. 07.09 14:38:13 INFO  [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  546. 07.09 14:38:13 INFO  [CommonLogger] busy = false
  547. 07.09 14:38:13 INFO  [SpeedLog] (SUBTOTAL) time = 4,041;
  548. 07.09 14:38:13 INFO  [CommonLogger] busy = false
  549. 07.09 14:38:14 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  550. 07.09 14:38:14 INFO  [TextCustomerDisplayPluginAbstract] **********************
  551. 07.09 14:38:14 INFO  [TextCustomerDisplayPluginAbstract] *Скидка          0.00*
  552. 07.09 14:38:14 INFO  [TextCustomerDisplayPluginAbstract] *Итого        1110.06*
  553. 07.09 14:38:14 INFO  [TextCustomerDisplayPluginAbstract] **********************
  554. 07.09 14:38:14 INFO  [TextCustomerDisplayPluginAbstract]
  555. 07.09 14:38:14 INFO  [FiscalPrinter] resetException()
  556. 07.09 14:38:19 INFO  [FilesChecker] Catalog goods transport event
  557. 07.09 14:38:19 INFO  [FilesChecker]    goods types for report count: 0
  558. 07.09 14:38:19 INFO  [FilesChecker]    catalog goods files to cash count: 0
  559. 07.09 14:38:19 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  560. 07.09 14:38:20 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  561. 07.09 14:38:20 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  562. 07.09 14:38:21 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  563. 07.09 14:38:21 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  564. 07.09 14:38:21 INFO  [CommonLogger] Menu command: Аннулирование (class ru.crystals.pos.visualization.menu.commands.CancelCheckCommand)
  565. 07.09 14:38:21 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=37, command=kbdLeft]
  566. 07.09 14:38:21 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  567. 07.09 14:38:21 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - false
  568. 07.09 14:38:21 INFO  [TechProcessShiftProxy] cancelCheck start
  569. 07.09 14:38:21 TRACE [ExternalProcessingsImpl] seems that this cash is not aware of existence of CFT-backed loyality programms. So, there is no need to cancel CFT-backed bonuses charge-offs
  570. 07.09 14:38:21 TRACE [ExternalProcessingsImpl] seems that this cash is not aware of existence of CFT-backed loyality programms. So, there is no need to cancel CFT-backed bonuses charge-offs
  571. 07.09 14:38:21 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  572. 07.09 14:38:21 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  573. 07.09 14:38:21 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  574. 07.09 14:38:21 INFO  [TechProcessShiftProxy] cancelCheck PurchaseEntity [id=10740, number=null, dateCreate=Fri Sep 07 14:38:05 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  575. 07.09 14:38:21 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  576. 07.09 14:38:21 INFO  [TextCustomerDisplayPluginAbstract] **********************
  577. 07.09 14:38:21 INFO  [TextCustomerDisplayPluginAbstract] *Аннулирование чека  *
  578. 07.09 14:38:21 INFO  [TextCustomerDisplayPluginAbstract] *Сумма чека   1110.06*
  579. 07.09 14:38:21 INFO  [TextCustomerDisplayPluginAbstract] **********************
  580. 07.09 14:38:21 INFO  [TextCustomerDisplayPluginAbstract]
  581. 07.09 14:38:21 TRACE [ExternalProcessingsImpl] Purchase cancelled: no implementation of PlastekServices.
  582. 07.09 14:38:21 TRACE [MLServiceImpl] entering getMlCardNo(PurchaseEntity). The argument is: PurchaseEntity [id=10740, number=70, dateCreate=Fri Sep 07 14:38:05 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  583. 07.09 14:38:21 TRACE [MLServiceImpl] leaving getMlCardNo(PurchaseEntity). The result is: null
  584. 07.09 14:38:21 INFO  [FiscalPrinter] printCheck
  585. 07.09 14:38:21 INFO  [FiscalPrinter] Fiscal interrupt: null
  586. 07.09 14:38:21 INFO  [FiscalPrinter] Load document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale-annul.xml
  587. 07.09 14:38:22 INFO  [DocumentTemplate] generate(File xmlTemplate) method gets invoked...
  588. 07.09 14:38:22 INFO  [DocumentTemplate] Length of a xmlTemplate object array is: 3307
  589. 07.09 14:38:22 INFO  [DocumentTemplate] Creating JAXB context...
  590. 07.09 14:38:22 INFO  [DocumentTemplate] Creating unmarshaller...
  591. 07.09 14:38:22 INFO  [DocumentTemplate] Trying to read data from the array...
  592. 07.09 14:38:22 INFO  [DocumentTemplate] Unmarshalling was complete successfully!
  593. 07.09 14:38:22 INFO  [DocumentTemplate] =====logo=====
  594. 07.09 14:38:22 INFO  [DocumentTemplate] content: null
  595. 07.09 14:38:22 INFO  [DocumentTemplate] =====header=====
  596. 07.09 14:38:22 INFO  [DocumentTemplate]                    jr. name                   ||NORMAL
  597. 07.09 14:38:22 INFO  [DocumentTemplate]          199100, Spb, Savushkina, 112         ||NORMAL
  598. 07.09 14:38:22 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  599. 07.09 14:38:22 INFO  [DocumentTemplate] Смена:66                     ЭКЛЗ:eee70e42-099||NORMAL
  600. 07.09 14:38:22 INFO  [DocumentTemplate] Номер кассы:60                ИНН:  7802781104||NORMAL
  601. 07.09 14:38:22 INFO  [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  602. 07.09 14:38:22 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  603. 07.09 14:38:22 INFO  [DocumentTemplate] КАССОВЫЙ ЧЕК            70   07-09-2018  14:38||NORMAL
  604. 07.09 14:38:22 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  605. 07.09 14:38:22 INFO  [DocumentTemplate] =====position=====
  606. 07.09 14:38:22 INFO  [DocumentTemplate] Шоколад яблочный сладки  652.53*     1 =652.53||SMALL
  607. 07.09 14:38:22 INFO  [DocumentTemplate] Пряник яблочный сладкий  457.53*     1 =457.53||SMALL
  608. 07.09 14:38:22 INFO  [DocumentTemplate] =====fiscal=====
  609. 07.09 14:38:22 INFO  [DocumentTemplate] content: null
  610. 07.09 14:38:22 INFO  [DocumentTemplate] =====footer=====
  611. 07.09 14:38:22 INFO  [DocumentTemplate]  ******************************************** ||NORMAL
  612. 07.09 14:38:22 INFO  [DocumentTemplate] ЧЕК No. 70 АННУЛИРОВАН||DOUBLEHEIGHT
  613. 07.09 14:38:22 INFO  [DocumentTemplate]  ******************************************** ||NORMAL
  614. 07.09 14:38:22 INFO  [DocumentTemplate] =====cut=====
  615. 07.09 14:38:22 INFO  [DocumentTemplate] content: null
  616. 07.09 14:38:22 INFO  [FiscalPrinter] getCountAnnul
  617. 07.09 14:38:22 INFO  [FiscalPrinter] CountAnnul = 18
  618. 07.09 14:38:22 INFO  [FiscalPrinter] getLastDocNum
  619. 07.09 14:38:23 INFO  [CheckServiceImpl] cancelCheck: PurchaseEntity [id=10740, number=70, dateCreate=Fri Sep 07 14:38:05 MSK 2018, dateCommit=Fri Sep 07 14:38:22 MSK 2018, fiscalDocNum=18;2083, sentToServerStatus=NO_SENT]
  620. 07.09 14:38:23 TRACE [ExternalProcessingsImpl] seems that this cash is not aware of existence of CFT-backed loyality programms. So, there is no need to cancel CFT-backed bonuses charge-offs
  621. 07.09 14:38:23 TRACE [ExternalProcessingsImpl] seems that this cash is not aware of existence of CFT-backed loyality programms. So, there is no need to cancel CFT-backed bonuses charge-offs
  622. 07.09 14:38:23 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  623. 07.09 14:38:23 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  624. 07.09 14:38:23 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  625. 07.09 14:38:23 TRACE [SCService] entering isEnabled()
  626. 07.09 14:38:23 TRACE [SCService] leaving isEnabled(). the result is: true
  627. 07.09 14:38:23 TRACE [SCService] Check was canceled
  628. 07.09 14:38:23 TRACE [SCService] Stop send Personal Offer
  629. 07.09 14:38:23 INFO  [CommonLogger] value = 0
  630. 07.09 14:38:23 INFO  [TechProcessImpl] Select seller [null]
  631. 07.09 14:38:23 INFO  [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1713,"cash":60,"mode":25,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":70,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1110.06,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"07.09.2018 14:38:23.344","offline":false}]}
  632. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  633. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] **********************
  634. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] *Добро               *
  635. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] *пожаловать          *
  636. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] **********************
  637. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract]
  638. 07.09 14:38:23 INFO  [SpeedLog] TechProcess cancelCheck time = 1481 ms
  639. 07.09 14:38:23 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - true
  640. 07.09 14:38:23 INFO  [TechProcessImpl] Select seller [null]
  641. 07.09 14:38:23 INFO  [TransferManager] Nothing yet not processed on server to resend
  642. 07.09 14:38:23 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  643. 07.09 14:38:23 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  644. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  645. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] **********************
  646. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] *Добро               *
  647. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] *пожаловать          *
  648. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract] **********************
  649. 07.09 14:38:23 INFO  [TextCustomerDisplayPluginAbstract]
  650. 07.09 14:38:25 INFO  [TransferManager] OD found 1 documents
  651. 07.09 14:38:25 INFO  [TransferManager] response message = Created
  652. 07.09 14:38:25 INFO  [TransferManager] Send document status : 10740 > WAIT_ACKNOWLEDGEMENT            file name : 60/Document_20180907143825_385_1713_60.ser
  653. 07.09 14:38:25 INFO  [CheckServiceImpl] SetSentToServerStatus : 10740 > WAIT_ACKNOWLEDGEMENT file name : 60/Document_20180907143825_385_1713_60.ser
  654. 07.09 14:38:25 INFO  [TransferManager] document 60/Document_20180907143825_385_1713_60.ser has been registreated
  655. 07.09 14:38:25 INFO  [TransferManager] Send document status : 10740 > SENT            file name : 60/Document_20180907143825_385_1713_60.ser
  656. 07.09 14:38:25 INFO  [CheckServiceImpl] SetSentToServerStatus : 10740 > SENT file name : 60/Document_20180907143825_385_1713_60.ser
  657. 07.09 14:38:25 INFO  [TransferManager] OD found 0 payment transactions
  658. 07.09 14:38:25 INFO  [TransferManager] OD found 0 transactions to register
  659. 07.09 14:38:25 INFO  [TransferManager] OD found 0 documents to register
  660. 07.09 14:38:29 INFO  [FilesChecker] Catalog goods transport event
  661. 07.09 14:38:29 INFO  [FilesChecker]    goods types for report count: 0
  662. 07.09 14:38:29 INFO  [FilesChecker]    catalog goods files to cash count: 0
  663. 07.09 14:38:35 INFO  [TransferManager] Nothing yet not processed on server to resend
  664. 07.09 14:38:39 INFO  [FilesChecker] Catalog goods transport event
  665. 07.09 14:38:39 INFO  [FilesChecker]    goods types for report count: 0
  666. 07.09 14:38:39 INFO  [FilesChecker]    catalog goods files to cash count: 0
  667. 07.09 14:38:41 DEBUG [TechProcessImpl] Server online mode
  668. 07.09 14:38:45 INFO  [TransferManager] ping = true
  669. 07.09 14:38:47 INFO  [TransferManager] Nothing yet not processed on server to resend
  670. 07.09 14:38:49 INFO  [FilesChecker] Catalog goods transport event
  671. 07.09 14:38:49 INFO  [FilesChecker]    goods types for report count: 0
  672. 07.09 14:38:49 INFO  [FilesChecker]    catalog goods files to cash count: 0
  673. 07.09 14:38:59 INFO  [TransferManager] Nothing yet not processed on server to resend
  674. 07.09 14:38:59 INFO  [FilesChecker] Catalog goods transport event
  675. 07.09 14:38:59 INFO  [FilesChecker]    goods types for report count: 0
  676. 07.09 14:38:59 INFO  [FilesChecker]    catalog goods files to cash count: 0
  677. 07.09 14:39:09 INFO  [FilesChecker] Catalog goods transport event
  678. 07.09 14:39:09 INFO  [FilesChecker]    goods types for report count: 0
  679. 07.09 14:39:09 INFO  [FilesChecker]    catalog goods files to cash count: 0
  680. 07.09 14:39:11 DEBUG [TechProcessImpl] Server online mode
  681. 07.09 14:39:11 INFO  [TransferManager] Nothing yet not processed on server to resend
  682. 07.09 14:39:19 INFO  [FilesChecker] Catalog goods transport event
  683. 07.09 14:39:19 INFO  [FilesChecker]    goods types for report count: 0
  684. 07.09 14:39:19 INFO  [FilesChecker]    catalog goods files to cash count: 0
  685. 07.09 14:39:23 INFO  [TransferManager] Nothing yet not processed on server to resend
  686. 07.09 14:39:25 INFO  [TransferManager] OD found 0 documents
  687. 07.09 14:39:25 INFO  [TransferManager] OD found 0 documents to register
  688. 07.09 14:39:29 INFO  [FilesChecker] Catalog goods transport event
  689. 07.09 14:39:29 INFO  [FilesChecker]    goods types for report count: 0
  690. 07.09 14:39:29 INFO  [FilesChecker]    catalog goods files to cash count: 0
  691. 07.09 14:39:35 INFO  [TransferManager] Nothing yet not processed on server to resend
  692. 07.09 14:39:35 INFO  [TransferManager] ping = true
  693. 07.09 14:39:39 INFO  [FilesChecker] Catalog goods transport event
  694. 07.09 14:39:39 INFO  [FilesChecker]    goods types for report count: 0
  695. 07.09 14:39:39 INFO  [FilesChecker]    catalog goods files to cash count: 0
  696. 07.09 14:39:41 DEBUG [TechProcessImpl] Server online mode
  697. 07.09 14:39:47 INFO  [TransferManager] Nothing yet not processed on server to resend
  698. 07.09 14:39:49 INFO  [FilesChecker] Catalog goods transport event
  699. 07.09 14:39:49 INFO  [FilesChecker]    goods types for report count: 0
  700. 07.09 14:39:49 INFO  [FilesChecker]    catalog goods files to cash count: 0
  701. 07.09 14:39:59 INFO  [TransferManager] Nothing yet not processed on server to resend
  702. 07.09 14:39:59 INFO  [FilesChecker] Catalog goods transport event
  703. 07.09 14:39:59 INFO  [FilesChecker]    goods types for report count: 0
  704. 07.09 14:39:59 INFO  [FilesChecker]    catalog goods files to cash count: 0
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!