Advertisement
fedorm

Untitled

Apr 19th, 2018
260
0
Never
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
text 203.33 KB | None | 0 0
  1. 18.04 20:33:46 INFO [CheckContainer] visualization will start searching product
  2. 18.04 20:33:46 INFO [TechProcessImpl] TP searchProduct 8805032000002
  3. 18.04 20:33:46 INFO [ServicesImpl] getGoodsByBarCode barcode = 8805032000002
  4. 18.04 20:33:46 INFO [ServicesImpl] get product by barcode '8805032000002' result: ProductPieceEntity[name=Йогурт апельсиновый сладкий (Ю. Корея), item=05032]
  5. 18.04 20:33:46 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 8805032000002 time = 1 ms
  6. 18.04 20:33:46 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 8805032000002 time = 1 ms
  7. 18.04 20:33:46 INFO [SpeedLog] getProductRestrictions time = 0 ms
  8. 18.04 20:33:46 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 8805032000002 time = 2 ms
  9. 18.04 20:33:46 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 8805032000002 time = 2 ms
  10. 18.04 20:33:46 INFO [SpeedLog] searchProduct time time = 2 ms
  11. 18.04 20:33:46 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":84,"pos":2,"barcode":"8805032000002","item":"05032","name":"Йогурт апельсиновый сладкий (Ю","price":681.13,"quant":1.0,"sum":681.13,"sumAll":879.31,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:33:46.076","offline":false}]}
  12. 18.04 20:33:46 INFO [SpeedLog] (CHECK_CONTAINER) show product container time = 0,033 sec; average speed = 0,031 sec; position test num = 154.0; prooduct item 05032; <33>
  13. 18.04 20:33:46 INFO [CommonLogger] busy = false
  14. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  15. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  16. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] *Йогурт апел 1 681.13*
  17. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] *Итого 879.31*
  18. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  19. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract]
  20. 18.04 20:33:46 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 05032; type: ProductPieceEntity; count: 1000; sum: 68113; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  21. 18.04 20:33:46 INFO [SpeedLog] TP addPosition time = 13 ms
  22. 18.04 20:33:46 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  23. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  24. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  25. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] *Йогурт апел 1 681.13*
  26. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] *Итого 879.31*
  27. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  28. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract]
  29. 18.04 20:33:46 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  30. 18.04 20:33:46 INFO [CommonLogger] busy = true
  31. 18.04 20:33:46 TRACE [TechProcessImpl] Start warn divide alco
  32. 18.04 20:33:46 TRACE [TechProcessImpl] End warn devide alco
  33. 18.04 20:33:46 INFO [TechProcessImpl] goToDiscounts
  34. 18.04 20:33:46 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  35. 18.04 20:33:46 INFO [LoyalProductFinder] Time finding items (2) = 14 ms
  36. 18.04 20:33:46 INFO [LoyalProductsConverter] Time searching loyal goods for 2 productItems = 14 ms
  37. 18.04 20:33:46 INFO [LoyalCalculatorConverter] Time converting 2 positions = 0 ms
  38. 18.04 20:33:46 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  39. 18.04 20:33:46 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  40. 18.04 20:33:46 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  41. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  42. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  43. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  44. 18.04 20:33:46 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  45. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  46. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  47. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  48. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  49. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  50. 18.04 20:33:46 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  51. 18.04 20:33:46 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  52. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  53. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  54. 18.04 20:33:46 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  55. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  56. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  57. 18.04 20:33:46 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  58. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  59. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  60. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  61. 18.04 20:33:46 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  62. 18.04 20:33:46 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  63. 18.04 20:33:46 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 2
  64. 18.04 20:33:46 INFO [AdvResultPersistenceManager] createLoyTx: either the receipt [Purchase:[number=null;dateCreate=2018-04-18 20:33:46.008;operationType=true;checkSum=87931;discount=0]] does not have discounts, or has corresponding loy-tx in the DB already
  65. 18.04 20:33:46 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  66. 18.04 20:33:46 INFO [AdvResultPersistenceManager] createLoyTx: either the receipt [Purchase:[number=null;dateCreate=2018-04-18 20:33:46.008;operationType=true;checkSum=87931;discount=0]] does not have discounts, or has corresponding loy-tx in the DB already
  67. 18.04 20:33:46 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 60 ms
  68. 18.04 20:33:46 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  69. 18.04 20:33:46 INFO [SpeedLog] TechProcess discounts processing on cheque with 2 positions [sale] time = 86 ms
  70. 18.04 20:33:46 INFO [CommonLogger] value = 0
  71. 18.04 20:33:46 TRACE [TechProcessImpl] Start warn divide amount
  72. 18.04 20:33:46 INFO [TechProcessImpl] goToPayments
  73. 18.04 20:33:46 INFO [CheckContainer] startPayments() surcharge = 87931
  74. 18.04 20:33:46 INFO [CheckContainer] isPaymentComplete = false
  75. 18.04 20:33:46 INFO [CheckContainer] prevState = ADD_POSITION
  76. 18.04 20:33:46 INFO [CheckContainer] paid = 0
  77. 18.04 20:33:46 INFO [CheckContainer] surcharge = 87931
  78. 18.04 20:33:46 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  79. 18.04 20:33:46 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  80. 18.04 20:33:46 INFO [CommonLogger] busy = false
  81. 18.04 20:33:46 INFO [SpeedLog] (SUBTOTAL) time = 0,132;
  82. 18.04 20:33:46 INFO [CommonLogger] busy = false
  83. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  84. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  85. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  86. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] *Итого 879.31*
  87. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  88. 18.04 20:33:46 INFO [TextCustomerDisplayPluginAbstract]
  89. 18.04 20:33:46 INFO [CommonLogger] BarCode::1642732420123
  90. 18.04 20:33:46 INFO [Scale] BarcodeScannerImpl - barcode = 1642732420123
  91. 18.04 20:33:46 INFO [TechProcessImpl] eventBarcodeScanner 1642732420123
  92. 18.04 20:33:46 INFO [CheckContainer] Barcode 1642732420123 has been received by visualisation
  93. 18.04 20:33:46 TRACE [MLServiceImpl] entering isEnabled()
  94. 18.04 20:33:46 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  95. 18.04 20:33:46 TRACE [MLServiceImpl] entering isMlCard(String, boolean). The argument are: "1642732420123"
  96. 18.04 20:33:46 TRACE [MLServiceImpl] leaving isMlCard(String, boolean). The result is: false
  97. 18.04 20:33:46 DEBUG [ExampleCardPlugin] entering getCardInfo(CardInfoSearchParams). The arguments are: request [ru.crystals.pos.cards.service.PluginCardSearchRequest@af5c92]
  98. 18.04 20:33:46 DEBUG [ExampleCardPlugin] leaving getCardInfo(CardInfoSearchParams). The card [1642732420123] does not exist in the processing for sure
  99. 18.04 20:33:46 WARN [SqlExceptionHelper] SQL Error: 0, SQLState: 42703
  100. 18.04 20:33:46 ERROR [SqlExceptionHelper] ERROR: column cardaction0_.reason does not exist
  101. Позиция: 301
  102. 18.04 20:33:47 ERROR [CashCardFinder] An error while searching card by number '1642732420123':
  103. org.hibernate.exception.SQLGrammarException: could not extract ResultSet
  104. at org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:123)
  105. at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:49)
  106. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:126)
  107. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:112)
  108. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:91)
  109. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.getResultSet(AbstractLoadPlanBasedLoader.java:449)
  110. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeQueryStatement(AbstractLoadPlanBasedLoader.java:202)
  111. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:137)
  112. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:102)
  113. at org.hibernate.loader.collection.plan.AbstractLoadPlanBasedCollectionInitializer.initialize(AbstractLoadPlanBasedCollectionInitializer.java:100)
  114. at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:693)
  115. at org.hibernate.event.internal.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:92)
  116. at org.hibernate.internal.SessionImpl.initializeCollection(SessionImpl.java:1933)
  117. at org.hibernate.collection.internal.AbstractPersistentCollection$4.doWork(AbstractPersistentCollection.java:558)
  118. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  119. at org.hibernate.collection.internal.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:554)
  120. at org.hibernate.collection.internal.AbstractPersistentCollection.read(AbstractPersistentCollection.java:142)
  121. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:171)
  122. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:156)
  123. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  124. at org.hibernate.collection.internal.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:155)
  125. at org.hibernate.collection.internal.PersistentBag.isEmpty(PersistentBag.java:283)
  126. at ru.crystals.pos.cards.service.search.CashCardFinder.prepareCard(CashCardFinder.java:264)
  127. at ru.crystals.pos.cards.service.search.CashCardFinder.getCardType(CashCardFinder.java:120)
  128. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeLocal(CardsServiceImpl.java:535)
  129. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeProcessing(CardsServiceImpl.java:576)
  130. at ru.crystals.pos.cards.service.CardsServiceImpl.getNativeCardEntity(CardsServiceImpl.java:497)
  131. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeEntity(CardsServiceImpl.java:477)
  132. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardType(CardsServiceImpl.java:881)
  133. at ru.crystals.pos.techprocess.TechProcessSearch.lambda$getCardType$0(TechProcessSearch.java:376)
  134. at ru.crystals.pos.techprocess.TechProcessSearch.tryGetCardTypeNotFromCardRange(TechProcessSearch.java:394)
  135. at ru.crystals.pos.techprocess.TechProcessSearch.getCardType(TechProcessSearch.java:376)
  136. at ru.crystals.pos.techprocess.TechProcessSearch.searchCard(TechProcessSearch.java:111)
  137. at ru.crystals.pos.techprocess.TechProcessImpl.searchCard(TechProcessImpl.java:3724)
  138. at ru.crystals.pos.visualization.check.CheckContainer.searchCardByBarcode(CheckContainer.java:1894)
  139. at ru.crystals.pos.visualization.check.CheckContainer.barcodeInAddPayment(CheckContainer.java:2200)
  140. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:2014)
  141. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:1982)
  142. at ru.crystals.pos.visualization.components.MainWindow.barcode(MainWindow.java:1147)
  143. at ru.crystals.pos.visualization.Factory.eventBarcode(Factory.java:741)
  144. at ru.crystals.pos.techprocess.TechProcessImpl.eventBarcodeScanner(TechProcessImpl.java:3814)
  145. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:307)
  146. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:119)
  147. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:100)
  148. at ru.crystals.pos.InternalCashPoolExecutorImpl$4.run(InternalCashPoolExecutorImpl.java:125)
  149. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  150. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  151. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  152. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  153. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  154. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  155. at java.lang.Thread.run(Thread.java:745)
  156. Caused by: org.postgresql.util.PSQLException: ERROR: column cardaction0_.reason does not exist
  157. Позиция: 301
  158. at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2270)
  159. at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1998)
  160. at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
  161. at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:570)
  162. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:420)
  163. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeQuery(AbstractJdbc2Statement.java:305)
  164. at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76)
  165. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:82)
  166. ... 47 more
  167. 18.04 20:33:47 WARN [CardsServiceImpl] CashCardsApplication: no balance received: 1642732420123
  168. 18.04 20:33:47 ERROR [CheckServiceImpl] leaving saveClientRequisites(InternalCardsEntity). No one card is detected!
  169. 18.04 20:33:47 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":35,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":84,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":879.31,"cardType":"","cardNumber":"1642732420123","discount":0.0,"discountAll":1000.0,"dateTime":"18.04.2018 20:33:47.145","offline":false}]}
  170. 18.04 20:33:47 INFO [CommonLogger] busy = true
  171. 18.04 20:33:47 INFO [TechProcessImpl] goToWaitGoods
  172. 18.04 20:33:47 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
  173. 18.04 20:33:47 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
  174. 18.04 20:33:47 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  175. 18.04 20:33:47 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  176. 18.04 20:33:47 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  177. 18.04 20:33:47 INFO [TechProcessImpl] goToPayments
  178. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  179. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] **********************
  180. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  181. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] *Итого 879.31*
  182. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] **********************
  183. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract]
  184. 18.04 20:33:47 INFO [CommonLogger] value = 0
  185. 18.04 20:33:47 INFO [CommonLogger] busy = false
  186. 18.04 20:33:47 INFO [CommonLogger] busy = true
  187. 18.04 20:33:47 TRACE [TechProcessImpl] Start warn divide alco
  188. 18.04 20:33:47 TRACE [TechProcessImpl] End warn devide alco
  189. 18.04 20:33:47 INFO [TechProcessImpl] goToDiscounts
  190. 18.04 20:33:47 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  191. 18.04 20:33:47 INFO [LoyalProductFinder] Time finding items (2) = 6 ms
  192. 18.04 20:33:47 INFO [LoyalProductsConverter] Time searching loyal goods for 2 productItems = 6 ms
  193. 18.04 20:33:47 INFO [LoyalCalculatorConverter] Time converting 2 positions = 0 ms
  194. 18.04 20:33:47 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  195. 18.04 20:33:47 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  196. 18.04 20:33:47 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  197. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  198. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  199. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  200. 18.04 20:33:47 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  201. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  202. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  203. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  204. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  205. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  206. 18.04 20:33:47 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  207. 18.04 20:33:47 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  208. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  209. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  210. 18.04 20:33:47 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  211. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  212. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  213. 18.04 20:33:47 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  214. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  215. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  216. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  217. 18.04 20:33:47 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  218. 18.04 20:33:47 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  219. 18.04 20:33:47 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 2
  220. 18.04 20:33:47 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the loy-tx [id: -1] was not detected (in the cache)! Null will be returned!
  221. 18.04 20:33:47 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 100 ms
  222. 18.04 20:33:47 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  223. 18.04 20:33:47 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":52,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":84,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":791.38,"cardType":"","cardNumber":"","discount":0.0,"discountAll":87.93,"dateTime":"18.04.2018 20:33:47.351","offline":false}]}
  224. 18.04 20:33:47 INFO [SpeedLog] TechProcess discounts processing on cheque with 2 positions [sale] time = 141 ms
  225. 18.04 20:33:47 INFO [CommonLogger] value = 8793
  226. 18.04 20:33:47 INFO [CommonLogger] busy = false
  227. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  228. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] **********************
  229. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] * *
  230. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] * *
  231. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract] **********************
  232. 18.04 20:33:47 INFO [TextCustomerDisplayPluginAbstract]
  233. 18.04 20:33:50 INFO [FiscalPrinter] resetException()
  234. 18.04 20:33:50 INFO [FilesChecker] Catalog goods transport event
  235. 18.04 20:33:50 INFO [FilesChecker] goods types for report count: 0
  236. 18.04 20:33:50 INFO [FilesChecker] catalog goods files to cash count: 0
  237. 18.04 20:33:52 INFO [TransferManager] Nothing yet not processed on server to resend
  238. 18.04 20:33:55 ERROR [TechProcessImpl] TP error:
  239. 18.04 20:33:56 INFO [CommonLogger] busy = true
  240. 18.04 20:33:56 TRACE [TechProcessImpl] Start warn divide amount
  241. 18.04 20:33:56 INFO [TechProcessImpl] goToPayments
  242. 18.04 20:33:56 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":52,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":84,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":791.38,"cardType":"","cardNumber":"","discount":0.0,"discountAll":87.93,"dateTime":"18.04.2018 20:33:56.817","offline":false}]}
  243. 18.04 20:33:56 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  244. 18.04 20:33:56 INFO [TextCustomerDisplayPluginAbstract] **********************
  245. 18.04 20:33:56 INFO [TextCustomerDisplayPluginAbstract] *Скидка 87.93*
  246. 18.04 20:33:56 INFO [TextCustomerDisplayPluginAbstract] *Итого 791.38*
  247. 18.04 20:33:56 INFO [TextCustomerDisplayPluginAbstract] **********************
  248. 18.04 20:33:56 INFO [TextCustomerDisplayPluginAbstract]
  249. 18.04 20:33:56 INFO [CheckContainer] startPayments() surcharge = 79138
  250. 18.04 20:33:56 INFO [CheckContainer] isPaymentComplete = false
  251. 18.04 20:33:56 INFO [CheckContainer] prevState = ADD_POSITION
  252. 18.04 20:33:56 INFO [CheckContainer] paid = 0
  253. 18.04 20:33:56 INFO [CheckContainer] surcharge = 79138
  254. 18.04 20:33:56 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  255. 18.04 20:33:56 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  256. 18.04 20:33:56 INFO [CommonLogger] busy = false
  257. 18.04 20:33:56 INFO [SpeedLog] (SUBTOTAL) time = 9,637;
  258. 18.04 20:33:56 INFO [CommonLogger] busy = false
  259. 18.04 20:33:56 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  260. 18.04 20:33:57 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  261. 18.04 20:33:57 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  262. 18.04 20:33:57 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  263. 18.04 20:33:57 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  264. 18.04 20:33:58 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=82, command=kbdMenu3]
  265. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  266. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  267. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]) one!
  268. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  269. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]] from further processing 'cause it has no chance to trigger anyway!
  270. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [1] BONUS_SR10-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]) one!
  271. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  272. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [1] BONUS_INFORMIX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]) one!
  273. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  274. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [0] BONUS_LOYMAX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]) one!
  275. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  276. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  277. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]) one!
  278. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  279. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]] from further processing 'cause it has no chance to trigger anyway!
  280. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [1] BONUS_SR10-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]) one!
  281. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  282. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [1] BONUS_INFORMIX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]) one!
  283. 18.04 20:33:58 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  284. 18.04 20:33:58 WARN [AdvertisingActionUtils] superfluous [0] BONUS_LOYMAX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]) one!
  285. 18.04 20:33:58 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  286. 18.04 20:33:58 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  287. 18.04 20:33:58 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  288. 18.04 20:33:59 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  289. 18.04 20:33:59 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  290. 18.04 20:33:59 INFO [CommonLogger] Menu command: Аннулирование (class ru.crystals.pos.visualization.menu.commands.CancelCheckCommand)
  291. 18.04 20:33:59 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=37, command=kbdLeft]
  292. 18.04 20:34:00 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  293. 18.04 20:34:00 INFO [TechProcessShiftProxy] cancelCheck start
  294. 18.04 20:34:00 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
  295. 18.04 20:34:00 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
  296. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  297. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  298. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  299. 18.04 20:34:00 INFO [WaitComponent] [WaitComponent] MainFrame enabled - false
  300. 18.04 20:34:00 INFO [TechProcessShiftProxy] cancelCheck PurchaseEntity [id=622, number=null, dateCreate=Wed Apr 18 20:33:46 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  301. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] Purchase cancelled: no implementation of PlastekServices.
  302. 18.04 20:34:00 TRACE [MLServiceImpl] entering getMlCardNo(PurchaseEntity). The argument is: PurchaseEntity [id=622, number=84, dateCreate=Wed Apr 18 20:33:46 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  303. 18.04 20:34:00 TRACE [MLServiceImpl] leaving getMlCardNo(PurchaseEntity). The result is: null
  304. 18.04 20:34:00 INFO [FiscalPrinter] printCheck
  305. 18.04 20:34:00 INFO [FiscalPrinter] Fiscal interrupt: null
  306. 18.04 20:34:00 INFO [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale-annul.xml
  307. 18.04 20:34:00 INFO [DocumentTemplate] =====logo=====
  308. 18.04 20:34:00 INFO [DocumentTemplate] content: null
  309. 18.04 20:34:00 INFO [DocumentTemplate] =====header=====
  310. 18.04 20:34:00 INFO [DocumentTemplate] jr. name ||NORMAL
  311. 18.04 20:34:00 INFO [DocumentTemplate] 199100, Spb, Savushkina, 112 ||NORMAL
  312. 18.04 20:34:00 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  313. 18.04 20:34:00 INFO [DocumentTemplate] Смена:9 ЭКЛЗ:c0c09cd9-882||NORMAL
  314. 18.04 20:34:00 INFO [DocumentTemplate] Номер кассы:61 ИНН: 7802781104||NORMAL
  315. 18.04 20:34:00 INFO [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  316. 18.04 20:34:00 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  317. 18.04 20:34:00 INFO [DocumentTemplate] КАССОВЫЙ ЧЕК 84 18-04-2018 20:34||NORMAL
  318. 18.04 20:34:00 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  319. 18.04 20:34:00 INFO [DocumentTemplate] =====position=====
  320. 18.04 20:34:00 INFO [DocumentTemplate] Пряник яблочный сочный 178.36* 1 =178.36||SMALL
  321. 18.04 20:34:00 INFO [DocumentTemplate] Йогурт апельсиновый сла 613.02* 1 =613.02||SMALL
  322. 18.04 20:34:00 INFO [DocumentTemplate] =====fiscal=====
  323. 18.04 20:34:00 INFO [DocumentTemplate] content: null
  324. 18.04 20:34:00 INFO [DocumentTemplate] =====footer=====
  325. 18.04 20:34:00 INFO [DocumentTemplate] ******************************************** ||NORMAL
  326. 18.04 20:34:00 INFO [DocumentTemplate] ЧЕК No. 84 АННУЛИРОВАН||DOUBLEHEIGHT
  327. 18.04 20:34:00 INFO [DocumentTemplate] ******************************************** ||NORMAL
  328. 18.04 20:34:00 INFO [DocumentTemplate] =====cut=====
  329. 18.04 20:34:00 INFO [DocumentTemplate] content: null
  330. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  331. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] **********************
  332. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] *Аннулирование чека *
  333. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] *Сумма чека 791.38*
  334. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] **********************
  335. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract]
  336. 18.04 20:34:00 INFO [FiscalPrinter] getCountAnnul
  337. 18.04 20:34:00 INFO [FiscalPrinter] CountAnnul = 24
  338. 18.04 20:34:00 INFO [FiscalPrinter] getLastDocNum
  339. 18.04 20:34:00 INFO [CheckServiceImpl] cancelCheck: PurchaseEntity [id=622, number=84, dateCreate=Wed Apr 18 20:33:46 MSK 2018, dateCommit=Wed Apr 18 20:34:00 MSK 2018, fiscalDocNum=24;162, sentToServerStatus=NO_SENT]
  340. 18.04 20:34:00 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
  341. 18.04 20:34:00 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
  342. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  343. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  344. 18.04 20:34:00 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  345. 18.04 20:34:00 INFO [CommonLogger] value = 0
  346. 18.04 20:34:00 INFO [TechProcessImpl] Select seller [null]
  347. 18.04 20:34:00 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":25,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":84,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":791.38,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:00.213","offline":false}]}
  348. 18.04 20:34:00 INFO [SpeedLog] TechProcess cancelCheck time = 180 ms
  349. 18.04 20:34:00 INFO [WaitComponent] [WaitComponent] MainFrame enabled - true
  350. 18.04 20:34:00 INFO [TechProcessImpl] Select seller [null]
  351. 18.04 20:34:00 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  352. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  353. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] **********************
  354. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] *Добро *
  355. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] *пожаловать *
  356. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract] **********************
  357. 18.04 20:34:00 INFO [TextCustomerDisplayPluginAbstract]
  358. 18.04 20:34:00 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  359. 18.04 20:34:00 INFO [FilesChecker] Catalog goods transport event
  360. 18.04 20:34:00 INFO [FilesChecker] goods types for report count: 0
  361. 18.04 20:34:00 INFO [FilesChecker] catalog goods files to cash count: 0
  362. 18.04 20:34:01 INFO [FiscalPrinter] resetException()
  363. 18.04 20:34:03 INFO [CommonLogger] BarCode::4600793000001
  364. 18.04 20:34:03 INFO [Scale] BarcodeScannerImpl - barcode = 4600793000001
  365. 18.04 20:34:03 INFO [TechProcessImpl] eventBarcodeScanner 4600793000001
  366. 18.04 20:34:03 INFO [CheckContainer] Barcode 4600793000001 has been received by visualisation
  367. 18.04 20:34:03 INFO [TechProcessImpl] TP searchProduct 4600793000001
  368. 18.04 20:34:03 INFO [ServicesImpl] getGoodsByBarCode barcode = 4600793000001
  369. 18.04 20:34:03 INFO [ServicesImpl] get product by barcode '4600793000001' result: ProductPieceEntity[name=Лимонад вишневый ароматный (Россия), item=00793]
  370. 18.04 20:34:03 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 4600793000001 time = 1 ms
  371. 18.04 20:34:03 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 4600793000001 time = 1 ms
  372. 18.04 20:34:03 INFO [SpeedLog] getProductRestrictions time = 0 ms
  373. 18.04 20:34:03 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 4600793000001 time = 1 ms
  374. 18.04 20:34:03 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 4600793000001 time = 1 ms
  375. 18.04 20:34:03 INFO [SpeedLog] searchProduct time time = 1 ms
  376. 18.04 20:34:03 INFO [SpeedLog] (CHECK_CONTAINER) show product container time = 0,009 sec; average speed = 0,031 sec; position test num = 155.0; prooduct item 00793; <9>
  377. 18.04 20:34:03 INFO [CommonLogger] busy = false
  378. 18.04 20:34:03 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":4,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"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":"18.04.2018 20:34:03.127","offline":false}]}
  379. 18.04 20:34:03 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  380. 18.04 20:34:03 INFO [TextCustomerDisplayPluginAbstract] **********************
  381. 18.04 20:34:03 INFO [TextCustomerDisplayPluginAbstract] *Лимонад вишн 1 52.84*
  382. 18.04 20:34:03 INFO [TextCustomerDisplayPluginAbstract] *Итого 52.84*
  383. 18.04 20:34:03 INFO [TextCustomerDisplayPluginAbstract] **********************
  384. 18.04 20:34:03 INFO [TextCustomerDisplayPluginAbstract]
  385. 18.04 20:34:03 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"pos":1,"barcode":"4600793000001","item":"00793","name":"Лимонад вишневый ароматный (Ро","price":52.84,"quant":1.0,"sum":52.84,"sumAll":52.84,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:03.127","offline":false}]}
  386. 18.04 20:34:04 INFO [TransferManager] Nothing yet not processed on server to resend
  387. 18.04 20:34:04 INFO [CommonLogger] BarCode::3007085000007
  388. 18.04 20:34:04 INFO [Scale] BarcodeScannerImpl - barcode = 3007085000007
  389. 18.04 20:34:04 INFO [TechProcessImpl] eventBarcodeScanner 3007085000007
  390. 18.04 20:34:04 INFO [CheckContainer] Barcode 3007085000007 has been received by visualisation
  391. 18.04 20:34:04 INFO [CheckContainer] All conditions are checked. Current position will be added
  392. 18.04 20:34:04 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 00793; type: ProductPieceEntity; count: 1000; sum: 5284; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  393. 18.04 20:34:04 INFO [SpeedLog] TP addPosition time = 28 ms
  394. 18.04 20:34:04 INFO [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,032 sec; average speed = 0,032 sec; position test num = 70; <32>
  395. 18.04 20:34:04 INFO [CheckContainer] visualization will start searching product
  396. 18.04 20:34:04 INFO [TechProcessImpl] TP searchProduct 3007085000007
  397. 18.04 20:34:04 INFO [ServicesImpl] getGoodsByBarCode barcode = 3007085000007
  398. 18.04 20:34:04 INFO [ServicesImpl] get product by barcode '3007085000007' result: ProductPieceEntity[name=Пряник лимонный сладкий (Франция), item=07085]
  399. 18.04 20:34:04 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 3007085000007 time = 2 ms
  400. 18.04 20:34:04 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 3007085000007 time = 2 ms
  401. 18.04 20:34:04 INFO [SpeedLog] getProductRestrictions time = 1 ms
  402. 18.04 20:34:04 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 3007085000007 time = 3 ms
  403. 18.04 20:34:04 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 3007085000007 time = 3 ms
  404. 18.04 20:34:04 INFO [SpeedLog] searchProduct time time = 3 ms
  405. 18.04 20:34:04 INFO [SpeedLog] (CHECK_CONTAINER) show product container time = 0,065 sec; average speed = 0,031 sec; position test num = 156.0; prooduct item 07085; <65>
  406. 18.04 20:34:04 INFO [CommonLogger] busy = false
  407. 18.04 20:34:04 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"pos":2,"barcode":"3007085000007","item":"07085","name":"Пряник лимонный сладкий (Франц","price":986.63,"quant":1.0,"sum":986.63,"sumAll":1039.47,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:04.933","offline":false}]}
  408. 18.04 20:34:04 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  409. 18.04 20:34:04 INFO [TextCustomerDisplayPluginAbstract] **********************
  410. 18.04 20:34:04 INFO [TextCustomerDisplayPluginAbstract] *Пряник лимо 1 986.63*
  411. 18.04 20:34:04 INFO [TextCustomerDisplayPluginAbstract] *Итого 1039.47*
  412. 18.04 20:34:04 INFO [TextCustomerDisplayPluginAbstract] **********************
  413. 18.04 20:34:04 INFO [TextCustomerDisplayPluginAbstract]
  414. 18.04 20:34:04 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 07085; type: ProductPieceEntity; count: 1000; sum: 98663; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  415. 18.04 20:34:04 INFO [SpeedLog] TP addPosition time = 20 ms
  416. 18.04 20:34:05 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  417. 18.04 20:34:05 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  418. 18.04 20:34:05 INFO [TextCustomerDisplayPluginAbstract] **********************
  419. 18.04 20:34:05 INFO [TextCustomerDisplayPluginAbstract] *Пряник лимо 1 986.63*
  420. 18.04 20:34:05 INFO [TextCustomerDisplayPluginAbstract] *Итого 1039.47*
  421. 18.04 20:34:05 INFO [TextCustomerDisplayPluginAbstract] **********************
  422. 18.04 20:34:05 INFO [TextCustomerDisplayPluginAbstract]
  423. 18.04 20:34:05 INFO [CommonLogger] BarCode::1642732420123
  424. 18.04 20:34:05 INFO [Scale] BarcodeScannerImpl - barcode = 1642732420123
  425. 18.04 20:34:05 INFO [TechProcessImpl] eventBarcodeScanner 1642732420123
  426. 18.04 20:34:05 INFO [CheckContainer] Barcode 1642732420123 has been received by visualisation
  427. 18.04 20:34:05 INFO [TechProcessImpl] TP searchProduct 1642732420123
  428. 18.04 20:34:05 INFO [ServicesImpl] getGoodsByBarCode barcode = 1642732420123
  429. 18.04 20:34:05 INFO [ServicesImpl] get product by barcode '1642732420123' result: null
  430. 18.04 20:34:05 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 1642732420123 time = 1 ms
  431. 18.04 20:34:05 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 1642732420123 time = 1 ms
  432. 18.04 20:34:05 INFO [ServicesImpl] Get product by item '1642732420123' result null
  433. 18.04 20:34:05 INFO [SpeedLog] searchProduct time time = 2 ms
  434. 18.04 20:34:05 TRACE [MLServiceImpl] entering isEnabled()
  435. 18.04 20:34:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  436. 18.04 20:34:05 TRACE [MLServiceImpl] entering isMlCard(String, boolean). The argument are: "1642732420123"
  437. 18.04 20:34:05 TRACE [MLServiceImpl] leaving isMlCard(String, boolean). The result is: false
  438. 18.04 20:34:05 DEBUG [ExampleCardPlugin] entering getCardInfo(CardInfoSearchParams). The arguments are: request [ru.crystals.pos.cards.service.PluginCardSearchRequest@84a0c3]
  439. 18.04 20:34:05 DEBUG [ExampleCardPlugin] leaving getCardInfo(CardInfoSearchParams). The card [1642732420123] does not exist in the processing for sure
  440. 18.04 20:34:05 WARN [SqlExceptionHelper] SQL Error: 0, SQLState: 42703
  441. 18.04 20:34:05 ERROR [SqlExceptionHelper] ERROR: column cardaction0_.reason does not exist
  442. Позиция: 301
  443. 18.04 20:34:05 ERROR [CashCardFinder] An error while searching card by number '1642732420123':
  444. org.hibernate.exception.SQLGrammarException: could not extract ResultSet
  445. at org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:123)
  446. at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:49)
  447. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:126)
  448. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:112)
  449. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:91)
  450. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.getResultSet(AbstractLoadPlanBasedLoader.java:449)
  451. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeQueryStatement(AbstractLoadPlanBasedLoader.java:202)
  452. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:137)
  453. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:102)
  454. at org.hibernate.loader.collection.plan.AbstractLoadPlanBasedCollectionInitializer.initialize(AbstractLoadPlanBasedCollectionInitializer.java:100)
  455. at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:693)
  456. at org.hibernate.event.internal.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:92)
  457. at org.hibernate.internal.SessionImpl.initializeCollection(SessionImpl.java:1933)
  458. at org.hibernate.collection.internal.AbstractPersistentCollection$4.doWork(AbstractPersistentCollection.java:558)
  459. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  460. at org.hibernate.collection.internal.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:554)
  461. at org.hibernate.collection.internal.AbstractPersistentCollection.read(AbstractPersistentCollection.java:142)
  462. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:171)
  463. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:156)
  464. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  465. at org.hibernate.collection.internal.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:155)
  466. at org.hibernate.collection.internal.PersistentBag.isEmpty(PersistentBag.java:283)
  467. at ru.crystals.pos.cards.service.search.CashCardFinder.prepareCard(CashCardFinder.java:264)
  468. at ru.crystals.pos.cards.service.search.CashCardFinder.getCardType(CashCardFinder.java:120)
  469. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeLocal(CardsServiceImpl.java:535)
  470. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeProcessing(CardsServiceImpl.java:576)
  471. at ru.crystals.pos.cards.service.CardsServiceImpl.getNativeCardEntity(CardsServiceImpl.java:497)
  472. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeEntity(CardsServiceImpl.java:477)
  473. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardType(CardsServiceImpl.java:881)
  474. at ru.crystals.pos.techprocess.TechProcessSearch.lambda$getCardType$0(TechProcessSearch.java:376)
  475. at ru.crystals.pos.techprocess.TechProcessSearch.tryGetCardTypeNotFromCardRange(TechProcessSearch.java:394)
  476. at ru.crystals.pos.techprocess.TechProcessSearch.getCardType(TechProcessSearch.java:376)
  477. at ru.crystals.pos.techprocess.TechProcessSearch.searchCard(TechProcessSearch.java:111)
  478. at ru.crystals.pos.techprocess.TechProcessImpl.searchCard(TechProcessImpl.java:3724)
  479. at ru.crystals.pos.visualization.search.SearchProductContainer.searchCouponAndDiscountCard(SearchProductContainer.java:271)
  480. at ru.crystals.pos.visualization.search.SearchProductContainer.search(SearchProductContainer.java:468)
  481. at ru.crystals.pos.visualization.check.CheckContainer.addPositionByBarcode(CheckContainer.java:2096)
  482. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:2028)
  483. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:1982)
  484. at ru.crystals.pos.visualization.components.MainWindow.barcode(MainWindow.java:1147)
  485. at ru.crystals.pos.visualization.Factory.eventBarcode(Factory.java:741)
  486. at ru.crystals.pos.techprocess.TechProcessImpl.eventBarcodeScanner(TechProcessImpl.java:3814)
  487. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:307)
  488. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:119)
  489. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:100)
  490. at ru.crystals.pos.InternalCashPoolExecutorImpl$4.run(InternalCashPoolExecutorImpl.java:125)
  491. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  492. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  493. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  494. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  495. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  496. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  497. at java.lang.Thread.run(Thread.java:745)
  498. Caused by: org.postgresql.util.PSQLException: ERROR: column cardaction0_.reason does not exist
  499. Позиция: 301
  500. at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2270)
  501. at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1998)
  502. at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
  503. at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:570)
  504. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:420)
  505. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeQuery(AbstractJdbc2Statement.java:305)
  506. at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76)
  507. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:82)
  508. ... 48 more
  509. 18.04 20:34:05 WARN [CardsServiceImpl] CashCardsApplication: no balance received: 1642732420123
  510. 18.04 20:34:05 ERROR [CheckServiceImpl] leaving saveClientRequisites(InternalCardsEntity). No one card is detected!
  511. 18.04 20:34:05 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":35,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1039.47,"cardType":"","cardNumber":"1642732420123","discount":0.0,"discountAll":1000.0,"dateTime":"18.04.2018 20:34:05.657","offline":false}]}
  512. 18.04 20:34:07 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  513. 18.04 20:34:07 INFO [CommonLogger] busy = true
  514. 18.04 20:34:07 TRACE [TechProcessImpl] Start warn divide alco
  515. 18.04 20:34:07 TRACE [TechProcessImpl] End warn devide alco
  516. 18.04 20:34:07 INFO [TechProcessImpl] goToDiscounts
  517. 18.04 20:34:07 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  518. 18.04 20:34:07 INFO [LoyalProductFinder] Time finding items (2) = 28 ms
  519. 18.04 20:34:07 INFO [LoyalProductsConverter] Time searching loyal goods for 2 productItems = 28 ms
  520. 18.04 20:34:07 INFO [LoyalCalculatorConverter] Time converting 2 positions = 0 ms
  521. 18.04 20:34:07 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  522. 18.04 20:34:07 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  523. 18.04 20:34:07 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  524. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  525. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  526. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  527. 18.04 20:34:07 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  528. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  529. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  530. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  531. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  532. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  533. 18.04 20:34:07 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  534. 18.04 20:34:07 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  535. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  536. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  537. 18.04 20:34:07 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  538. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  539. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  540. 18.04 20:34:07 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  541. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  542. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  543. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  544. 18.04 20:34:07 DEBUG [TechProcessImpl] Server online mode
  545. 18.04 20:34:07 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  546. 18.04 20:34:07 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  547. 18.04 20:34:07 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 2
  548. 18.04 20:34:07 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  549. 18.04 20:34:07 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 116 ms
  550. 18.04 20:34:07 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  551. 18.04 20:34:07 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":52,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":935.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":103.94,"dateTime":"18.04.2018 20:34:07.860","offline":false}]}
  552. 18.04 20:34:07 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  553. 18.04 20:34:07 INFO [TextCustomerDisplayPluginAbstract] **********************
  554. 18.04 20:34:07 INFO [TextCustomerDisplayPluginAbstract] *Скидка 103.94*
  555. 18.04 20:34:07 INFO [TextCustomerDisplayPluginAbstract] *Итого 935.53*
  556. 18.04 20:34:07 INFO [TextCustomerDisplayPluginAbstract] **********************
  557. 18.04 20:34:07 INFO [TextCustomerDisplayPluginAbstract]
  558. 18.04 20:34:07 INFO [SpeedLog] TechProcess discounts processing on cheque with 2 positions [sale] time = 170 ms
  559. 18.04 20:34:07 INFO [CommonLogger] value = 10394
  560. 18.04 20:34:07 INFO [CommonLogger] busy = false
  561. 18.04 20:34:08 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  562. 18.04 20:34:08 INFO [TextCustomerDisplayPluginAbstract] **********************
  563. 18.04 20:34:08 INFO [TextCustomerDisplayPluginAbstract] * *
  564. 18.04 20:34:08 INFO [TextCustomerDisplayPluginAbstract] * *
  565. 18.04 20:34:08 INFO [TextCustomerDisplayPluginAbstract] **********************
  566. 18.04 20:34:08 INFO [TextCustomerDisplayPluginAbstract]
  567. 18.04 20:34:09 INFO [TransferManager] OD found 2 documents
  568. 18.04 20:34:09 INFO [TransferManager] response message = Created
  569. 18.04 20:34:09 INFO [TransferManager] Send document status : 621 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203409_265_1666_61.ser
  570. 18.04 20:34:09 INFO [CheckServiceImpl] SetSentToServerStatus : 621 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203409_265_1666_61.ser
  571. 18.04 20:34:09 INFO [TransferManager] document 61/Document_20180418203409_265_1666_61.ser has been registreated
  572. 18.04 20:34:09 INFO [TransferManager] Send document status : 621 > SENT file name : 61/Document_20180418203409_265_1666_61.ser
  573. 18.04 20:34:09 INFO [CheckServiceImpl] SetSentToServerStatus : 621 > SENT file name : 61/Document_20180418203409_265_1666_61.ser
  574. 18.04 20:34:09 INFO [TransferManager] response message = Created
  575. 18.04 20:34:09 INFO [TransferManager] Send document status : 622 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203409_298_1666_61.ser
  576. 18.04 20:34:09 INFO [CheckServiceImpl] SetSentToServerStatus : 622 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203409_298_1666_61.ser
  577. 18.04 20:34:09 INFO [TransferManager] document 61/Document_20180418203409_298_1666_61.ser has been registreated
  578. 18.04 20:34:09 INFO [TransferManager] Send document status : 622 > SENT file name : 61/Document_20180418203409_298_1666_61.ser
  579. 18.04 20:34:09 INFO [CheckServiceImpl] SetSentToServerStatus : 622 > SENT file name : 61/Document_20180418203409_298_1666_61.ser
  580. 18.04 20:34:09 INFO [TransferManager] OD found 0 payment transactions
  581. 18.04 20:34:09 INFO [TransferManager] OD found 0 transactions to register
  582. 18.04 20:34:09 INFO [TransferManager] OD found 0 documents to register
  583. 18.04 20:34:10 INFO [FilesChecker] Catalog goods transport event
  584. 18.04 20:34:10 INFO [FilesChecker] goods types for report count: 0
  585. 18.04 20:34:10 INFO [FilesChecker] catalog goods files to cash count: 0
  586. 18.04 20:34:10 INFO [TransferManager] ping = true
  587. 18.04 20:34:16 INFO [TransferManager] Nothing yet not processed on server to resend
  588. 18.04 20:34:19 INFO [FiscalPrinter] resetException()
  589. 18.04 20:34:20 INFO [FilesChecker] Catalog goods transport event
  590. 18.04 20:34:20 INFO [FilesChecker] goods types for report count: 0
  591. 18.04 20:34:20 INFO [FilesChecker] catalog goods files to cash count: 0
  592. 18.04 20:34:24 ERROR [TechProcessImpl] TP error:
  593. 18.04 20:34:25 INFO [CommonLogger] busy = true
  594. 18.04 20:34:25 TRACE [TechProcessImpl] Start warn divide amount
  595. 18.04 20:34:25 INFO [TechProcessImpl] goToPayments
  596. 18.04 20:34:25 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  597. 18.04 20:34:25 INFO [TextCustomerDisplayPluginAbstract] **********************
  598. 18.04 20:34:25 INFO [TextCustomerDisplayPluginAbstract] *Скидка 103.94*
  599. 18.04 20:34:25 INFO [TextCustomerDisplayPluginAbstract] *Итого 935.53*
  600. 18.04 20:34:25 INFO [TextCustomerDisplayPluginAbstract] **********************
  601. 18.04 20:34:25 INFO [TextCustomerDisplayPluginAbstract]
  602. 18.04 20:34:25 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":52,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":935.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":103.94,"dateTime":"18.04.2018 20:34:25.446","offline":false}]}
  603. 18.04 20:34:25 INFO [CheckContainer] startPayments() surcharge = 93553
  604. 18.04 20:34:25 INFO [CheckContainer] isPaymentComplete = false
  605. 18.04 20:34:25 INFO [CheckContainer] prevState = ADD_POSITION
  606. 18.04 20:34:25 INFO [CheckContainer] paid = 0
  607. 18.04 20:34:25 INFO [CheckContainer] surcharge = 93553
  608. 18.04 20:34:25 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  609. 18.04 20:34:25 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  610. 18.04 20:34:25 INFO [CommonLogger] busy = false
  611. 18.04 20:34:25 INFO [SpeedLog] (SUBTOTAL) time = 17,777;
  612. 18.04 20:34:25 INFO [CommonLogger] busy = false
  613. 18.04 20:34:25 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  614. 18.04 20:34:25 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  615. 18.04 20:34:26 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  616. 18.04 20:34:26 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  617. 18.04 20:34:26 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  618. 18.04 20:34:26 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=82, command=kbdMenu3]
  619. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  620. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  621. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]) one!
  622. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  623. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]] from further processing 'cause it has no chance to trigger anyway!
  624. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [1] BONUS_SR10-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]) one!
  625. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  626. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [1] BONUS_INFORMIX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]) one!
  627. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  628. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [0] BONUS_LOYMAX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]) one!
  629. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  630. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  631. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]) one!
  632. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  633. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]] from further processing 'cause it has no chance to trigger anyway!
  634. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [1] BONUS_SR10-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]) one!
  635. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  636. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [1] BONUS_INFORMIX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]) one!
  637. 18.04 20:34:26 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  638. 18.04 20:34:26 WARN [AdvertisingActionUtils] superfluous [0] BONUS_LOYMAX-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]) one!
  639. 18.04 20:34:26 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  640. 18.04 20:34:27 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  641. 18.04 20:34:27 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  642. 18.04 20:34:27 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  643. 18.04 20:34:28 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  644. 18.04 20:34:28 INFO [CommonLogger] Menu command: Аннулирование (class ru.crystals.pos.visualization.menu.commands.CancelCheckCommand)
  645. 18.04 20:34:28 INFO [TransferManager] Nothing yet not processed on server to resend
  646. 18.04 20:34:28 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=37, command=kbdLeft]
  647. 18.04 20:34:28 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  648. 18.04 20:34:28 INFO [TechProcessShiftProxy] cancelCheck start
  649. 18.04 20:34:28 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
  650. 18.04 20:34:28 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
  651. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  652. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  653. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  654. 18.04 20:34:28 INFO [TechProcessShiftProxy] cancelCheck PurchaseEntity [id=623, number=null, dateCreate=Wed Apr 18 20:34:04 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  655. 18.04 20:34:28 INFO [WaitComponent] [WaitComponent] MainFrame enabled - false
  656. 18.04 20:34:28 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  657. 18.04 20:34:28 INFO [TextCustomerDisplayPluginAbstract] **********************
  658. 18.04 20:34:28 INFO [TextCustomerDisplayPluginAbstract] *Аннулирование чека *
  659. 18.04 20:34:28 INFO [TextCustomerDisplayPluginAbstract] *Сумма чека 935.53*
  660. 18.04 20:34:28 INFO [TextCustomerDisplayPluginAbstract] **********************
  661. 18.04 20:34:28 INFO [TextCustomerDisplayPluginAbstract]
  662. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] Purchase cancelled: no implementation of PlastekServices.
  663. 18.04 20:34:28 TRACE [MLServiceImpl] entering getMlCardNo(PurchaseEntity). The argument is: PurchaseEntity [id=623, number=85, dateCreate=Wed Apr 18 20:34:04 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  664. 18.04 20:34:28 TRACE [MLServiceImpl] leaving getMlCardNo(PurchaseEntity). The result is: null
  665. 18.04 20:34:28 INFO [FiscalPrinter] printCheck
  666. 18.04 20:34:28 INFO [FiscalPrinter] Fiscal interrupt: null
  667. 18.04 20:34:28 INFO [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale-annul.xml
  668. 18.04 20:34:28 INFO [DocumentTemplate] =====logo=====
  669. 18.04 20:34:28 INFO [DocumentTemplate] content: null
  670. 18.04 20:34:28 INFO [DocumentTemplate] =====header=====
  671. 18.04 20:34:28 INFO [DocumentTemplate] jr. name ||NORMAL
  672. 18.04 20:34:28 INFO [DocumentTemplate] 199100, Spb, Savushkina, 112 ||NORMAL
  673. 18.04 20:34:28 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  674. 18.04 20:34:28 INFO [DocumentTemplate] Смена:9 ЭКЛЗ:c0c09cd9-882||NORMAL
  675. 18.04 20:34:28 INFO [DocumentTemplate] Номер кассы:61 ИНН: 7802781104||NORMAL
  676. 18.04 20:34:28 INFO [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  677. 18.04 20:34:28 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  678. 18.04 20:34:28 INFO [DocumentTemplate] КАССОВЫЙ ЧЕК 85 18-04-2018 20:34||NORMAL
  679. 18.04 20:34:28 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  680. 18.04 20:34:28 INFO [DocumentTemplate] =====position=====
  681. 18.04 20:34:28 INFO [DocumentTemplate] Лимонад вишневый аромат 47.56* 1 =47.56||SMALL
  682. 18.04 20:34:28 INFO [DocumentTemplate] Пряник лимонный сладкий 887.97* 1 =887.97||SMALL
  683. 18.04 20:34:28 INFO [DocumentTemplate] =====fiscal=====
  684. 18.04 20:34:28 INFO [DocumentTemplate] content: null
  685. 18.04 20:34:28 INFO [DocumentTemplate] =====footer=====
  686. 18.04 20:34:28 INFO [DocumentTemplate] ******************************************** ||NORMAL
  687. 18.04 20:34:28 INFO [DocumentTemplate] ЧЕК No. 85 АННУЛИРОВАН||DOUBLEHEIGHT
  688. 18.04 20:34:28 INFO [DocumentTemplate] ******************************************** ||NORMAL
  689. 18.04 20:34:28 INFO [DocumentTemplate] =====cut=====
  690. 18.04 20:34:28 INFO [DocumentTemplate] content: null
  691. 18.04 20:34:28 INFO [FiscalPrinter] getCountAnnul
  692. 18.04 20:34:28 INFO [FiscalPrinter] CountAnnul = 25
  693. 18.04 20:34:28 INFO [FiscalPrinter] getLastDocNum
  694. 18.04 20:34:28 INFO [CheckServiceImpl] cancelCheck: PurchaseEntity [id=623, number=85, dateCreate=Wed Apr 18 20:34:04 MSK 2018, dateCommit=Wed Apr 18 20:34:28 MSK 2018, fiscalDocNum=25;163, sentToServerStatus=NO_SENT]
  695. 18.04 20:34:28 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
  696. 18.04 20:34:28 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
  697. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  698. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  699. 18.04 20:34:28 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  700. 18.04 20:34:28 INFO [CommonLogger] value = 0
  701. 18.04 20:34:28 INFO [TechProcessImpl] Select seller [null]
  702. 18.04 20:34:28 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":25,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":85,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":935.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:28.849","offline":false}]}
  703. 18.04 20:34:28 INFO [SpeedLog] TechProcess cancelCheck time = 188 ms
  704. 18.04 20:34:28 INFO [WaitComponent] [WaitComponent] MainFrame enabled - true
  705. 18.04 20:34:28 INFO [TechProcessImpl] Select seller [null]
  706. 18.04 20:34:28 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  707. 18.04 20:34:29 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  708. 18.04 20:34:29 INFO [TextCustomerDisplayPluginAbstract] **********************
  709. 18.04 20:34:29 INFO [TextCustomerDisplayPluginAbstract] *Добро *
  710. 18.04 20:34:29 INFO [TextCustomerDisplayPluginAbstract] *пожаловать *
  711. 18.04 20:34:29 INFO [TextCustomerDisplayPluginAbstract] **********************
  712. 18.04 20:34:29 INFO [TextCustomerDisplayPluginAbstract]
  713. 18.04 20:34:29 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  714. 18.04 20:34:29 INFO [FiscalPrinter] resetException()
  715. 18.04 20:34:30 INFO [FilesChecker] Catalog goods transport event
  716. 18.04 20:34:30 INFO [FilesChecker] goods types for report count: 0
  717. 18.04 20:34:30 INFO [FilesChecker] catalog goods files to cash count: 0
  718. 18.04 20:34:31 INFO [CommonLogger] BarCode::5004982000008
  719. 18.04 20:34:31 INFO [Scale] BarcodeScannerImpl - barcode = 5004982000008
  720. 18.04 20:34:31 INFO [TechProcessImpl] eventBarcodeScanner 5004982000008
  721. 18.04 20:34:31 INFO [CheckContainer] Barcode 5004982000008 has been received by visualisation
  722. 18.04 20:34:31 INFO [TechProcessImpl] TP searchProduct 5004982000008
  723. 18.04 20:34:31 INFO [ServicesImpl] getGoodsByBarCode barcode = 5004982000008
  724. 18.04 20:34:31 INFO [ServicesImpl] get product by barcode '5004982000008' result: ProductPieceEntity[name=Йогурт лимонный горький (Великобритания), item=04982]
  725. 18.04 20:34:31 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5004982000008 time = 3 ms
  726. 18.04 20:34:31 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 5004982000008 time = 3 ms
  727. 18.04 20:34:31 INFO [SpeedLog] getProductRestrictions time = 0 ms
  728. 18.04 20:34:31 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 5004982000008 time = 3 ms
  729. 18.04 20:34:31 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5004982000008 time = 3 ms
  730. 18.04 20:34:31 INFO [SpeedLog] searchProduct time time = 4 ms
  731. 18.04 20:34:31 INFO [SpeedLog] (CHECK_CONTAINER) show product container time = 0,010 sec; average speed = 0,031 sec; position test num = 157.0; prooduct item 04982; <10>
  732. 18.04 20:34:31 INFO [CommonLogger] busy = false
  733. 18.04 20:34:31 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":4,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"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":"18.04.2018 20:34:31.335","offline":false}]}
  734. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  735. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] **********************
  736. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] *Йогурт лимо 1 558.80*
  737. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] *Итого 558.80*
  738. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] **********************
  739. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract]
  740. 18.04 20:34:31 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"pos":1,"barcode":"5004982000008","item":"04982","name":"Йогурт лимонный горький (Велик","price":558.8,"quant":1.0,"sum":558.8,"sumAll":558.8,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:31.335","offline":false}]}
  741. 18.04 20:34:31 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 04982; type: ProductPieceEntity; count: 4000; sum: 223520; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  742. 18.04 20:34:31 INFO [SpeedLog] TP addPosition time = 17 ms
  743. 18.04 20:34:31 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":12,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"pos":-1,"barcode":"5004982000008","item":"04982","name":"Йогурт лимонный горький (Велик","price":558.8,"quant":4.0,"sum":2235.2,"sumAll":2235.2,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:31.693","offline":false}]}
  744. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  745. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] **********************
  746. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] *Йогурт лим 4 2235.20*
  747. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] *Итого 2235.20*
  748. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract] **********************
  749. 18.04 20:34:31 INFO [TextCustomerDisplayPluginAbstract]
  750. 18.04 20:34:31 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  751. 18.04 20:34:32 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  752. 18.04 20:34:32 INFO [CommonLogger] busy = true
  753. 18.04 20:34:32 TRACE [TechProcessImpl] Start warn divide alco
  754. 18.04 20:34:32 TRACE [TechProcessImpl] End warn devide alco
  755. 18.04 20:34:32 INFO [TechProcessImpl] goToDiscounts
  756. 18.04 20:34:32 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  757. 18.04 20:34:32 INFO [LoyalProductFinder] Time finding items (1) = 3 ms
  758. 18.04 20:34:32 INFO [LoyalProductsConverter] Time searching loyal goods for 1 productItems = 4 ms
  759. 18.04 20:34:32 INFO [LoyalCalculatorConverter] Time converting 1 positions = 0 ms
  760. 18.04 20:34:32 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  761. 18.04 20:34:32 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  762. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  763. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  764. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  765. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  766. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  767. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  768. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  769. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  770. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  771. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  772. 18.04 20:34:32 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  773. 18.04 20:34:32 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  774. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  775. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  776. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  777. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  778. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  779. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  780. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  781. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  782. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  783. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  784. 18.04 20:34:32 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  785. 18.04 20:34:32 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 1
  786. 18.04 20:34:32 INFO [AdvResultPersistenceManager] createLoyTx: either the receipt [Purchase:[number=null;dateCreate=2018-04-18 20:34:31.674;operationType=true;checkSum=223520;discount=0]] does not have discounts, or has corresponding loy-tx in the DB already
  787. 18.04 20:34:32 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  788. 18.04 20:34:32 INFO [AdvResultPersistenceManager] createLoyTx: either the receipt [Purchase:[number=null;dateCreate=2018-04-18 20:34:31.674;operationType=true;checkSum=223520;discount=0]] does not have discounts, or has corresponding loy-tx in the DB already
  789. 18.04 20:34:32 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 35 ms
  790. 18.04 20:34:32 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  791. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  792. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] **********************
  793. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  794. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] *Итого 2235.20*
  795. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] **********************
  796. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract]
  797. 18.04 20:34:32 INFO [SpeedLog] TechProcess discounts processing on cheque with 1 positions [sale] time = 46 ms
  798. 18.04 20:34:32 INFO [CommonLogger] value = 0
  799. 18.04 20:34:32 TRACE [TechProcessImpl] Start warn divide amount
  800. 18.04 20:34:32 INFO [TechProcessImpl] goToPayments
  801. 18.04 20:34:32 INFO [CheckContainer] startPayments() surcharge = 223520
  802. 18.04 20:34:32 INFO [CheckContainer] isPaymentComplete = false
  803. 18.04 20:34:32 INFO [CheckContainer] prevState = ADD_POSITION
  804. 18.04 20:34:32 INFO [CheckContainer] paid = 0
  805. 18.04 20:34:32 INFO [CheckContainer] surcharge = 223520
  806. 18.04 20:34:32 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  807. 18.04 20:34:32 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  808. 18.04 20:34:32 INFO [CommonLogger] busy = false
  809. 18.04 20:34:32 INFO [SpeedLog] (SUBTOTAL) time = 0,097;
  810. 18.04 20:34:32 INFO [CommonLogger] busy = false
  811. 18.04 20:34:32 INFO [CommonLogger] BarCode::9678478205008
  812. 18.04 20:34:32 INFO [Scale] BarcodeScannerImpl - barcode = 9678478205008
  813. 18.04 20:34:32 INFO [TechProcessImpl] eventBarcodeScanner 9678478205008
  814. 18.04 20:34:32 INFO [CheckContainer] Barcode 9678478205008 has been received by visualisation
  815. 18.04 20:34:32 TRACE [MLServiceImpl] entering isEnabled()
  816. 18.04 20:34:32 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  817. 18.04 20:34:32 TRACE [MLServiceImpl] entering isMlCard(String, boolean). The argument are: "9678478205008"
  818. 18.04 20:34:32 TRACE [MLServiceImpl] leaving isMlCard(String, boolean). The result is: false
  819. 18.04 20:34:32 DEBUG [ExampleCardPlugin] entering getCardInfo(CardInfoSearchParams). The arguments are: request [ru.crystals.pos.cards.service.PluginCardSearchRequest@1b1f777]
  820. 18.04 20:34:32 DEBUG [ExampleCardPlugin] leaving getCardInfo(CardInfoSearchParams). The card [9678478205008] does not exist in the processing for sure
  821. 18.04 20:34:32 WARN [SqlExceptionHelper] SQL Error: 0, SQLState: 42703
  822. 18.04 20:34:32 ERROR [SqlExceptionHelper] ERROR: column cardaction0_.reason does not exist
  823. Позиция: 301
  824. 18.04 20:34:32 ERROR [CashCardFinder] An error while searching card by number '9678478205008':
  825. org.hibernate.exception.SQLGrammarException: could not extract ResultSet
  826. at org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:123)
  827. at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:49)
  828. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:126)
  829. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:112)
  830. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:91)
  831. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.getResultSet(AbstractLoadPlanBasedLoader.java:449)
  832. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeQueryStatement(AbstractLoadPlanBasedLoader.java:202)
  833. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:137)
  834. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:102)
  835. at org.hibernate.loader.collection.plan.AbstractLoadPlanBasedCollectionInitializer.initialize(AbstractLoadPlanBasedCollectionInitializer.java:100)
  836. at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:693)
  837. at org.hibernate.event.internal.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:92)
  838. at org.hibernate.internal.SessionImpl.initializeCollection(SessionImpl.java:1933)
  839. at org.hibernate.collection.internal.AbstractPersistentCollection$4.doWork(AbstractPersistentCollection.java:558)
  840. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  841. at org.hibernate.collection.internal.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:554)
  842. at org.hibernate.collection.internal.AbstractPersistentCollection.read(AbstractPersistentCollection.java:142)
  843. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:171)
  844. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:156)
  845. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  846. at org.hibernate.collection.internal.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:155)
  847. at org.hibernate.collection.internal.PersistentBag.isEmpty(PersistentBag.java:283)
  848. at ru.crystals.pos.cards.service.search.CashCardFinder.prepareCard(CashCardFinder.java:264)
  849. at ru.crystals.pos.cards.service.search.CashCardFinder.getCardType(CashCardFinder.java:120)
  850. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeLocal(CardsServiceImpl.java:535)
  851. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeProcessing(CardsServiceImpl.java:576)
  852. at ru.crystals.pos.cards.service.CardsServiceImpl.getNativeCardEntity(CardsServiceImpl.java:497)
  853. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeEntity(CardsServiceImpl.java:477)
  854. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardType(CardsServiceImpl.java:881)
  855. at ru.crystals.pos.techprocess.TechProcessSearch.lambda$getCardType$0(TechProcessSearch.java:376)
  856. at ru.crystals.pos.techprocess.TechProcessSearch.tryGetCardTypeNotFromCardRange(TechProcessSearch.java:394)
  857. at ru.crystals.pos.techprocess.TechProcessSearch.getCardType(TechProcessSearch.java:376)
  858. at ru.crystals.pos.techprocess.TechProcessSearch.searchCard(TechProcessSearch.java:111)
  859. at ru.crystals.pos.techprocess.TechProcessImpl.searchCard(TechProcessImpl.java:3724)
  860. at ru.crystals.pos.visualization.check.CheckContainer.searchCardByBarcode(CheckContainer.java:1894)
  861. at ru.crystals.pos.visualization.check.CheckContainer.barcodeInAddPayment(CheckContainer.java:2200)
  862. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:2014)
  863. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:1982)
  864. at ru.crystals.pos.visualization.components.MainWindow.barcode(MainWindow.java:1147)
  865. at ru.crystals.pos.visualization.Factory.eventBarcode(Factory.java:741)
  866. at ru.crystals.pos.techprocess.TechProcessImpl.eventBarcodeScanner(TechProcessImpl.java:3814)
  867. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:307)
  868. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:119)
  869. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:100)
  870. at ru.crystals.pos.InternalCashPoolExecutorImpl$4.run(InternalCashPoolExecutorImpl.java:125)
  871. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  872. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  873. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  874. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  875. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  876. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  877. at java.lang.Thread.run(Thread.java:745)
  878. Caused by: org.postgresql.util.PSQLException: ERROR: column cardaction0_.reason does not exist
  879. Позиция: 301
  880. at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2270)
  881. at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1998)
  882. at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
  883. at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:570)
  884. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:420)
  885. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeQuery(AbstractJdbc2Statement.java:305)
  886. at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76)
  887. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:82)
  888. ... 47 more
  889. 18.04 20:34:32 WARN [CardsServiceImpl] CashCardsApplication: no balance received: 9678478205008
  890. 18.04 20:34:32 ERROR [CheckServiceImpl] leaving saveClientRequisites(InternalCardsEntity). No one card is detected!
  891. 18.04 20:34:32 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":35,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":2235.2,"cardType":"","cardNumber":"9678478205008","discount":0.0,"discountAll":1000.0,"dateTime":"18.04.2018 20:34:32.549","offline":false}]}
  892. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  893. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] **********************
  894. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  895. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] *Итого 2235.20*
  896. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] **********************
  897. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract]
  898. 18.04 20:34:32 INFO [CommonLogger] busy = true
  899. 18.04 20:34:32 INFO [TechProcessImpl] goToWaitGoods
  900. 18.04 20:34:32 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
  901. 18.04 20:34:32 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
  902. 18.04 20:34:32 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  903. 18.04 20:34:32 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  904. 18.04 20:34:32 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  905. 18.04 20:34:32 INFO [TechProcessImpl] goToPayments
  906. 18.04 20:34:32 INFO [CommonLogger] value = 0
  907. 18.04 20:34:32 INFO [CommonLogger] busy = false
  908. 18.04 20:34:32 INFO [CommonLogger] busy = true
  909. 18.04 20:34:32 TRACE [TechProcessImpl] Start warn divide alco
  910. 18.04 20:34:32 TRACE [TechProcessImpl] End warn devide alco
  911. 18.04 20:34:32 INFO [TechProcessImpl] goToDiscounts
  912. 18.04 20:34:32 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  913. 18.04 20:34:32 INFO [LoyalProductFinder] Time finding items (1) = 3 ms
  914. 18.04 20:34:32 INFO [LoyalProductsConverter] Time searching loyal goods for 1 productItems = 3 ms
  915. 18.04 20:34:32 INFO [LoyalCalculatorConverter] Time converting 1 positions = 0 ms
  916. 18.04 20:34:32 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  917. 18.04 20:34:32 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  918. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  919. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  920. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  921. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  922. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  923. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  924. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  925. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  926. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  927. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  928. 18.04 20:34:32 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  929. 18.04 20:34:32 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  930. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  931. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  932. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  933. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  934. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  935. 18.04 20:34:32 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  936. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  937. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  938. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  939. 18.04 20:34:32 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  940. 18.04 20:34:32 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  941. 18.04 20:34:32 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 1
  942. 18.04 20:34:32 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the loy-tx [id: -1] was not detected (in the cache)! Null will be returned!
  943. 18.04 20:34:32 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 215 ms
  944. 18.04 20:34:32 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  945. 18.04 20:34:32 INFO [SpeedLog] TechProcess discounts processing on cheque with 1 positions [sale] time = 236 ms
  946. 18.04 20:34:32 INFO [CommonLogger] value = 0
  947. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  948. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] **********************
  949. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  950. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] *Итого 2235.20*
  951. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract] **********************
  952. 18.04 20:34:32 INFO [TextCustomerDisplayPluginAbstract]
  953. 18.04 20:34:32 TRACE [TechProcessImpl] Start warn divide amount
  954. 18.04 20:34:32 INFO [TechProcessImpl] goToPayments
  955. 18.04 20:34:32 INFO [CheckContainer] startPayments() surcharge = 223520
  956. 18.04 20:34:32 INFO [CheckContainer] isPaymentComplete = false
  957. 18.04 20:34:32 INFO [CheckContainer] prevState = ADD_POSITION
  958. 18.04 20:34:32 INFO [CheckContainer] paid = 0
  959. 18.04 20:34:32 INFO [CheckContainer] surcharge = 223520
  960. 18.04 20:34:32 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  961. 18.04 20:34:32 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  962. 18.04 20:34:32 INFO [CommonLogger] busy = false
  963. 18.04 20:34:32 INFO [SpeedLog] (SUBTOTAL) time = 0,283;
  964. 18.04 20:34:32 INFO [CommonLogger] busy = false
  965. 18.04 20:34:33 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  966. 18.04 20:34:33 INFO [TextCustomerDisplayPluginAbstract] **********************
  967. 18.04 20:34:33 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  968. 18.04 20:34:33 INFO [TextCustomerDisplayPluginAbstract] *Итого 2235.20*
  969. 18.04 20:34:33 INFO [TextCustomerDisplayPluginAbstract] **********************
  970. 18.04 20:34:33 INFO [TextCustomerDisplayPluginAbstract]
  971. 18.04 20:34:34 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=50, character=2]
  972. 18.04 20:34:34 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=50, character=2]
  973. 18.04 20:34:35 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=51, character=3]
  974. 18.04 20:34:35 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=53, character=5]
  975. 18.04 20:34:35 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=110, character=,]
  976. 18.04 20:34:35 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=50, character=2]
  977. 18.04 20:34:35 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=48, character=0]
  978. 18.04 20:34:36 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  979. 18.04 20:34:36 INFO [TechProcessImpl] addPayment CashPaymentEntity 223520
  980. 18.04 20:34:36 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":37,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":2235.2,"sumAll":2235.2,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:36.047","offline":false}]}
  981. 18.04 20:34:36 TRACE [TechProcessEventsImpl] entering purchaseChangedAtPaymentsStage(PurchaseEntity, List). The arguments are: purchase [PurchaseEntity [id=624, number=null, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]], notifications [[]]
  982. 18.04 20:34:36 INFO [CommonLogger] value = 0
  983. 18.04 20:34:36 INFO [TechProcessImpl] goToPayments
  984. 18.04 20:34:36 INFO [CheckContainer] startPayments() surcharge = 0
  985. 18.04 20:34:36 INFO [CheckContainer] isPaymentComplete = true
  986. 18.04 20:34:36 INFO [CheckContainer] prevState = ADD_POSITION
  987. 18.04 20:34:36 INFO [CheckContainer] paid = 223520
  988. 18.04 20:34:36 INFO [CheckContainer] surcharge = 0
  989. 18.04 20:34:36 INFO [CheckContainer] getPaymentFiscalizeError = BEFORE_FISCALIZE
  990. 18.04 20:34:36 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  991. 18.04 20:34:36 INFO [CheckContainer] ADD_PAYMENT : setPayment = 0 - getPositionsListContainer().getSurcharge() = 0
  992. 18.04 20:34:36 INFO [CheckContainer] isPaymentComplete = true
  993. 18.04 20:34:36 INFO [CheckContainer] prevState = ADD_POSITION
  994. 18.04 20:34:36 INFO [CheckContainer] paid = 223520
  995. 18.04 20:34:36 INFO [CheckContainer] surcharge = 0
  996. 18.04 20:34:36 INFO [CheckContainer] getPaymentFiscalizeError = BEFORE_FISCALIZE
  997. 18.04 20:34:36 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  998. 18.04 20:34:36 INFO [CheckContainer] ADD_PAYMENT : setPayment = 0 - getPositionsListContainer().getSurcharge() = 0
  999. 18.04 20:34:36 TRACE [TechProcessEventsImpl] leaving purchaseChangedAtPaymentsStage(PurchaseEntity, List). It took 67 [ms]
  1000. 18.04 20:34:36 WARN [PrismaBridgeImpl] Can't send [add payment complete] prisma event, position or change is empty
  1001. 18.04 20:34:36 INFO [TechProcessShift] fiscalizeCheck PurchaseEntity [id=624, number=null, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  1002. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1003. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] **********************
  1004. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] *Оплачено 2235.20*
  1005. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] *Cдача 0.00*
  1006. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] **********************
  1007. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract]
  1008. 18.04 20:34:36 INFO [FiscalPrinter] getLastKpk
  1009. 18.04 20:34:36 INFO [FiscalPrinter] LastKpk = 78
  1010. 18.04 20:34:36 INFO [FiscalPrinter] printCheck
  1011. 18.04 20:34:36 INFO [FiscalPrinter] getLastKpk
  1012. 18.04 20:34:36 INFO [FiscalPrinter] LastKpk = 78
  1013. 18.04 20:34:36 INFO [FiscalPrinter] Fiscal interrupt: null
  1014. 18.04 20:34:36 INFO [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale.xml
  1015. 18.04 20:34:36 INFO [DocumentTemplate] =====logo=====
  1016. 18.04 20:34:36 INFO [DocumentTemplate] content: null
  1017. 18.04 20:34:36 INFO [DocumentTemplate] =====header=====
  1018. 18.04 20:34:36 INFO [DocumentTemplate] jr. name ||NORMAL
  1019. 18.04 20:34:36 INFO [DocumentTemplate] 199100, Spb, Savushkina, 112 ||NORMAL
  1020. 18.04 20:34:36 INFO [DocumentTemplate] ----------------------------------------- ||NORMAL
  1021. 18.04 20:34:36 INFO [DocumentTemplate] Смена:9 ЭКЛЗ:c0c09cd9-882||NORMAL
  1022. 18.04 20:34:36 INFO [DocumentTemplate] Номер кассы:61 ИНН: 7802781104||NORMAL
  1023. 18.04 20:34:36 INFO [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  1024. 18.04 20:34:36 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1025. 18.04 20:34:36 INFO [DocumentTemplate] ЧЕК ПРОДАЖИ 86 18-04-2018 20:34||NORMAL
  1026. 18.04 20:34:36 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1027. 18.04 20:34:36 INFO [DocumentTemplate] =====position=====
  1028. 18.04 20:34:36 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1029. 18.04 20:34:36 INFO [DocumentTemplate] Йогурт лимонный горьк 558.80 *4 =2235.20||SMALL
  1030. 18.04 20:34:36 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1031. 18.04 20:34:36 INFO [DocumentTemplate] =====payment=====
  1032. 18.04 20:34:36 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1033. 18.04 20:34:36 INFO [DocumentTemplate] НАЛИЧНЫЕ =2235.20||NORMAL
  1034. 18.04 20:34:36 INFO [DocumentTemplate] =====discount=====
  1035. 18.04 20:34:36 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1036. 18.04 20:34:36 INFO [DocumentTemplate] ||NORMAL
  1037. 18.04 20:34:36 INFO [DocumentTemplate] СПАСИБО ЗА ПОКУПКУ! ||NORMAL
  1038. 18.04 20:34:36 INFO [DocumentTemplate] ||NORMAL
  1039. 18.04 20:34:36 INFO [DocumentTemplate] Цены ||NORMAL
  1040. 18.04 20:34:36 INFO [DocumentTemplate] указаны с учетом скидки по карте постоянного ||NORMAL
  1041. 18.04 20:34:36 INFO [DocumentTemplate] покупателя ||NORMAL
  1042. 18.04 20:34:36 INFO [DocumentTemplate] 9678478205008 ||NORMAL
  1043. 18.04 20:34:36 INFO [DocumentTemplate] ||NORMAL
  1044. 18.04 20:34:36 INFO [DocumentTemplate] =====tax=====
  1045. 18.04 20:34:36 INFO [DocumentTemplate] content: null
  1046. 18.04 20:34:36 INFO [DocumentTemplate] =====total=====
  1047. 18.04 20:34:36 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1048. 18.04 20:34:36 INFO [DocumentTemplate] Итого без скидки 2235.20 ||NORMAL
  1049. 18.04 20:34:36 INFO [DocumentTemplate] Скидка 0.00 ||NORMAL
  1050. 18.04 20:34:36 INFO [DocumentTemplate] Итого к оплате 2235.20 ||NORMAL
  1051. 18.04 20:34:36 INFO [DocumentTemplate] СДАЧА =0.00||NORMAL
  1052. 18.04 20:34:36 INFO [DocumentTemplate] =====bonuses=====
  1053. 18.04 20:34:36 INFO [DocumentTemplate] =====fiscal=====
  1054. 18.04 20:34:36 INFO [DocumentTemplate] content: null
  1055. 18.04 20:34:36 INFO [DocumentTemplate] =====footer=====
  1056. 18.04 20:34:36 INFO [DocumentTemplate] Док N:86 18-04-2018 20:34 ||NORMAL
  1057. 18.04 20:34:36 INFO [DocumentTemplate] Спасибо за покупку! ||NORMAL
  1058. 18.04 20:34:36 INFO [DocumentTemplate] =====cut=====
  1059. 18.04 20:34:36 INFO [DocumentTemplate] content: null
  1060. 18.04 20:34:36 INFO [FiscalPrinter] getLastKpk
  1061. 18.04 20:34:36 INFO [FiscalPrinter] LastKpk = 79
  1062. 18.04 20:34:36 INFO [FiscalPrinter] getLastDocNum
  1063. 18.04 20:34:36 INFO [CheckServiceImpl] fiscalizeCheck: PurchaseEntity [id=624, number=86, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=Wed Apr 18 20:34:36 MSK 2018, fiscalDocNum=79;164, sentToServerStatus=UNCOMMITED]
  1064. 18.04 20:34:36 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":39,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":2235.2,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:36.484","offline":false}]}
  1065. 18.04 20:34:36 TRACE [ExternalProcessingsImpl] entering purchaseFiscalized(PurchaseEntity)
  1066. 18.04 20:34:36 INFO [CFTBridgeImpl] Bonuses can not be charged to the card. Purchase does not contain Sberbank cards. Purchase: PurchaseEntity [id=624, number=86, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=Wed Apr 18 20:34:36 MSK 2018, fiscalDocNum=79;164, sentToServerStatus=UNCOMMITED]
  1067. 18.04 20:34:36 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of PlastekServices.
  1068. 18.04 20:34:36 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of externalLoyaltyService.
  1069. 18.04 20:34:36 TRACE [ExternalProcessingsImpl] about to notifty ext-loy-providers that the current receipt [PurchaseEntity [id=624, number=86, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=Wed Apr 18 20:34:36 MSK 2018, fiscalDocNum=79;164, sentToServerStatus=UNCOMMITED]] was fiscalized...
  1070. 18.04 20:34:36 TRACE [ExternalProcessingsImpl] leaving purchaseFiscalized(PurchaseEntity). It took 75 [ms]
  1071. 18.04 20:34:36 INFO [FiscalPrinter] printServiceDocument
  1072. 18.04 20:34:36 INFO [SpeedLog] leaving useUniqueCoupons(Collection<UniqueCouponExemplarEntity>) time = 0 ms
  1073. 18.04 20:34:36 INFO [DocumentTemplate] =====null=====
  1074. 18.04 20:34:36 INFO [DocumentTemplate] Вам выдано 14 наклеек ||NORMAL
  1075. 18.04 20:34:36 INFO [AbstractFiscalPrinterEmulator] printServiceDocument
  1076. 18.04 20:34:36 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":5,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":86,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":2235.2,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:36.623","offline":false}]}
  1077. 18.04 20:34:36 INFO [TechProcessShift] Check = PurchaseEntity [id=624, number=86, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=Wed Apr 18 20:34:36 MSK 2018, fiscalDocNum=79;164, sentToServerStatus=NO_SENT]
  1078. checkNum = 0
  1079. checkModule.getCurrentPurchaseEntry().getCurrentState() = REG_CHECK
  1080. 18.04 20:34:36 TRACE [ExternalProcessingsImpl] afterFiscalize: no implementation of externalLoyaltyService
  1081. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1082. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] **********************
  1083. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] *Вам выдано 14 наклее*
  1084. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] * *
  1085. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract] **********************
  1086. 18.04 20:34:36 INFO [TextCustomerDisplayPluginAbstract]
  1087. 18.04 20:34:36 INFO [SpeedLog] TechProcess fiscalizeCheck operationType = true, positions count = 1 time = 653 ms
  1088. 18.04 20:34:36 INFO [FiscalPrinter] getPrintedDocumentWithOffset() invoked
  1089. 18.04 20:34:36 INFO [FiscalPrinter] FilePrinter.getLastPrinted() invoked
  1090. 18.04 20:34:36 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1091. 18.04 20:34:36 INFO [TechProcessImpl] saveCheck PurchaseEntity [id=624, number=86, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=Wed Apr 18 20:34:36 MSK 2018, fiscalDocNum=79;164, sentToServerStatus=NO_SENT]
  1092. 18.04 20:34:36 INFO [CheckServiceImpl] saveCheck: PurchaseEntity [id=624, number=86, dateCreate=Wed Apr 18 20:34:31 MSK 2018, dateCommit=Wed Apr 18 20:34:36 MSK 2018, fiscalDocNum=79;164, sentToServerStatus=NO_SENT]
  1093. 18.04 20:34:36 INFO [TechProcessImpl] Select seller [null]
  1094. 18.04 20:34:36 INFO [FiscalPrinter] resetException()
  1095. 18.04 20:34:37 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1096. 18.04 20:34:37 INFO [TextCustomerDisplayPluginAbstract] **********************
  1097. 18.04 20:34:37 INFO [TextCustomerDisplayPluginAbstract] *Добро *
  1098. 18.04 20:34:37 INFO [TextCustomerDisplayPluginAbstract] *пожаловать *
  1099. 18.04 20:34:37 INFO [TextCustomerDisplayPluginAbstract] **********************
  1100. 18.04 20:34:37 INFO [TextCustomerDisplayPluginAbstract]
  1101. 18.04 20:34:37 DEBUG [TechProcessImpl] Server online mode
  1102. 18.04 20:34:37 INFO [FiscalPrinter] resetException()
  1103. 18.04 20:34:39 INFO [CommonLogger] Starting cleaning. All log records older than 18 января 2018 20:34:39 will be deleted.
  1104. 18.04 20:34:39 INFO [CommonLogger] Cleaning done.
  1105. 18.04 20:34:39 INFO [CommonLogger] BarCode::5409196000002
  1106. 18.04 20:34:39 INFO [Scale] BarcodeScannerImpl - barcode = 5409196000002
  1107. 18.04 20:34:39 INFO [TechProcessImpl] eventBarcodeScanner 5409196000002
  1108. 18.04 20:34:39 INFO [CheckContainer] Barcode 5409196000002 has been received by visualisation
  1109. 18.04 20:34:39 INFO [TechProcessImpl] TP searchProduct 5409196000002
  1110. 18.04 20:34:39 INFO [ServicesImpl] getGoodsByBarCode barcode = 5409196000002
  1111. 18.04 20:34:39 INFO [ServicesImpl] get product by barcode '5409196000002' result: ProductPieceEntity[name=Напиток вишневый кислый (Бельгия), item=09196]
  1112. 18.04 20:34:39 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5409196000002 time = 0 ms
  1113. 18.04 20:34:39 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 5409196000002 time = 0 ms
  1114. 18.04 20:34:39 INFO [SpeedLog] getProductRestrictions time = 0 ms
  1115. 18.04 20:34:39 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 5409196000002 time = 1 ms
  1116. 18.04 20:34:39 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5409196000002 time = 1 ms
  1117. 18.04 20:34:39 INFO [SpeedLog] searchProduct time time = 2 ms
  1118. 18.04 20:34:39 INFO [SpeedLog] (CHECK_CONTAINER) show product container time = 0,015 sec; average speed = 0,031 sec; position test num = 158.0; prooduct item 09196; <15>
  1119. 18.04 20:34:39 INFO [CommonLogger] busy = false
  1120. 18.04 20:34:39 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":4,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"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":"18.04.2018 20:34:39.567","offline":false}]}
  1121. 18.04 20:34:39 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1122. 18.04 20:34:39 INFO [TextCustomerDisplayPluginAbstract] **********************
  1123. 18.04 20:34:39 INFO [TextCustomerDisplayPluginAbstract] *Напиток ви 1 1261.06*
  1124. 18.04 20:34:39 INFO [TextCustomerDisplayPluginAbstract] *Итого 1261.06*
  1125. 18.04 20:34:39 INFO [TextCustomerDisplayPluginAbstract] **********************
  1126. 18.04 20:34:39 INFO [TextCustomerDisplayPluginAbstract]
  1127. 18.04 20:34:39 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":1,"barcode":"5409196000002","item":"09196","name":"Напиток вишневый кислый (Бельг","price":1261.06,"quant":1.0,"sum":1261.06,"sumAll":1261.06,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:39.567","offline":false}]}
  1128. 18.04 20:34:40 INFO [TransferManager] Nothing yet not processed on server to resend
  1129. 18.04 20:34:40 INFO [FilesChecker] Catalog goods transport event
  1130. 18.04 20:34:40 INFO [FilesChecker] goods types for report count: 0
  1131. 18.04 20:34:40 INFO [FilesChecker] catalog goods files to cash count: 0
  1132. 18.04 20:34:41 INFO [TransferManager] Message [userLogOut] has been sent
  1133. 18.04 20:34:41 INFO [CommonLogger] BarCode::4903363000006
  1134. 18.04 20:34:41 INFO [Scale] BarcodeScannerImpl - barcode = 4903363000006
  1135. 18.04 20:34:41 INFO [TechProcessImpl] eventBarcodeScanner 4903363000006
  1136. 18.04 20:34:41 INFO [CheckContainer] Barcode 4903363000006 has been received by visualisation
  1137. 18.04 20:34:41 INFO [CheckContainer] All conditions are checked. Current position will be added
  1138. 18.04 20:34:41 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 09196; type: ProductPieceEntity; count: 1000; sum: 126106; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  1139. 18.04 20:34:41 INFO [SpeedLog] TP addPosition time = 18 ms
  1140. 18.04 20:34:41 INFO [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,035 sec; average speed = 0,032 sec; position test num = 71; <35>
  1141. 18.04 20:34:41 INFO [CheckContainer] visualization will start searching product
  1142. 18.04 20:34:41 INFO [TechProcessImpl] TP searchProduct 4903363000006
  1143. 18.04 20:34:41 INFO [ServicesImpl] getGoodsByBarCode barcode = 4903363000006
  1144. 18.04 20:34:41 INFO [ServicesImpl] get product by barcode '4903363000006' result: ProductPieceEntity[name=Лимонад грушевый терпкий (Япония), item=03363]
  1145. 18.04 20:34:41 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 4903363000006 time = 1 ms
  1146. 18.04 20:34:41 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 4903363000006 time = 4 ms
  1147. 18.04 20:34:41 INFO [SpeedLog] getProductRestrictions time = 1 ms
  1148. 18.04 20:34:41 INFO [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 4903363000006 time = 5 ms
  1149. 18.04 20:34:41 INFO [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 4903363000006 time = 5 ms
  1150. 18.04 20:34:41 INFO [SpeedLog] searchProduct time time = 5 ms
  1151. 18.04 20:34:41 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":67,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":2,"barcode":"4903363000006","item":"03363","name":"Лимонад грушевый терпкий (Япон","price":438.42,"quant":1.0,"sum":438.42,"sumAll":1699.48,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:41.311","offline":false}]}
  1152. 18.04 20:34:41 INFO [SpeedLog] (CHECK_CONTAINER) show product container time = 0,032 sec; average speed = 0,031 sec; position test num = 159.0; prooduct item 03363; <32>
  1153. 18.04 20:34:41 INFO [CommonLogger] busy = false
  1154. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1155. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] **********************
  1156. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] *Лимонад гру 1 438.42*
  1157. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] *Итого 1699.48*
  1158. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] **********************
  1159. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract]
  1160. 18.04 20:34:41 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 03363; type: ProductPieceEntity; count: 1000; sum: 43842; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  1161. 18.04 20:34:41 INFO [SpeedLog] TP addPosition time = 11 ms
  1162. 18.04 20:34:41 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1163. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1164. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] **********************
  1165. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] *Лимонад гру 1 438.42*
  1166. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] *Итого 1699.48*
  1167. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] **********************
  1168. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract]
  1169. 18.04 20:34:41 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  1170. 18.04 20:34:41 INFO [CommonLogger] busy = true
  1171. 18.04 20:34:41 TRACE [TechProcessImpl] Start warn divide alco
  1172. 18.04 20:34:41 TRACE [TechProcessImpl] End warn devide alco
  1173. 18.04 20:34:41 INFO [TechProcessImpl] goToDiscounts
  1174. 18.04 20:34:41 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  1175. 18.04 20:34:41 INFO [LoyalProductFinder] Time finding items (2) = 17 ms
  1176. 18.04 20:34:41 INFO [LoyalProductsConverter] Time searching loyal goods for 2 productItems = 18 ms
  1177. 18.04 20:34:41 INFO [LoyalCalculatorConverter] Time converting 2 positions = 0 ms
  1178. 18.04 20:34:41 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1179. 18.04 20:34:41 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1180. 18.04 20:34:41 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  1181. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  1182. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  1183. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  1184. 18.04 20:34:41 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  1185. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  1186. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  1187. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  1188. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  1189. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  1190. 18.04 20:34:41 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1191. 18.04 20:34:41 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1192. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  1193. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  1194. 18.04 20:34:41 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  1195. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  1196. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  1197. 18.04 20:34:41 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  1198. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  1199. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  1200. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  1201. 18.04 20:34:41 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  1202. 18.04 20:34:41 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1203. 18.04 20:34:41 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 2
  1204. 18.04 20:34:41 INFO [AdvResultPersistenceManager] createLoyTx: either the receipt [Purchase:[number=null;dateCreate=2018-04-18 20:34:41.240;operationType=true;checkSum=169948;discount=0]] does not have discounts, or has corresponding loy-tx in the DB already
  1205. 18.04 20:34:41 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  1206. 18.04 20:34:41 INFO [AdvResultPersistenceManager] createLoyTx: either the receipt [Purchase:[number=null;dateCreate=2018-04-18 20:34:41.240;operationType=true;checkSum=169948;discount=0]] does not have discounts, or has corresponding loy-tx in the DB already
  1207. 18.04 20:34:41 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 70 ms
  1208. 18.04 20:34:41 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  1209. 18.04 20:34:41 INFO [SpeedLog] TechProcess discounts processing on cheque with 2 positions [sale] time = 103 ms
  1210. 18.04 20:34:41 INFO [CommonLogger] value = 0
  1211. 18.04 20:34:41 TRACE [TechProcessImpl] Start warn divide amount
  1212. 18.04 20:34:41 INFO [TechProcessImpl] goToPayments
  1213. 18.04 20:34:41 INFO [CheckContainer] startPayments() surcharge = 169948
  1214. 18.04 20:34:41 INFO [CheckContainer] isPaymentComplete = false
  1215. 18.04 20:34:41 INFO [CheckContainer] prevState = ADD_POSITION
  1216. 18.04 20:34:41 INFO [CheckContainer] paid = 0
  1217. 18.04 20:34:41 INFO [CheckContainer] surcharge = 169948
  1218. 18.04 20:34:41 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  1219. 18.04 20:34:41 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  1220. 18.04 20:34:41 INFO [CommonLogger] busy = false
  1221. 18.04 20:34:41 INFO [SpeedLog] (SUBTOTAL) time = 0,141;
  1222. 18.04 20:34:41 INFO [CommonLogger] busy = false
  1223. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1224. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] **********************
  1225. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  1226. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] *Итого 1699.48*
  1227. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract] **********************
  1228. 18.04 20:34:41 INFO [TextCustomerDisplayPluginAbstract]
  1229. 18.04 20:34:42 INFO [CommonLogger] BarCode::1642722420123
  1230. 18.04 20:34:42 INFO [Scale] BarcodeScannerImpl - barcode = 1642722420123
  1231. 18.04 20:34:42 INFO [TechProcessImpl] eventBarcodeScanner 1642722420123
  1232. 18.04 20:34:42 INFO [CheckContainer] Barcode 1642722420123 has been received by visualisation
  1233. 18.04 20:34:42 TRACE [MLServiceImpl] entering isEnabled()
  1234. 18.04 20:34:42 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  1235. 18.04 20:34:42 TRACE [MLServiceImpl] entering isMlCard(String, boolean). The argument are: "1642722420123"
  1236. 18.04 20:34:42 TRACE [MLServiceImpl] leaving isMlCard(String, boolean). The result is: false
  1237. 18.04 20:34:42 DEBUG [ExampleCardPlugin] entering getCardInfo(CardInfoSearchParams). The arguments are: request [ru.crystals.pos.cards.service.PluginCardSearchRequest@c58410]
  1238. 18.04 20:34:42 DEBUG [ExampleCardPlugin] leaving getCardInfo(CardInfoSearchParams). The card [1642722420123] does not exist in the processing for sure
  1239. 18.04 20:34:42 WARN [SqlExceptionHelper] SQL Error: 0, SQLState: 42703
  1240. 18.04 20:34:42 ERROR [SqlExceptionHelper] ERROR: column cardaction0_.reason does not exist
  1241. Позиция: 301
  1242. 18.04 20:34:42 ERROR [CashCardFinder] An error while searching card by number '1642722420123':
  1243. org.hibernate.exception.SQLGrammarException: could not extract ResultSet
  1244. at org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:123)
  1245. at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:49)
  1246. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:126)
  1247. at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:112)
  1248. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:91)
  1249. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.getResultSet(AbstractLoadPlanBasedLoader.java:449)
  1250. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeQueryStatement(AbstractLoadPlanBasedLoader.java:202)
  1251. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:137)
  1252. at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:102)
  1253. at org.hibernate.loader.collection.plan.AbstractLoadPlanBasedCollectionInitializer.initialize(AbstractLoadPlanBasedCollectionInitializer.java:100)
  1254. at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:693)
  1255. at org.hibernate.event.internal.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:92)
  1256. at org.hibernate.internal.SessionImpl.initializeCollection(SessionImpl.java:1933)
  1257. at org.hibernate.collection.internal.AbstractPersistentCollection$4.doWork(AbstractPersistentCollection.java:558)
  1258. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  1259. at org.hibernate.collection.internal.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:554)
  1260. at org.hibernate.collection.internal.AbstractPersistentCollection.read(AbstractPersistentCollection.java:142)
  1261. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:171)
  1262. at org.hibernate.collection.internal.AbstractPersistentCollection$1.doWork(AbstractPersistentCollection.java:156)
  1263. at org.hibernate.collection.internal.AbstractPersistentCollection.withTemporarySessionIfNeeded(AbstractPersistentCollection.java:260)
  1264. at org.hibernate.collection.internal.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:155)
  1265. at org.hibernate.collection.internal.PersistentBag.isEmpty(PersistentBag.java:283)
  1266. at ru.crystals.pos.cards.service.search.CashCardFinder.prepareCard(CashCardFinder.java:264)
  1267. at ru.crystals.pos.cards.service.search.CashCardFinder.getCardType(CashCardFinder.java:120)
  1268. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeLocal(CardsServiceImpl.java:535)
  1269. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeProcessing(CardsServiceImpl.java:576)
  1270. at ru.crystals.pos.cards.service.CardsServiceImpl.getNativeCardEntity(CardsServiceImpl.java:497)
  1271. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardTypeEntity(CardsServiceImpl.java:477)
  1272. at ru.crystals.pos.cards.service.CardsServiceImpl.getCardType(CardsServiceImpl.java:881)
  1273. at ru.crystals.pos.techprocess.TechProcessSearch.lambda$getCardType$0(TechProcessSearch.java:376)
  1274. at ru.crystals.pos.techprocess.TechProcessSearch.tryGetCardTypeNotFromCardRange(TechProcessSearch.java:394)
  1275. at ru.crystals.pos.techprocess.TechProcessSearch.getCardType(TechProcessSearch.java:376)
  1276. at ru.crystals.pos.techprocess.TechProcessSearch.searchCard(TechProcessSearch.java:111)
  1277. at ru.crystals.pos.techprocess.TechProcessImpl.searchCard(TechProcessImpl.java:3724)
  1278. at ru.crystals.pos.visualization.check.CheckContainer.searchCardByBarcode(CheckContainer.java:1894)
  1279. at ru.crystals.pos.visualization.check.CheckContainer.barcodeInAddPayment(CheckContainer.java:2200)
  1280. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:2014)
  1281. at ru.crystals.pos.visualization.check.CheckContainer.barcode(CheckContainer.java:1982)
  1282. at ru.crystals.pos.visualization.components.MainWindow.barcode(MainWindow.java:1147)
  1283. at ru.crystals.pos.visualization.Factory.eventBarcode(Factory.java:741)
  1284. at ru.crystals.pos.techprocess.TechProcessImpl.eventBarcodeScanner(TechProcessImpl.java:3814)
  1285. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:307)
  1286. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:119)
  1287. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:100)
  1288. at ru.crystals.pos.InternalCashPoolExecutorImpl$4.run(InternalCashPoolExecutorImpl.java:125)
  1289. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  1290. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  1291. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  1292. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  1293. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  1294. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  1295. at java.lang.Thread.run(Thread.java:745)
  1296. Caused by: org.postgresql.util.PSQLException: ERROR: column cardaction0_.reason does not exist
  1297. Позиция: 301
  1298. at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2270)
  1299. at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1998)
  1300. at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
  1301. at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:570)
  1302. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:420)
  1303. at org.postgresql.jdbc2.AbstractJdbc2Statement.executeQuery(AbstractJdbc2Statement.java:305)
  1304. at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76)
  1305. at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:82)
  1306. ... 47 more
  1307. 18.04 20:34:42 WARN [CardsServiceImpl] CashCardsApplication: no balance received: 1642722420123
  1308. 18.04 20:34:42 ERROR [CheckServiceImpl] leaving saveClientRequisites(InternalCardsEntity). No one card is detected!
  1309. 18.04 20:34:42 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":35,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1699.48,"cardType":"","cardNumber":"1642722420123","discount":0.0,"discountAll":1000.0,"dateTime":"18.04.2018 20:34:42.231","offline":false}]}
  1310. 18.04 20:34:42 INFO [CommonLogger] busy = true
  1311. 18.04 20:34:42 INFO [TechProcessImpl] goToWaitGoods
  1312. 18.04 20:34:42 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
  1313. 18.04 20:34:42 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
  1314. 18.04 20:34:42 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  1315. 18.04 20:34:42 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  1316. 18.04 20:34:42 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  1317. 18.04 20:34:42 INFO [TechProcessImpl] goToPayments
  1318. 18.04 20:34:42 INFO [CommonLogger] value = 0
  1319. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1320. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] **********************
  1321. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  1322. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] *Итого 1699.48*
  1323. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] **********************
  1324. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract]
  1325. 18.04 20:34:42 INFO [CommonLogger] busy = false
  1326. 18.04 20:34:42 INFO [CommonLogger] busy = true
  1327. 18.04 20:34:42 TRACE [TechProcessImpl] Start warn divide alco
  1328. 18.04 20:34:42 TRACE [TechProcessImpl] End warn devide alco
  1329. 18.04 20:34:42 INFO [TechProcessImpl] goToDiscounts
  1330. 18.04 20:34:42 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  1331. 18.04 20:34:42 INFO [LoyalProductFinder] Time finding items (2) = 2 ms
  1332. 18.04 20:34:42 INFO [LoyalProductsConverter] Time searching loyal goods for 2 productItems = 2 ms
  1333. 18.04 20:34:42 INFO [LoyalCalculatorConverter] Time converting 2 positions = 0 ms
  1334. 18.04 20:34:42 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1335. 18.04 20:34:42 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1336. 18.04 20:34:42 WARN [AdvertisingActionUtils] superfluous [1] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  1337. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  1338. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  1339. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  1340. 18.04 20:34:42 WARN [AdvertisingActionUtils] superfluous [2] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  1341. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  1342. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  1343. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  1344. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  1345. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  1346. 18.04 20:34:42 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1347. 18.04 20:34:42 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1348. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3399;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]] from further processing 'cause it has no chance to trigger anyway!
  1349. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2139;guid=422993744;name=РА_ЦФТ_исключение20;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1060.0]] from further processing 'cause it has no chance to trigger anyway!
  1350. 18.04 20:34:42 WARN [AdvertisingActionUtils] superfluous [0] BONUS_CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2734;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  1351. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3018;guid=58524;name=AQ-427: Списание бонусов как скидка 20% при покупке 15 товаров и прокатывании карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  1352. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2881;guid=58762;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1100.0]] from further processing 'cause it has no chance to trigger anyway!
  1353. 18.04 20:34:42 WARN [AdvertisingActionUtils] superfluous [0] CFT-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2800;guid=60037;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=1170.0]) one!
  1354. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2653;guid=60530;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1180.0]] from further processing 'cause it has no chance to trigger anyway!
  1355. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=3457;guid=60617;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1190.0]] from further processing 'cause it has no chance to trigger anyway!
  1356. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3294;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]] from further processing 'cause it has no chance to trigger anyway!
  1357. 18.04 20:34:42 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=2690;guid=-6683376;name=Lenta_cobranding_decrement-CR-1019;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1505.0]] from further processing 'cause it has no chance to trigger anyway!
  1358. 18.04 20:34:42 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1359. 18.04 20:34:42 INFO [LoyalServiceImpl] Discount calculation (Purchase #new; Positions: 2
  1360. 18.04 20:34:42 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the loy-tx [id: -1] was not detected (in the cache)! Null will be returned!
  1361. 18.04 20:34:42 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 91 ms
  1362. 18.04 20:34:42 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  1363. 18.04 20:34:42 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":52,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1529.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":169.95,"dateTime":"18.04.2018 20:34:42.448","offline":false}]}
  1364. 18.04 20:34:42 INFO [SpeedLog] TechProcess discounts processing on cheque with 2 positions [sale] time = 127 ms
  1365. 18.04 20:34:42 INFO [CommonLogger] value = 16995
  1366. 18.04 20:34:42 TRACE [TechProcessImpl] Start warn divide amount
  1367. 18.04 20:34:42 INFO [TechProcessImpl] goToPayments
  1368. 18.04 20:34:42 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":52,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1529.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":169.95,"dateTime":"18.04.2018 20:34:42.479","offline":false}]}
  1369. 18.04 20:34:42 INFO [CheckContainer] startPayments() surcharge = 152953
  1370. 18.04 20:34:42 INFO [CheckContainer] isPaymentComplete = false
  1371. 18.04 20:34:42 INFO [CheckContainer] prevState = ADD_POSITION
  1372. 18.04 20:34:42 INFO [CheckContainer] paid = 0
  1373. 18.04 20:34:42 INFO [CheckContainer] surcharge = 152953
  1374. 18.04 20:34:42 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  1375. 18.04 20:34:42 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  1376. 18.04 20:34:42 INFO [CommonLogger] busy = false
  1377. 18.04 20:34:42 INFO [SpeedLog] (SUBTOTAL) time = 0,167;
  1378. 18.04 20:34:42 INFO [CommonLogger] busy = false
  1379. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1380. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] **********************
  1381. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] *Скидка 169.95*
  1382. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] *Итого 1529.53*
  1383. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract] **********************
  1384. 18.04 20:34:42 INFO [TextCustomerDisplayPluginAbstract]
  1385. 18.04 20:34:44 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1386. 18.04 20:34:44 ERROR [TechProcessImpl] TP error: Cash payment plugin on enter(): sum is not available
  1387. 18.04 20:34:44 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=49, character=1]
  1388. 18.04 20:34:44 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=53, character=5]
  1389. 18.04 20:34:44 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=50, character=2]
  1390. 18.04 20:34:45 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=57, character=9]
  1391. 18.04 20:34:45 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=110, character=,]
  1392. 18.04 20:34:45 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=53, character=5]
  1393. 18.04 20:34:45 INFO [TechProcessImpl] eventAlphaNumericKey: AlphaNumericKey [scanCode=51, character=3]
  1394. 18.04 20:34:45 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1395. 18.04 20:34:45 INFO [TechProcessImpl] addPayment CashPaymentEntity 152953
  1396. 18.04 20:34:45 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":37,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":1529.53,"sumAll":1529.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:45.927","offline":false}]}
  1397. 18.04 20:34:45 TRACE [TechProcessEventsImpl] entering purchaseChangedAtPaymentsStage(PurchaseEntity, List). The arguments are: purchase [PurchaseEntity [id=625, number=null, dateCreate=Wed Apr 18 20:34:41 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]], notifications [[]]
  1398. 18.04 20:34:45 INFO [CommonLogger] value = 16995
  1399. 18.04 20:34:45 INFO [TechProcessImpl] goToPayments
  1400. 18.04 20:34:45 INFO [CheckContainer] startPayments() surcharge = 0
  1401. 18.04 20:34:45 INFO [CheckContainer] isPaymentComplete = true
  1402. 18.04 20:34:45 INFO [CheckContainer] prevState = ADD_POSITION
  1403. 18.04 20:34:45 INFO [CheckContainer] paid = 152953
  1404. 18.04 20:34:45 INFO [CheckContainer] surcharge = 0
  1405. 18.04 20:34:45 INFO [CheckContainer] getPaymentFiscalizeError = BEFORE_FISCALIZE
  1406. 18.04 20:34:45 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  1407. 18.04 20:34:45 INFO [CheckContainer] ADD_PAYMENT : setPayment = 0 - getPositionsListContainer().getSurcharge() = 0
  1408. 18.04 20:34:45 INFO [CheckContainer] isPaymentComplete = true
  1409. 18.04 20:34:45 INFO [CheckContainer] prevState = ADD_POSITION
  1410. 18.04 20:34:45 INFO [CheckContainer] paid = 152953
  1411. 18.04 20:34:45 INFO [CheckContainer] surcharge = 0
  1412. 18.04 20:34:45 INFO [CheckContainer] getPaymentFiscalizeError = BEFORE_FISCALIZE
  1413. 18.04 20:34:45 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  1414. 18.04 20:34:45 INFO [CheckContainer] ADD_PAYMENT : setPayment = 0 - getPositionsListContainer().getSurcharge() = 0
  1415. 18.04 20:34:45 TRACE [TechProcessEventsImpl] leaving purchaseChangedAtPaymentsStage(PurchaseEntity, List). It took 58 [ms]
  1416. 18.04 20:34:45 WARN [PrismaBridgeImpl] Can't send [add payment complete] prisma event, position or change is empty
  1417. 18.04 20:34:45 INFO [TechProcessShift] fiscalizeCheck PurchaseEntity [id=625, number=null, dateCreate=Wed Apr 18 20:34:41 MSK 2018, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  1418. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1419. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  1420. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] *Оплачено 1529.53*
  1421. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] *Cдача 0.00*
  1422. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  1423. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract]
  1424. 18.04 20:34:46 INFO [FiscalPrinter] getLastKpk
  1425. 18.04 20:34:46 INFO [FiscalPrinter] LastKpk = 79
  1426. 18.04 20:34:46 INFO [FiscalPrinter] printCheck
  1427. 18.04 20:34:46 INFO [FiscalPrinter] getLastKpk
  1428. 18.04 20:34:46 INFO [FiscalPrinter] LastKpk = 79
  1429. 18.04 20:34:46 INFO [FiscalPrinter] Fiscal interrupt: null
  1430. 18.04 20:34:46 INFO [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale.xml
  1431. 18.04 20:34:46 INFO [DocumentTemplate] =====logo=====
  1432. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1433. 18.04 20:34:46 INFO [DocumentTemplate] =====header=====
  1434. 18.04 20:34:46 INFO [DocumentTemplate] jr. name ||NORMAL
  1435. 18.04 20:34:46 INFO [DocumentTemplate] 199100, Spb, Savushkina, 112 ||NORMAL
  1436. 18.04 20:34:46 INFO [DocumentTemplate] ----------------------------------------- ||NORMAL
  1437. 18.04 20:34:46 INFO [DocumentTemplate] Смена:9 ЭКЛЗ:c0c09cd9-882||NORMAL
  1438. 18.04 20:34:46 INFO [DocumentTemplate] Номер кассы:61 ИНН: 7802781104||NORMAL
  1439. 18.04 20:34:46 INFO [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  1440. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1441. 18.04 20:34:46 INFO [DocumentTemplate] ЧЕК ПРОДАЖИ 87 18-04-2018 20:34||NORMAL
  1442. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1443. 18.04 20:34:46 INFO [DocumentTemplate] =====position=====
  1444. 18.04 20:34:46 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1445. 18.04 20:34:46 INFO [DocumentTemplate] Напиток вишневый кисл 1134.9 *1 =1134.95||SMALL
  1446. 18.04 20:34:46 INFO [DocumentTemplate] Лимонад грушевый терп 394.58 *1 =394.58||SMALL
  1447. 18.04 20:34:46 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1448. 18.04 20:34:46 INFO [DocumentTemplate] =====payment=====
  1449. 18.04 20:34:46 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1450. 18.04 20:34:46 INFO [DocumentTemplate] НАЛИЧНЫЕ =1529.53||NORMAL
  1451. 18.04 20:34:46 INFO [DocumentTemplate] =====discount=====
  1452. 18.04 20:34:46 INFO [DocumentTemplate] ----------------------------------------------||NORMAL
  1453. 18.04 20:34:46 INFO [DocumentTemplate] ВАША СКИДКА СОСТАВИЛА, руб =169.95||DOUBLEHEIGHT
  1454. 18.04 20:34:46 INFO [DocumentTemplate] ||NORMAL
  1455. 18.04 20:34:46 INFO [DocumentTemplate] СПАСИБО ЗА ПОКУПКУ! ||NORMAL
  1456. 18.04 20:34:46 INFO [DocumentTemplate] ||NORMAL
  1457. 18.04 20:34:46 INFO [DocumentTemplate] Цены ||NORMAL
  1458. 18.04 20:34:46 INFO [DocumentTemplate] указаны с учетом скидки по карте постоянного ||NORMAL
  1459. 18.04 20:34:46 INFO [DocumentTemplate] покупателя ||NORMAL
  1460. 18.04 20:34:46 INFO [DocumentTemplate] 1642722420123 ||NORMAL
  1461. 18.04 20:34:46 INFO [DocumentTemplate] ||NORMAL
  1462. 18.04 20:34:46 INFO [DocumentTemplate] =====tax=====
  1463. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1464. 18.04 20:34:46 INFO [DocumentTemplate] =====total=====
  1465. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1466. 18.04 20:34:46 INFO [DocumentTemplate] Итого без скидки 1699.48 ||NORMAL
  1467. 18.04 20:34:46 INFO [DocumentTemplate] Скидка 169.95 ||NORMAL
  1468. 18.04 20:34:46 INFO [DocumentTemplate] Итого к оплате 1529.53 ||NORMAL
  1469. 18.04 20:34:46 INFO [DocumentTemplate] СДАЧА =0.00||NORMAL
  1470. 18.04 20:34:46 INFO [DocumentTemplate] =====bonuses=====
  1471. 18.04 20:34:46 INFO [DocumentTemplate] =====fiscal=====
  1472. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1473. 18.04 20:34:46 INFO [DocumentTemplate] =====footer=====
  1474. 18.04 20:34:46 INFO [DocumentTemplate] Док N:87 18-04-2018 20:34 ||NORMAL
  1475. 18.04 20:34:46 INFO [DocumentTemplate] Спасибо за покупку! ||NORMAL
  1476. 18.04 20:34:46 INFO [DocumentTemplate] =====cut=====
  1477. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1478. 18.04 20:34:46 INFO [FiscalPrinter] getLastKpk
  1479. 18.04 20:34:46 INFO [FiscalPrinter] LastKpk = 80
  1480. 18.04 20:34:46 INFO [FiscalPrinter] getLastDocNum
  1481. 18.04 20:34:46 INFO [CheckServiceImpl] fiscalizeCheck: PurchaseEntity [id=625, number=87, dateCreate=Wed Apr 18 20:34:41 MSK 2018, dateCommit=Wed Apr 18 20:34:46 MSK 2018, fiscalDocNum=80;166, sentToServerStatus=UNCOMMITED]
  1482. 18.04 20:34:46 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":39,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1529.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:46.557","offline":false}]}
  1483. 18.04 20:34:46 TRACE [ExternalProcessingsImpl] entering purchaseFiscalized(PurchaseEntity)
  1484. 18.04 20:34:46 INFO [CFTBridgeImpl] Bonuses can not be charged to the card. Purchase does not contain Sberbank cards. Purchase: PurchaseEntity [id=625, number=87, dateCreate=Wed Apr 18 20:34:41 MSK 2018, dateCommit=Wed Apr 18 20:34:46 MSK 2018, fiscalDocNum=80;166, sentToServerStatus=UNCOMMITED]
  1485. 18.04 20:34:46 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of PlastekServices.
  1486. 18.04 20:34:46 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of externalLoyaltyService.
  1487. 18.04 20:34:46 TRACE [ExternalProcessingsImpl] about to notifty ext-loy-providers that the current receipt [PurchaseEntity [id=625, number=87, dateCreate=Wed Apr 18 20:34:41 MSK 2018, dateCommit=Wed Apr 18 20:34:46 MSK 2018, fiscalDocNum=80;166, sentToServerStatus=UNCOMMITED]] was fiscalized...
  1488. 18.04 20:34:46 TRACE [ExternalProcessingsImpl] leaving purchaseFiscalized(PurchaseEntity). It took 50 [ms]
  1489. 18.04 20:34:46 INFO [FiscalPrinter] printDiscountsReport
  1490. 18.04 20:34:46 INFO [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/discounts-report.xml
  1491. 18.04 20:34:46 INFO [DocumentTemplate] =====logo=====
  1492. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1493. 18.04 20:34:46 INFO [DocumentTemplate] =====header=====
  1494. 18.04 20:34:46 INFO [DocumentTemplate] ||NORMAL
  1495. 18.04 20:34:46 INFO [DocumentTemplate] ||NORMAL
  1496. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1497. 18.04 20:34:46 INFO [DocumentTemplate] Смена:9 ЭКЛЗ:c0c09cd9-882||NORMAL
  1498. 18.04 20:34:46 INFO [DocumentTemplate] Номер кассы:61 ИНН: 7802781104||NORMAL
  1499. 18.04 20:34:46 INFO [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  1500. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1501. 18.04 20:34:46 INFO [DocumentTemplate] ОТЧЕТ ПО СКИДКАМ 87 18-04-2018 20:34||NORMAL
  1502. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1503. 18.04 20:34:46 INFO [DocumentTemplate] =====groupByGoods=====
  1504. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1505. 18.04 20:34:46 INFO [DocumentTemplate] =====groupByAA=====
  1506. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1507. 18.04 20:34:46 INFO [DocumentTemplate] По акциям ||NORMAL
  1508. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1509. 18.04 20:34:46 INFO [DocumentTemplate] Сообщение кассиру после закрытия чека ||SMALL
  1510. 18.04 20:34:46 INFO [DocumentTemplate] Напиток вишневый кислый (Бель*1 =126.11||SMALL
  1511. 18.04 20:34:46 INFO [DocumentTemplate] Лимонад грушевый терпкий (Япо*1 =43.84||SMALL
  1512. 18.04 20:34:46 INFO [DocumentTemplate] =====checkDiscounts=====
  1513. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1514. 18.04 20:34:46 INFO [DocumentTemplate] =====total=====
  1515. 18.04 20:34:46 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  1516. 18.04 20:34:46 INFO [DocumentTemplate] ИТОГО СКИДКА НА ЧЕК =169.95||NORMAL
  1517. 18.04 20:34:46 INFO [DocumentTemplate] =====fiscal=====
  1518. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1519. 18.04 20:34:46 INFO [DocumentTemplate] =====footer=====
  1520. 18.04 20:34:46 INFO [DocumentTemplate] Док N:87 18-04-2018 20:34 ||NORMAL
  1521. 18.04 20:34:46 INFO [DocumentTemplate] Спасибо за покупку! ||NORMAL
  1522. 18.04 20:34:46 INFO [DocumentTemplate] =====cut=====
  1523. 18.04 20:34:46 INFO [DocumentTemplate] content: null
  1524. 18.04 20:34:46 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1666,"cash":61,"mode":5,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":87,"pos":0,"barcode":"","item":"","name":"","price":0.0,"quant":0.0,"sum":0.0,"sumAll":1529.53,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"18.04.2018 20:34:46.649","offline":false}]}
  1525. 18.04 20:34:46 INFO [TechProcessShift] Check = PurchaseEntity [id=625, number=87, dateCreate=Wed Apr 18 20:34:41 MSK 2018, dateCommit=Wed Apr 18 20:34:46 MSK 2018, fiscalDocNum=80;166, sentToServerStatus=NO_SENT]
  1526. checkNum = 0
  1527. checkModule.getCurrentPurchaseEntry().getCurrentState() = REG_CHECK
  1528. 18.04 20:34:46 TRACE [ExternalProcessingsImpl] afterFiscalize: no implementation of externalLoyaltyService
  1529. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  1530. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  1531. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] * *
  1532. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] * *
  1533. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract] **********************
  1534. 18.04 20:34:46 INFO [TextCustomerDisplayPluginAbstract]
  1535. 18.04 20:34:46 INFO [SpeedLog] TechProcess fiscalizeCheck operationType = true, positions count = 2 time = 779 ms
  1536. 18.04 20:34:46 INFO [SpeedLog] leaving useUniqueCoupons(Collection<UniqueCouponExemplarEntity>) time = 0 ms
  1537. 18.04 20:34:50 INFO [FilesChecker] Catalog goods transport event
  1538. 18.04 20:34:50 INFO [FilesChecker] goods types for report count: 0
  1539. 18.04 20:34:50 INFO [FilesChecker] catalog goods files to cash count: 0
  1540. 18.04 20:34:52 INFO [TransferManager] Nothing yet not processed on server to resend
  1541. 18.04 20:35:00 INFO [FilesChecker] Catalog goods transport event
  1542. 18.04 20:35:00 INFO [FilesChecker] goods types for report count: 0
  1543. 18.04 20:35:00 INFO [FilesChecker] catalog goods files to cash count: 0
  1544. 18.04 20:35:00 INFO [TransferManager] ping = true
  1545. 18.04 20:35:02 INFO [FiscalPrinter] resetException()
  1546. 18.04 20:35:02 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1547. 18.04 20:35:02 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1548. 18.04 20:35:02 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1549. 18.04 20:35:02 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  1550. 18.04 20:35:03 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  1551. 18.04 20:35:04 INFO [TransferManager] Nothing yet not processed on server to resend
  1552. 18.04 20:35:05 INFO [TransferManager] File 61/LoyTransaction_20180418203505_925_1666_61.ser sucessfully registered
  1553. 18.04 20:35:05 INFO [TransferManager] File 61/LoyTransaction_20180418203505_878_1666_61.ser sucessfully registered
  1554. 18.04 20:35:05 INFO [TransferManager] 2 transactions processed to upload; 2 transactions processed to register; Time: 74 ms.
  1555. 18.04 20:35:07 DEBUG [TechProcessImpl] Server online mode
  1556. 18.04 20:35:09 INFO [TransferManager] OD found 3 documents
  1557. 18.04 20:35:09 INFO [TransferManager] response message = Created
  1558. 18.04 20:35:09 INFO [TransferManager] Send document status : 623 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203509_978_1666_61.ser
  1559. 18.04 20:35:09 INFO [CheckServiceImpl] SetSentToServerStatus : 623 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203509_978_1666_61.ser
  1560. 18.04 20:35:09 INFO [TransferManager] document 61/Document_20180418203509_978_1666_61.ser has been registreated
  1561. 18.04 20:35:09 INFO [TransferManager] Send document status : 623 > SENT file name : 61/Document_20180418203509_978_1666_61.ser
  1562. 18.04 20:35:10 INFO [CheckServiceImpl] SetSentToServerStatus : 623 > SENT file name : 61/Document_20180418203509_978_1666_61.ser
  1563. 18.04 20:35:10 INFO [TransferManager] response message = Created
  1564. 18.04 20:35:10 INFO [TransferManager] Send document status : 624 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203510_9_1666_61.ser
  1565. 18.04 20:35:10 INFO [CheckServiceImpl] SetSentToServerStatus : 624 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203510_9_1666_61.ser
  1566. 18.04 20:35:10 INFO [TransferManager] document 61/Document_20180418203510_9_1666_61.ser has been registreated
  1567. 18.04 20:35:10 INFO [TransferManager] Send document status : 624 > SENT file name : 61/Document_20180418203510_9_1666_61.ser
  1568. 18.04 20:35:10 INFO [CheckServiceImpl] SetSentToServerStatus : 624 > SENT file name : 61/Document_20180418203510_9_1666_61.ser
  1569. 18.04 20:35:10 INFO [TransferManager] response message = Created
  1570. 18.04 20:35:10 INFO [TransferManager] Send document status : 625 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203510_37_1666_61.ser
  1571. 18.04 20:35:10 INFO [CheckServiceImpl] SetSentToServerStatus : 625 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20180418203510_37_1666_61.ser
  1572. 18.04 20:35:10 INFO [TransferManager] document 61/Document_20180418203510_37_1666_61.ser has been registreated
  1573. 18.04 20:35:10 INFO [TransferManager] Send document status : 625 > SENT file name : 61/Document_20180418203510_37_1666_61.ser
  1574. 18.04 20:35:10 INFO [CheckServiceImpl] SetSentToServerStatus : 625 > SENT file name : 61/Document_20180418203510_37_1666_61.ser
  1575. 18.04 20:35:10 INFO [TransferManager] OD found 0 payment transactions
  1576. 18.04 20:35:10 INFO [TransferManager] OD found 0 transactions to register
  1577. 18.04 20:35:10 INFO [TransferManager] OD found 0 documents to register
  1578. 18.04 20:35:10 INFO [FilesChecker] Catalog goods transport event
  1579. 18.04 20:35:10 INFO [FilesChecker] goods types for report count: 0
  1580. 18.04 20:35:10 INFO [FilesChecker] catalog goods files to cash count: 0
  1581. 18.04 20:35:16 INFO [TransferManager] Nothing yet not processed on server to resend
  1582. 18.04 20:35:19 INFO [FiscalPrinter] resetException()
  1583. 18.04 20:35:19 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1584. 18.04 20:35:19 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1585. 18.04 20:35:19 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1586. 18.04 20:35:20 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  1587. 18.04 20:35:20 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  1588. 18.04 20:35:20 INFO [FilesChecker] Catalog goods transport event
  1589. 18.04 20:35:20 INFO [FilesChecker] goods types for report count: 0
  1590. 18.04 20:35:20 INFO [FilesChecker] catalog goods files to cash count: 0
  1591. 18.04 20:35:28 INFO [TransferManager] Nothing yet not processed on server to resend
  1592. 18.04 20:35:30 INFO [FilesChecker] Catalog goods transport event
  1593. 18.04 20:35:30 INFO [FilesChecker] goods types for report count: 0
  1594. 18.04 20:35:30 INFO [FilesChecker] catalog goods files to cash count: 0
  1595. 18.04 20:35:35 INFO [FiscalPrinter] resetException()
  1596. 18.04 20:35:35 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1597. 18.04 20:35:35 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1598. 18.04 20:35:36 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  1599. 18.04 20:35:36 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  1600. 18.04 20:35:36 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  1601. 18.04 20:35:37 DEBUG [TechProcessImpl] Server online mode
  1602. 18.04 20:35:38 INFO [LoyProvidersRegistryImpl] Resend stale feedback
  1603. 18.04 20:35:38 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = set-loy-provider
  1604. 18.04 20:35:38 INFO [LoyProvidersRegistryImpl] There is no stale feedback for set-loy-provider
  1605. 18.04 20:35:39 INFO [CommonLogger] Starting cleaning. All log records older than 18 января 2018 20:35:39 will be deleted.
  1606. 18.04 20:35:39 INFO [CommonLogger] Cleaning done.
  1607. 18.04 20:35:40 INFO [TransferManager] Nothing yet not processed on server to resend
  1608. 18.04 20:35:40 INFO [FilesChecker] Catalog goods transport event
  1609. 18.04 20:35:40 INFO [FilesChecker] goods types for report count: 0
  1610. 18.04 20:35:40 INFO [FilesChecker] catalog goods files to cash count: 0
  1611. 18.04 20:35:50 INFO [FilesChecker] Catalog goods transport event
  1612. 18.04 20:35:50 INFO [FilesChecker] goods types for report count: 0
  1613. 18.04 20:35:50 INFO [FilesChecker] catalog goods files to cash count: 0
  1614. 18.04 20:35:50 INFO [TransferManager] ping = true
  1615. 18.04 20:35:52 INFO [TransferManager] Nothing yet not processed on server to
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement