Advertisement
fedorm

Untitled

Aug 29th, 2019
262
0
Never
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
XML 17.80 KB | None | 0 0
  1. 29.08 18:56:15.608 INFO  [CatalogService] getGoodsByBarCode barcode = 5000001
  2. 29.08 18:56:15.615 INFO  [CatalogService] get product by barcode '5000001' result: null
  3. 29.08 18:56:15.615 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5000001 time = 7 ms
  4. 29.08 18:56:15.615 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5000001 time = 7 ms
  5. 29.08 18:56:15.615 INFO  [CatalogService] Get product by item '5000001' result null
  6. 29.08 18:56:15.615 INFO  [SpeedLog] searchProduct time  time = 7 ms
  7. 29.08 18:56:15.615 INFO  [TechProcessImpl] TP searchProduct 01001
  8. 29.08 18:56:15.617 INFO  [CatalogService] Get product by item '01001' result ProductPieceEntity[name=Кефир малиновый сладкий (Израиль), item=01001]
  9. 29.08 18:56:15.618 INFO  [SpeedLog] getProductRestrictions  time = 1 ms
  10. 29.08 18:56:15.618 INFO  [SpeedLog] searchProduct time  time = 2 ms
  11. 29.08 18:56:15.761 INFO  [TechProcessImpl] TP searchProduct 5000002
  12. 29.08 18:56:15.762 INFO  [CatalogService] getGoodsByBarCode barcode = 5000002
  13. 29.08 18:56:15.762 INFO  [CatalogService] get product by barcode '5000002' result: null
  14. 29.08 18:56:15.762 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5000002 time = 0 ms
  15. 29.08 18:56:15.762 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5000002 time = 0 ms
  16. 29.08 18:56:15.763 INFO  [CatalogService] Get product by item '5000002' result null
  17. 29.08 18:56:15.763 INFO  [SpeedLog] searchProduct time  time = 1 ms
  18. 29.08 18:56:15.763 INFO  [TechProcessImpl] TP searchProduct 02025
  19. 29.08 18:56:15.764 INFO  [CatalogService] Get product by item '02025' result ProductPieceEntity[name=Пряник виноградный сладкий (Испания), item=02025]
  20. 29.08 18:56:15.764 INFO  [SpeedLog] getProductRestrictions  time = 0 ms
  21. 29.08 18:56:15.765 INFO  [SpeedLog] searchProduct time  time = 2 ms
  22. 29.08 18:56:15.771 INFO  [TechProcessImpl] TP searchProduct 31000
  23. 29.08 18:56:15.772 INFO  [CatalogService] Get product by item '31000' result ProductCiggyEntity[name=Сигареты Просто Верблюд (Израиль), item=31000]
  24. 29.08 18:56:15.773 INFO  [SpeedLog] getProductRestrictions  time = 0 ms
  25. 29.08 18:56:15.773 INFO  [SpeedLog] searchProduct time  time = 2 ms
  26. 29.08 18:56:15.846 INFO  [TechProcessImpl] TP searchProduct 5000002
  27. 29.08 18:56:15.846 INFO  [CatalogService] getGoodsByBarCode barcode = 5000002
  28. 29.08 18:56:15.846 INFO  [CatalogService] get product by barcode '5000002' result: null
  29. 29.08 18:56:15.847 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5000002 time = 1 ms
  30. 29.08 18:56:15.847 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5000002 time = 1 ms
  31. 29.08 18:56:15.847 INFO  [CatalogService] Get product by item '5000002' result null
  32. 29.08 18:56:15.849 INFO  [SpeedLog] searchProduct time  time = 3 ms
  33. 29.08 18:56:15.849 INFO  [TechProcessImpl] TP searchProduct 020256
  34. 29.08 18:56:15.849 INFO  [CatalogService] Get product by item '020256' result null
  35. 29.08 18:56:15.850 INFO  [CatalogService] getGoodsByBarCode barcode = 020256
  36. 29.08 18:56:15.850 INFO  [CatalogService] get product by barcode '020256' result: null
  37. 29.08 18:56:15.850 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 020256 time = 0 ms
  38. 29.08 18:56:15.850 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 020256 time = 0 ms
  39. 29.08 18:56:15.851 INFO  [SpeedLog] searchProduct time  time = 2 ms
  40. 29.08 18:56:15.851 WARN  [SoftCheckService] Unable to find product by barcode = '5000002' or code = '020256'
  41. 29.08 18:56:17.973 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  42. 29.08 18:56:17.988 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 01001; type: ProductPieceEntity; count: 2000; sum: 28846; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  43. 29.08 18:56:18.127 INFO  [SpeedLog] TP addPosition time = 139 ms
  44. 29.08 18:56:18.128 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 02025; type: ProductPieceEntity; count: 10000; sum: 328830; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  45. 29.08 18:56:18.131 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  46. 29.08 18:56:18.131 INFO  [TextCustomerDisplayPluginAbstract] **********************
  47. 29.08 18:56:18.131 INFO  [TextCustomerDisplayPluginAbstract] *Кефир малин 2 288.46*
  48. 29.08 18:56:18.131 INFO  [TextCustomerDisplayPluginAbstract] *Итого         288.46*
  49. 29.08 18:56:18.131 INFO  [TextCustomerDisplayPluginAbstract] **********************
  50. 29.08 18:56:18.131 INFO  [TextCustomerDisplayPluginAbstract]
  51. 29.08 18:56:18.146 INFO  [SpeedLog] TP addPosition time = 18 ms
  52. 29.08 18:56:18.146 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 31000; type: ProductCiggyEntity; count: 10000; sum: 328830; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  53. 29.08 18:56:18.171 INFO  [SpeedLog] TP addPosition time = 25 ms
  54. 29.08 18:56:18.194 TRACE [MLServiceImpl] entering isEnabled()
  55. 29.08 18:56:18.194 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  56. 29.08 18:56:18.195 DEBUG [LoymaxCardPlugin] entering getCardInfo(ru.crystals.pos.cards.service.PluginCardSearchRequest@16025a).
  57. 29.08 18:56:18.195 DEBUG [LoymaxCardPlugin] Coupon number length: 15
  58. 29.08 18:56:18.195 DEBUG [LoymaxCardPlugin] Card number length: 13 - not allowed
  59. 29.08 18:56:18.195 DEBUG [LoymaxCardPlugin] leaving getCardInfo(CardInfoSearchParams). The card [9905100100001] does not exist in the processing for sure
  60. 29.08 18:56:18.239 INFO  [RegistryImpl] Subscription URLs of module CARDS:
  61.     JNP : jnp://172.29.17.105:1099
  62.     HTTP : http://172.29.17.105:8090
  63. 29.08 18:56:18.239 INFO  [RegistryImpl] Try register SubscriptionURL - CARDS : JNP:jnp://172.29.17.105:1099
  64. 29.08 18:56:18.240 INFO  [RegistryImpl] Try register SubscriptionURL - CARDS : HTTP:http://172.29.17.105:8090
  65. 29.08 18:56:18.273 WARN  [BonusBalanceSupplier] Processing "ServerCardsApplication" has provided no bonus balance for card "9905100100001"
  66. 29.08 18:56:18.464 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  67. 29.08 18:56:18.465 INFO  [TextCustomerDisplayPluginAbstract] **********************
  68. 29.08 18:56:18.465 INFO  [TextCustomerDisplayPluginAbstract] *Сигареты  10 3288.30*
  69. 29.08 18:56:18.465 INFO  [TextCustomerDisplayPluginAbstract] *Итого        6865.06*
  70. 29.08 18:56:18.465 INFO  [TextCustomerDisplayPluginAbstract] **********************
  71. 29.08 18:56:18.465 INFO  [TextCustomerDisplayPluginAbstract]
  72. 29.08 18:56:18.508 TRACE [TechProcessImpl] entering applyClientRequisites(ClientEntity). The argument is: holder [ClientEntity [id=19627; guid=110769; deleted=false]]
  73. 29.08 18:56:18.508 TRACE [TechProcessImpl] leaving applyClientRequisites(ClientEntity). Cash template prohibits usage of personal-sensitive data to send e-receipt to a card-ho;der
  74. 29.08 18:56:19.230 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  75. 29.08 18:56:19.233 INFO  [CommonLogger] busy = true
  76. 29.08 18:56:20.139 TRACE [TechProcessImpl] Start warn divide alco
  77. 29.08 18:56:20.140 TRACE [TechProcessImpl] End warn devide alco
  78. 29.08 18:56:20.146 INFO  [TechProcessImpl] goToDiscounts
  79. 29.08 18:56:20.161 INFO  [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  80. 29.08 18:56:20.167 INFO  [LoyalProductFinder] Time finding items (3) = 5 ms
  81. 29.08 18:56:20.167 INFO  [LoyalProductsConverter] Time searching loyal goods for 3 productItems = 5 ms
  82. 29.08 18:56:20.167 INFO  [LoyalCalculatorConverter] Time converting 3 positions = 0 ms
  83. 29.08 18:56:20.167 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase{number=3, dateCreate=Thu Aug 29 18:56:17 MSK 2019, checkSum=686506, operationType=true, discountValueTotal=0}
  84. 29.08 18:56:20.168 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: null
  85. 29.08 18:56:20.168 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase{number=3, dateCreate=Thu Aug 29 18:56:17 MSK 2019, checkSum=686506, operationType=true, discountValueTotal=0}
  86. 29.08 18:56:20.168 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  87. 29.08 18:56:20.403 WARN  [LoyaltyRestrictionsServiceImpl] checkRestrictions: no limits received
  88. 29.08 18:56:20.405 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  89. 29.08 18:56:20.405 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  90. 29.08 18:56:20.419 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  91. 29.08 18:56:20.420 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase{number=3, dateCreate=Thu Aug 29 18:56:17 MSK 2019, checkSum=686506, operationType=true, discountValueTotal=0}
  92. 29.08 18:56:20.420 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: null
  93. 29.08 18:56:20.420 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase{number=3, dateCreate=Thu Aug 29 18:56:17 MSK 2019, checkSum=686506, operationType=true, discountValueTotal=0}
  94. 29.08 18:56:20.420 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  95. 29.08 18:56:20.420 TRACE [SCService] entering isEnabled()
  96. 29.08 18:56:20.420 TRACE [SCService] leaving isEnabled(). the result is: false
  97. 29.08 18:56:20.421 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  98. 29.08 18:56:20.421 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  99. 29.08 18:56:20.422 DEBUG [LoymaxUtils] Loymax request:
  100. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><Calculates><CalculateRequest PurchaseID="71050551567094180421" OperationID="1b49de3416964a3081f89f3dcd87c697" OperationDate="2019-08-29T18:56:20.421+03:00" ElementID="1"><Cheque ChequeDate="2019-08-29T18:56:17.988+03:00"><ChequeLine PosID="492" Amount="288.46" Name="Кефир малиновый сладкий (Израиль)" GoodsId="01001" Price="144.23" Quantity="2.000"/><ChequeLine PosID="493" Amount="3288.30" Name="Пряник виноградный сладкий (Испания)" GoodsId="02025" Price="328.83" Quantity="10.000"><Params><Param Name="MinAmount" Value="328.83" Type="decimal"/></Params></ChequeLine><ChequeLine PosID="494" Amount="3288.30" Name="Сигареты Просто Верблюд (Израиль)" GoodsId="31000" Price="328.83" Quantity="10.000"><Params><Param Name="MinAmount" Value="328.83" Type="decimal"/></Params></ChequeLine></Cheque></CalculateRequest></Calculates></XMLRequest>
  101.  
  102. 29.08 18:56:20.439 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  103. 29.08 18:56:20.444 DEBUG [LoymaxUtils] Loymax response:
  104. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><Calculates><CalculateResponse AvailableAmount="6865.06" AvailableBonusAmount="0" ErrorCode="0"><Cheque ChequeDate="2019-08-29T18:56:17.988+03:00"><ChequeLine PosID="492" Amount="288.46" Name="Кефир малиновый сладкий (Израиль)" GoodsId="01001" Price="144.23" Quantity="2.000" Discount="0"/><ChequeLine PosID="493" Amount="3288.30" Name="Пряник виноградный сладкий (Испания)" GoodsId="02025" Price="328.83" Quantity="10.000" Discount="0"><Params><Param Name="MinAmount" Value="328.83" Type="decimal"/></Params></ChequeLine><ChequeLine PosID="494" Amount="3288.30" Name="Сигареты Просто Верблюд (Израиль)" GoodsId="31000" Price="328.83" Quantity="10.000" Discount="0"><Params><Param Name="MinAmount" Value="328.83" Type="decimal"/></Params></ChequeLine></Cheque></CalculateResponse></Calculates></XMLResponse>
  105.  
  106. 29.08 18:56:20.447 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830000].
  107. 29.08 18:56:20.447 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830000].
  108. 29.08 18:56:20.447 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830000].
  109. 29.08 18:56:20.447 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830000].
  110. 29.08 18:56:20.448 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830000].
  111. 29.08 18:56:20.452 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830000].
  112. 29.08 18:56:20.467 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  113. 29.08 18:56:20.468 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  114. 29.08 18:56:20.481 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  115. 29.08 18:56:20.484 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  116. 29.08 18:56:20.485 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  117. 29.08 18:56:20.486 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  118. 29.08 18:56:20.491 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  119. 29.08 18:56:20.491 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  120. 29.08 18:56:20.492 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  121. 29.08 18:56:20.492 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  122. 29.08 18:56:20.492 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  123. 29.08 18:56:20.492 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  124. 29.08 18:56:20.492 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  125. 29.08 18:56:20.492 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  126. 29.08 18:56:20.493 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  127. 29.08 18:56:20.493 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  128. 29.08 18:56:20.493 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  129. 29.08 18:56:20.493 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  130. 29.08 18:56:20.538 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  131. 29.08 18:56:20.540 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  132. 29.08 18:56:20.543 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  133. 29.08 18:56:20.544 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  134. 29.08 18:56:20.548 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase{number=3, dateCreate=Thu Aug 29 18:56:17 MSK 2019, checkSum=686506, operationType=true, discountValueTotal=0}
  135. 29.08 18:56:20.548 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: null
  136. 29.08 18:56:20.548 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase{number=3, dateCreate=Thu Aug 29 18:56:17 MSK 2019, checkSum=686506, operationType=true, discountValueTotal=0}
  137. 29.08 18:56:20.548 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  138. 29.08 18:56:20.548 INFO  [LoyalServiceImpl] Discount calculation (Purchase #3; Positions: 3
  139. 29.08 18:56:20.550 WARN  [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  140. 29.08 18:56:20.551 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=493;number=2;item=02025;count=10000;sum=328830].
  141. 29.08 18:56:20.551 WARN  [RestrictionUtils] Discount apply is forbidden for Position:[id=494;number=3;item=31000;count=10000;sum=328830].
  142. 29.08 18:56:20.567 INFO  [LoyalBridgeServiceImpl] Total time of discount calculation = 405 ms
  143. 29.08 18:56:20.567 INFO  [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  144. 29.08 18:56:20.588 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  145. 29.08 18:56:20.589 INFO  [TextCustomerDisplayPluginAbstract] **********************
  146. 29.08 18:56:20.589 INFO  [TextCustomerDisplayPluginAbstract] *Скидка          0.00*
  147. 29.08 18:56:20.589 INFO  [TextCustomerDisplayPluginAbstract] *Итого        6865.06*
  148. 29.08 18:56:20.589 INFO  [TextCustomerDisplayPluginAbstract] **********************
  149. 29.08 18:56:20.589 INFO  [TextCustomerDisplayPluginAbstract]
  150. 29.08 18:56:20.591 INFO  [SpeedLog] TechProcess discounts processing on cheque with 3 positions [sale] time = 445 ms
  151. 29.08 18:56:20.592 INFO  [CommonLogger] value = 0
  152. 29.08 18:56:20.626 TRACE [TechProcessImpl] Start warn divide amount
  153. 29.08 18:56:20.627 INFO  [TechProcessImpl] goToPayments
  154. 29.08 18:56:20.629 INFO  [CheckContainer] startPayments() surcharge = 686506
  155. 29.08 18:56:20.633 INFO  [CheckContainer] isPaymentComplete = false
  156. 29.08 18:56:20.634 INFO  [CheckContainer] prevState = ADD_PAYMENT
  157. 29.08 18:56:20.634 INFO  [CheckContainer] paid = 0
  158. 29.08 18:56:20.634 INFO  [CheckContainer] surcharge = 686506
  159. 29.08 18:56:20.634 INFO  [CheckContainer] getPaymentFiscalizeError = NONE
  160. 29.08 18:56:20.634 INFO  [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  161. 29.08 18:56:20.640 INFO  [CommonLogger] busy = false
  162. 29.08 18:56:20.641 INFO  [SpeedLog] (SUBTOTAL) time = 1,408;
  163. 29.08 18:56:20.641 INFO  [CommonLogger] busy = false
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement