API tools faq
paste
Advertisement
fedorm

Untitled

fedorm
Nov 1st, 2018
216
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
text 67.81 KB | None | 0 0
raw download clone embed print report
  1. 01.11 01:42:03 INFO [TechProcessImpl] TechProcess add position id: null; number: 1; item: 0000002102; type: ProductPieceEntity; count: 10000; sum: 50700; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  2. 01.11 01:42:03 INFO [SpeedLog] TP addPosition time = 13 ms
  3. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  4. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] **********************
  5. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] *Носки 2ого 10 507.00*
  6. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] *Итого 507.00*
  7. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] **********************
  8. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract]
  9. 01.11 01:42:03 INFO [PrismaBridgeImpl] {"events":[{"prefix":"KKM","shop":1789,"cash":61,"mode":12,"tabNumber":"1","cashier":"Администраторов А.А.","purchase":4,"pos":-1,"barcode":"0000002102 ","item":"0000002102","name":"Носки 2ого размера","price":50.7,"quant":10.0,"sum":507.0,"sumAll":507.0,"cardType":"","cardNumber":"","discount":0.0,"discountAll":0.0,"dateTime":"01.11.2018 01:42:03.280","offline":false}]}
  10. 01.11 01:42:03 DEBUG [TechProcessImpl] Server online mode
  11. 01.11 01:42:03 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  12. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  13. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] **********************
  14. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] *Носки 2ого 10 507.00*
  15. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] *Итого 507.00*
  16. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract] **********************
  17. 01.11 01:42:03 INFO [TextCustomerDisplayPluginAbstract]
  18. 01.11 01:42:03 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  19. 01.11 01:42:03 INFO [CommonLogger] busy = true
  20. 01.11 01:42:03 TRACE [TechProcessImpl] Start warn divide alco
  21. 01.11 01:42:03 TRACE [TechProcessImpl] End warn devide alco
  22. 01.11 01:42:03 INFO [TechProcessImpl] goToDiscounts
  23. 01.11 01:42:03 INFO [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  24. 01.11 01:42:03 INFO [LoyalProductFinder] Time finding items (1) = 2 ms
  25. 01.11 01:42:03 INFO [LoyalProductsConverter] Time searching loyal goods for 1 productItems = 2 ms
  26. 01.11 01:42:03 INFO [LoyalCalculatorConverter] Time converting 1 positions = 0 ms
  27. 01.11 01:42:04 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  28. 01.11 01:42:04 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  29. 01.11 01:42:04 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  30. 01.11 01:42:04 TRACE [LoyPluginEmulator] entering doDiscount(Receipt). The argument is: ru.crystals.api.commons.ReceiptPurchaseWrapper@493e4c
  31. 01.11 01:42:04 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  32. 01.11 01:42:04 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  33. 01.11 01:42:04 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, quiet=false, cardProcessingId='null'}; it took 0 [ms]
  34. 01.11 01:42:04 TRACE [LoyPluginEmulator] entering makeCalcCalls(List, EmulatorReceipt). The arguments are: urls: [http://127.0.0.1:10065/loy-plugin-processing/emulator/process], emulatorReceipt: emul-receipt [card: emul-card [no: "22520000"; available: 1000000; to-write-off: null]; positions: [emul-pos [no: 1; marking: "0000002102"; qnty: 10000; sum: 50700; min-sum: 0; discountable: true]]]
  35. 01.11 01:42:04 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  36. 01.11 01:42:04 TRACE [LoyPluginEmulator] leaving makeCalcCalls(List, EmulatorReceipt). The result is: emul-loy-result [discounts: []; limit: null; taken-into-account: null]; it took 539 [ms]
  37. 01.11 01:42:04 TRACE [LoyPluginEmulator] leaving doDiscount(Receipt). The result is: LoyaltyResult{discounts=null, bonusWriteOffsLimits=null, bonusesTakenIntoAccount=null, bonusAccrualResults=null, printDocuments=null, messagesToCashier=null, extendedAttributeMap=null}; it took 638 [ms]
  38. 01.11 01:42:04 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=4;dateCreate=2018-11-01 01:41:59.052;operationType=true;checkSum=50700;discount=0]
  39. 01.11 01:42:04 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The result is: null
  40. 01.11 01:42:04 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase:[number=4;dateCreate=2018-11-01 01:41:59.052;operationType=true;checkSum=50700;discount=0]
  41. 01.11 01:42:04 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The result is: null
  42. 01.11 01:42:04 TRACE [SCService] entering isEnabled()
  43. 01.11 01:42:04 TRACE [SCService] leaving isEnabled(). the result is: true
  44. 01.11 01:42:04 TRACE [SCService] entering calcDiscount(Purchase). The argument is: Purchase:[number=4;dateCreate=2018-11-01 01:41:59.052;operationType=true;checkSum=50700;discount=0]
  45. 01.11 01:42:04 TRACE [SCService] Send request <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  46. <ChequeRequest xmlns="http://smartcheckout.ru/xsd/s10ren.xsd" ChequeType="Soft">
  47. <RequestID>1541025719052</RequestID>
  48. <Card>
  49. <CardNumber>22520000</CardNumber>
  50. </Card>
  51. <DateTime>2018-11-01T01:42:04.685+03:00</DateTime>
  52. <POS>61</POS>
  53. <Number>1541025719052</Number>
  54. <Summ>507.00</Summ>
  55. <Discount>0.000</Discount>
  56. <SummDiscounted>507.00</SummDiscounted>
  57. <Item>
  58. <PositionNumber>1</PositionNumber>
  59. <Article>0000002102</Article>
  60. <ArticleName>Носки 2ого размера</ArticleName>
  61. <Price>50.70</Price>
  62. <Quantity>10.000</Quantity>
  63. <Summ>507.00</Summ>
  64. <Discount>0.000</Discount>
  65. <SummDiscounted>507.00</SummDiscounted>
  66. </Item>
  67. </ChequeRequest>
  68.  
  69. 01.11 01:42:04 TRACE [SCService] entering getServices()
  70. 01.11 01:42:04 TRACE [SCService] getting services (IN MEMORY)
  71. 01.11 01:42:04 TRACE [SCService] leaving getServices(). The result size is: 2
  72. 01.11 01:42:04 TRACE [SCService] Received response: <?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  73. <ChequeRequestResponce xmlns="http://smartcheckout.ru/xsd/s10ren.xsd">
  74. <RequestID>1541025719052</RequestID>
  75. <Processed>2018-11-01T01:42:04.697+03:00</Processed>
  76. <ReturnCode>0</ReturnCode>
  77. <CashierMessageTO>0</CashierMessageTO>
  78. <Summ>507.00</Summ>
  79. <Discount>0.000</Discount>
  80. <SummDiscounted>507.00</SummDiscounted>
  81. <Item>
  82. <PositionNumber>1</PositionNumber>
  83. <Article>0000002102</Article>
  84. <ArticleName>Носки 2ого размера</ArticleName>
  85. <Price>50.70</Price>
  86. <MinPrice>0</MinPrice>
  87. <Quantity>10.000</Quantity>
  88. <Summ>507.00</Summ>
  89. <Discount>0.000</Discount>
  90. <SummDiscounted>507.00</SummDiscounted>
  91. </Item>
  92. </ChequeRequestResponce>
  93.  
  94. 01.11 01:42:04 TRACE [SCReceiptConverter] ml discounts: {position-key [marking: 0000002102; original-price: 5070]=0}
  95. 01.11 01:42:04 TRACE [SCReceiptConverter] it was inferred that no SC POS discounts were given on position [key: position-key [marking: 0000002102; original-price: 5070]]: SC discount value is: 0
  96. 01.11 01:42:04 TRACE [SCService] leaving calcDiscount(Purchase). The result is: Purchase:[number=null;dateCreate=(NULL);operationType=null;checkSum=50700;discount=0]; it took 28 [ms]
  97. 01.11 01:42:04 DEBUG [LoymaxUtils] Loymax request:
  98. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><Calculates><CalculateRequest PurchaseID="17890611541025724714" OperationID="e7487432cf82449aaaa58af474863230" OperationDate="2018-11-01T01:42:04.714+03:00" ElementID="1"><Cheque ChequeDate="2018-11-01T01:41:59.052+03:00"><ChequeLine PosID="9428" Amount="507.00" Name="Носки 2ого размера" GoodsId="0000002102" Price="50.70" Quantity="10.000"/></Cheque></CalculateRequest></Calculates></XMLRequest>
  99.  
  100. 01.11 01:42:04 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  101. 01.11 01:42:04 DEBUG [LoymaxUtils] Loymax response:
  102. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><Calculates><CalculateResponse AvailableAmount="507.00" AvailableBonusAmount="0" ErrorCode="0"><Cheque ChequeDate="2018-11-01T01:41:59.052+03:00"><ChequeLine PosID="9428" Amount="507.00" Name="Носки 2ого размера" GoodsId="0000002102" Price="50.70" Quantity="10.000" Discount="0"/></Cheque></CalculateResponse></Calculates></XMLResponse>
  103.  
  104. 01.11 01:42:04 TRACE [SMServiceImpl] entering calcDiscount(Purchase, long, long, ILoyTechProcess). receipt-id: 9451; action-guid: 1890143020
  105. 01.11 01:42:05 INFO [PendingOperationQueue] Pending cards operation queue is empty. Populating from DB...
  106. 01.11 01:42:05 INFO [PendingOperationQueue] No pending card operations found.
  107. 01.11 01:42:05 TRACE [SMServiceEmulator] entering getToken(String, String, String, String). The arguments are: login [5345], password [5345], scope [null], type [password]
  108. 01.11 01:42:05 TRACE [SMServiceEmulator] leaving getToken(). The result is: sm-token [token: "этот токен вечен"; expired: 1000000]
  109. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Resend stale feedback
  110. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = SetApiLoyaltyPlugin
  111. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Found 5 stale feedbacks for SetApiLoyaltyPlugin. Submit to executor
  112. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = loymax
  113. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] There is no stale feedback for loymax
  114. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = sm
  115. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] There is no stale feedback for sm
  116. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = kopilka
  117. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] There is no stale feedback for kopilka
  118. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = set-loy-provider
  119. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] There is no stale feedback for set-loy-provider
  120. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = ml
  121. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Found 1 stale feedbacks for ml. Submit to executor
  122. 01.11 01:42:05 TRACE [MLServiceImpl] entering isEnabled()
  123. 01.11 01:42:05 TRACE [MLServiceImpl] leaving isEnabled(). the result is: true
  124. 01.11 01:42:05 TRACE [MLServiceImpl] Entering commitDiscounts (LoyExtProviderFeedback). The argument is LoyExtProviderFeedback{id=4325, payload=<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
  125. <ProcessRequest xmlns="http://loyalty.manzanagroup.ru/loyalty.xsd">
  126. <request>
  127. <ChequeRequest>
  128. <Card>
  129. <CardNumber>2612341234</CardNumber>
  130. </Card>
  131. <DateTime>2018-10-31T21:03:03.050+03:00</DateTime>
  132. <Organization>Victoria</Organization>
  133. <BusinessUnit>1789</BusinessUnit>
  134. <POS>61</POS>
  135. <Number>1541008981794</Number>
  136. <OperationType>Return</OperationType>
  137. <Summ>595.33</Summ>
  138. <Discount>5.001</Discount>
  139. <SummDiscounted>565.56</SummDiscounted>
  140. <Item>
  141. <PositionNumber>1</PositionNumber>
  142. <Article>04075</Article>
  143. <ArticleName>Пряник банановый сладкий (Великобритания)</ArticleName>
  144. <Price>595.33</Price>
  145. <Quantity>1.000</Quantity>
  146. <Summ>595.33</Summ>
  147. <Discount>5.001</Discount>
  148. <SummDiscounted>565.56</SummDiscounted>
  149. <ExtendedAttribute>
  150. <Key>DEFAULT</Key>
  151. <Value>5.001</Value>
  152. </ExtendedAttribute>
  153. </Item>
  154. </ChequeRequest>
  155. </request>
  156. </ProcessRequest>
  157. }
  158. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] Search for stale feedbacks provider = smch
  159. 01.11 01:42:05 INFO [LoyProvidersRegistryImpl] There is no stale feedback for smch
  160. 01.11 01:42:05 TRACE [SMServiceEmulator] entering getCurrentOffers(String, String). The arguments are: authHeader [Bearer MB>B B>:5= 25G5=], clientGUID [{"clientIdentifier":{"type":"GUID","id":"160032"},"timeZoneId":"Europe/Moscow"}]
  161. 01.11 01:42:05 TRACE [LoyPluginEmulator] entering onSendFeedback(LoyProviderFeedback). The argument is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  162. 01.11 01:42:05 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  163. 01.11 01:42:05 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  164. 01.11 01:42:05 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, quiet=false, cardProcessingId='null'}; it took 0 [ms]
  165. 01.11 01:42:05 TRACE [LoyPluginEmulator] entering makeFeedbackCall(URL, String, int, int). The arguments are: url [http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback], feedback [feedback from loyalty plugin emulator], connTimeout [1000], readTimeout [1000]
  166. 01.11 01:42:05 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  167. 01.11 01:42:05 TRACE [LoyPluginEmulator] leaving makeFeedbackCall(URL, String, int, int). It took 34 [ms]
  168. 01.11 01:42:05 TRACE [LoyPluginEmulator] leaving onSendFeedback(LoyProviderFeedback). The result is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  169. 01.11 01:42:06 TRACE [LoyPluginEmulator] entering onSendFeedback(LoyProviderFeedback). The argument is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  170. 01.11 01:42:06 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  171. 01.11 01:42:06 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  172. 01.11 01:42:06 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, quiet=false, cardProcessingId='null'}; it took 4 [ms]
  173. 01.11 01:42:06 TRACE [LoyPluginEmulator] entering makeFeedbackCall(URL, String, int, int). The arguments are: url [http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback], feedback [feedback from loyalty plugin emulator], connTimeout [1000], readTimeout [1000]
  174. 01.11 01:42:06 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  175. 01.11 01:42:06 TRACE [LoyPluginEmulator] leaving makeFeedbackCall(URL, String, int, int). It took 23 [ms]
  176. 01.11 01:42:06 TRACE [LoyPluginEmulator] leaving onSendFeedback(LoyProviderFeedback). The result is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  177. 01.11 01:42:06 TRACE [LoyPluginEmulator] entering onSendFeedback(LoyProviderFeedback). The argument is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  178. 01.11 01:42:06 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  179. 01.11 01:42:06 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  180. 01.11 01:42:06 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, quiet=false, cardProcessingId='null'}; it took 1 [ms]
  181. 01.11 01:42:06 TRACE [LoyPluginEmulator] entering makeFeedbackCall(URL, String, int, int). The arguments are: url [http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback], feedback [feedback from loyalty plugin emulator], connTimeout [1000], readTimeout [1000]
  182. 01.11 01:42:06 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  183. 01.11 01:42:06 TRACE [LoyPluginEmulator] leaving makeFeedbackCall(URL, String, int, int). It took 38 [ms]
  184. 01.11 01:42:06 TRACE [LoyPluginEmulator] leaving onSendFeedback(LoyProviderFeedback). The result is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  185. 01.11 01:42:07 TRACE [MLServiceImpl] entering getServices()
  186. 01.11 01:42:07 INFO [MLServiceImpl] About to create stubs to web-services
  187. 01.11 01:42:07 TRACE [SMServiceEmulator] entering getDelay(long, XmlRequestTypeEnum). The arguments are: guid [160032], requestType [OFFERS]
  188. 01.11 01:42:07 INFO [SMServiceEmulator] reading SM-base...
  189. 01.11 01:42:07 DEBUG [SMServiceEmulator] The array a XmlSmBase object to be extracted from is neither empty nor null - its length is: 12627
  190. 01.11 01:42:07 TRACE [LoyPluginEmulator] entering onSendFeedback(LoyProviderFeedback). The argument is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  191. 01.11 01:42:07 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  192. 01.11 01:42:07 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  193. 01.11 01:42:07 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, quiet=false, cardProcessingId='null'}; it took 9 [ms]
  194. 01.11 01:42:07 TRACE [LoyPluginEmulator] entering makeFeedbackCall(URL, String, int, int). The arguments are: url [http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback], feedback [feedback from loyalty plugin emulator], connTimeout [1000], readTimeout [1000]
  195. 01.11 01:42:07 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  196. 01.11 01:42:07 TRACE [LoyPluginEmulator] leaving makeFeedbackCall(URL, String, int, int). It took 27 [ms]
  197. 01.11 01:42:07 TRACE [LoyPluginEmulator] leaving onSendFeedback(LoyProviderFeedback). The result is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  198. 01.11 01:42:07 TRACE [LoyPluginEmulator] entering onSendFeedback(LoyProviderFeedback). The argument is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  199. 01.11 01:42:07 TRACE [LoyPluginEmulator] entering readProperties(IntegrationProperties, Logger)
  200. 01.11 01:42:07 TRACE [LoyPluginEmulator] getUrlProperty: the "secondary.calc.url" is not set
  201. 01.11 01:42:07 TRACE [LoyPluginEmulator] leaving readProperties(IntegrationProperties, Logger). The result is: LoyPluginEmulatorProperties{primaryCalcUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/process, secondaryCalcUrl=null, feedbackUrl=http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback, connectionTimeout=1000, readTimeout=1000, quiet=false, cardProcessingId='null'}; it took 0 [ms]
  202. 01.11 01:42:07 TRACE [LoyPluginEmulator] entering makeFeedbackCall(URL, String, int, int). The arguments are: url [http://127.0.0.1:10065/loy-plugin-processing/emulator/feedback], feedback [feedback from loyalty plugin emulator], connTimeout [1000], readTimeout [1000]
  203. 01.11 01:42:07 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  204. 01.11 01:42:07 TRACE [LoyPluginEmulator] leaving makeFeedbackCall(URL, String, int, int). It took 42 [ms]
  205. 01.11 01:42:07 TRACE [LoyPluginEmulator] leaving onSendFeedback(LoyProviderFeedback). The result is: LoyProviderFeedback{attemptsCount=0, savingStrategy=REPLACE, actionOnReceiptCancellation=REMOVE}
  206. 01.11 01:42:07 ERROR [SMServiceImpl] leaving calcDiscount(Purchase, long, long, ILoyTechProcess): failed to get SM-offers [body: ]
  207. ru.crystals.setmachine.promo.exception.SMClientException: Failed to request for offers
  208. at ru.crystals.setmachine.promo.api.v2.SMClient.requestForOffers(SMClient.java:182)
  209. at ru.crystals.pos.sm.SMServiceImpl.calcDiscount(SMServiceImpl.java:139)
  210. at ru.crystals.pos.loyal.sm.SetMachineLoyProvider.process(SetMachineLoyProvider.java:116)
  211. at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:133)
  212. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:752)
  213. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:671)
  214. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:770)
  215. at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:372)
  216. at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3552)
  217. at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3115)
  218. at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1477)
  219. at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1448)
  220. at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1345)
  221. at ru.crystals.pos.visualization.components.MainWindow.subTotal(MainWindow.java:844)
  222. at ru.crystals.pos.visualization.Factory.eventFunctionKey(Factory.java:538)
  223. at ru.crystals.pos.techprocess.TechProcessImpl.eventFunctionKey(TechProcessImpl.java:4169)
  224. at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:165)
  225. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  226. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  227. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  228. at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  229. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  230. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  231. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  232. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  233. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  234. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  235. at java.lang.Thread.run(Thread.java:745)
  236. Caused by: com.mashape.unirest.http.exceptions.UnirestException: java.net.SocketTimeoutException: Read timed out
  237. at com.mashape.unirest.http.HttpClientHelper.request(HttpClientHelper.java:143)
  238. at com.mashape.unirest.request.BaseRequest.asString(BaseRequest.java:56)
  239. at ru.crystals.setmachine.promo.api.v2.SMClient.requestForOffers(SMClient.java:158)
  240. ... 27 more
  241. Caused by: java.net.SocketTimeoutException: Read timed out
  242. at java.net.SocketInputStream.socketRead0(Native Method)
  243. at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
  244. at java.net.SocketInputStream.read(SocketInputStream.java:170)
  245. at java.net.SocketInputStream.read(SocketInputStream.java:141)
  246. at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
  247. at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
  248. at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
  249. at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
  250. at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
  251. at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
  252. at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
  253. at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
  254. at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
  255. at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
  256. at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
  257. at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
  258. at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
  259. at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
  260. at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
  261. at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
  262. at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
  263. at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
  264. at com.mashape.unirest.http.HttpClientHelper.request(HttpClientHelper.java:138)
  265. ... 29 more
  266. 01.11 01:42:08 DEBUG [SMServiceEmulator] Unmarshalling was complete successfully!
  267. 01.11 01:42:08 TRACE [SMServiceEmulator] leaving getDelay(long, XmlRequestTypeEnum). The result is: 500
  268. 01.11 01:42:08 ERROR [SetMachineLoyProvider] leaving process(Purchase, PurchaseEntity, ILoyTechProcess). Failed to connect to SM service
  269. ru.crystals.pos.sm.exception.SMConnectionException: Сервис персональных предложений временно недоступен
  270. at ru.crystals.pos.sm.SMServiceImpl.calcDiscount(SMServiceImpl.java:151)
  271. at ru.crystals.pos.loyal.sm.SetMachineLoyProvider.process(SetMachineLoyProvider.java:116)
  272. at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:133)
  273. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:752)
  274. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:671)
  275. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:770)
  276. at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:372)
  277. at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3552)
  278. at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3115)
  279. at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1477)
  280. at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1448)
  281. at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1345)
  282. at ru.crystals.pos.visualization.components.MainWindow.subTotal(MainWindow.java:844)
  283. at ru.crystals.pos.visualization.Factory.eventFunctionKey(Factory.java:538)
  284. at ru.crystals.pos.techprocess.TechProcessImpl.eventFunctionKey(TechProcessImpl.java:4169)
  285. at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:165)
  286. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  287. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  288. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  289. at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  290. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  291. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  292. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  293. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  294. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  295. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  296. at java.lang.Thread.run(Thread.java:745)
  297. 01.11 01:42:08 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  298. 01.11 01:42:08 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  299. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3574;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!
  300. 01.11 01:42:08 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=2039;guid=64278;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=2080.0]) one!
  301. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2043;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!
  302. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2515;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!
  303. 01.11 01:42:08 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=3154;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  304. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2711;guid=1270000641;name=SET10BONUSES: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=10.0]] from further processing 'cause it has no chance to trigger anyway!
  305. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3636;guid=61905;name=SR-2252_AdvAct_WriteoffBonuses;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1020.0]] from further processing 'cause it has no chance to trigger anyway!
  306. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3172;guid=-833406141;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=2000.0]] from further processing 'cause it has no chance to trigger anyway!
  307. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=1971;guid=64771;name=SR-2185: Автоматическое списание бонусов SET10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2090.0]] from further processing 'cause it has no chance to trigger anyway!
  308. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=3787;guid=62731;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  309. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=3245;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!
  310. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=2621;guid=64860;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2100.0]] from further processing 'cause it has no chance to trigger anyway!
  311. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=2711;guid=1270000641;name=SET10BONUSES: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=10.0]] from further processing 'cause it has no chance to trigger anyway!
  312. 01.11 01:42:08 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  313. 01.11 01:42:08 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  314. 01.11 01:42:08 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  315. 01.11 01:42:08 WARN [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  316. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3636;guid=61905;name=SR-2252_AdvAct_WriteoffBonuses;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1020.0]] from further processing 'cause it has no chance to trigger anyway!
  317. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2043;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!
  318. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2515;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!
  319. 01.11 01:42:08 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=3154;guid=1935033882;name=РА_ЦФТ_без_ограничений;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1070.0]) one!
  320. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=3787;guid=62731;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]] from further processing 'cause it has no chance to trigger anyway!
  321. 01.11 01:42:08 TRACE [MLServiceImpl] service [url: "http://127.0.0.1:60324/sap/manzana?wsdl"] was created... about to set connetion-timeouts [connect: 10000 [ms]; request: 5000 [ms]]
  322. 01.11 01:42:08 TRACE [MLServiceImpl] connection timeouts were set
  323. 01.11 01:42:08 TRACE [MLServiceImpl] leaving getServices(). The result size is: 1
  324. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=3574;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!
  325. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=3245;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!
  326. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3172;guid=-833406141;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=2000.0]] from further processing 'cause it has no chance to trigger anyway!
  327. 01.11 01:42:08 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=2039;guid=64278;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=2080.0]) one!
  328. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=1971;guid=64771;name=SR-2185: Автоматическое списание бонусов SET10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2090.0]] from further processing 'cause it has no chance to trigger anyway!
  329. 01.11 01:42:08 WARN [AdvertisingActionUtils] removing BONUS_LOYMAX-backed bonus discount action [AdvertisingActionEntity:[id=2621;guid=64860;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2100.0]] from further processing 'cause it has no chance to trigger anyway!
  330. 01.11 01:42:08 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  331. 01.11 01:42:08 INFO [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  332. 01.11 01:42:08 INFO [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  333. 01.11 01:42:08 INFO [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  334. 01.11 01:42:08 INFO [LoyalServiceImpl] Discount calculation (Purchase #4; Positions: 1
  335. 01.11 01:42:08 WARN [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  336. 01.11 01:42:08 TRACE [SMServiceEmulator] No need to throw an exception for [holder-guid: 160032; request-type: OFFERS]
  337. 01.11 01:42:08 TRACE [SMServiceEmulator] leaving getCurrentOffers(String, String)
  338. 01.11 01:42:08 ERROR [MLServiceImpl] leaving calcDiscount(Purchase): failed to connect to any of the servers [{}]
  339. 01.11 01:42:08 INFO [MLServiceImpl] looking up LoyFeedbackDao...
  340. 01.11 01:42:08 INFO [LoyalBridgeServiceImpl] Total time of discount calculation = 5207 ms
  341. 01.11 01:42:08 INFO [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  342. 01.11 01:42:08 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  343. 01.11 01:42:08 INFO [TextCustomerDisplayPluginAbstract] **********************
  344. 01.11 01:42:08 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  345. 01.11 01:42:08 INFO [TextCustomerDisplayPluginAbstract] *Итого 507.00*
  346. 01.11 01:42:08 INFO [TextCustomerDisplayPluginAbstract] **********************
  347. 01.11 01:42:08 INFO [TextCustomerDisplayPluginAbstract]
  348. 01.11 01:42:08 INFO [SpeedLog] TechProcess discounts processing on cheque with 1 positions [sale] time = 5234 ms
  349. 01.11 01:42:08 INFO [CommonLogger] value = 0
  350. 01.11 01:42:09 INFO [CommonLogger] busy = false
  351. 01.11 01:42:09 INFO [TransferManager] Nothing yet not processed on server to resend
  352. 01.11 01:42:11 INFO [FilesChecker] Catalog goods transport event
  353. 01.11 01:42:11 INFO [FilesChecker] goods types for report count: 0
  354. 01.11 01:42:11 INFO [FilesChecker] catalog goods files to cash count: 0
  355. 01.11 01:42:15 INFO [FiscalPrinter] resetException()
  356. 01.11 01:42:20 ERROR [TechProcessImpl] TP error:
  357. 01.11 01:42:21 INFO [CommonLogger] busy = true
  358. 01.11 01:42:21 TRACE [TechProcessImpl] Start warn divide amount
  359. 01.11 01:42:21 DEBUG [TechProcessCheckDivisor] Run divide check by department
  360. 01.11 01:42:21 INFO [LoyalBridgeServiceImpl] --------------- begin create Loy Tx for divided purchases ---------------
  361. 01.11 01:42:21 INFO [TransferManager] Nothing yet not processed on server to resend
  362. 01.11 01:42:21 INFO [LoyalBridgeServiceImpl] Total time of create Loy Tx for divided purchases = 37 ms
  363. 01.11 01:42:21 INFO [LoyalBridgeServiceImpl] ---------------- end create Loy Tx for divided purchases ----------------
  364. 01.11 01:42:21 INFO [TechProcessImpl] goToPayments
  365. 01.11 01:42:21 INFO [CheckContainer] startPayments() surcharge = 50700
  366. 01.11 01:42:21 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  367. 01.11 01:42:21 INFO [TextCustomerDisplayPluginAbstract] **********************
  368. 01.11 01:42:21 INFO [TextCustomerDisplayPluginAbstract] *Скидка 0.00*
  369. 01.11 01:42:21 INFO [TextCustomerDisplayPluginAbstract] *Итого 507.00*
  370. 01.11 01:42:21 INFO [TextCustomerDisplayPluginAbstract] **********************
  371. 01.11 01:42:21 INFO [TextCustomerDisplayPluginAbstract]
  372. 01.11 01:42:21 INFO [CheckContainer] isPaymentComplete = false
  373. 01.11 01:42:21 INFO [CheckContainer] prevState = ADD_POSITION
  374. 01.11 01:42:21 INFO [CheckContainer] paid = 0
  375. 01.11 01:42:21 INFO [CheckContainer] surcharge = 50700
  376. 01.11 01:42:21 INFO [CheckContainer] getPaymentFiscalizeError = NONE
  377. 01.11 01:42:21 INFO [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  378. 01.11 01:42:21 INFO [CommonLogger] busy = false
  379. 01.11 01:42:21 INFO [SpeedLog] (SUBTOTAL) time = 17,727;
  380. 01.11 01:42:21 INFO [CommonLogger] busy = false
  381. 01.11 01:42:21 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  382. 01.11 01:42:21 INFO [FilesChecker] Catalog goods transport event
  383. 01.11 01:42:21 INFO [FilesChecker] goods types for report count: 0
  384. 01.11 01:42:21 INFO [FilesChecker] catalog goods files to cash count: 0
  385. 01.11 01:42:21 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  386. 01.11 01:42:21 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  387. 01.11 01:42:22 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  388. 01.11 01:42:22 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  389. 01.11 01:42:22 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=82, command=kbdMenu3]
  390. 01.11 01:42:22 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=3574;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]) one!
  391. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=2039;guid=64278;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=2080.0]] from further processing 'cause it has no chance to trigger anyway!
  392. 01.11 01:42:22 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=2043;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]) one!
  393. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2515;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!
  394. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3154;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!
  395. 01.11 01:42:22 WARN [AdvertisingActionUtils] superfluous [3] BONUS_SR10-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2711;guid=1270000641;name=SET10BONUSES: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=10.0]) one!
  396. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3636;guid=61905;name=SR-2252_AdvAct_WriteoffBonuses;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1020.0]] from further processing 'cause it has no chance to trigger anyway!
  397. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3172;guid=-833406141;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=2000.0]] from further processing 'cause it has no chance to trigger anyway!
  398. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=1971;guid=64771;name=SR-2185: Автоматическое списание бонусов SET10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2090.0]] from further processing 'cause it has no chance to trigger anyway!
  399. 01.11 01:42:22 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=3787;guid=62731;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]) one!
  400. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=3245;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!
  401. 01.11 01:42:22 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=2621;guid=64860;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2100.0]) one!
  402. 01.11 01:42:22 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=3574;guid=-1506222621;name=РА_Спасибо_до50р;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1320.0]) one!
  403. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing CFT-backed bonus discount action [AdvertisingActionEntity:[id=2039;guid=64278;name=Спасибо от Сбербанка;parentGuid=null;masterActions=[];mode=UNCONDITIONAL;worksAnyTime=true;useRestrictions=true;priority=2080.0]] from further processing 'cause it has no chance to trigger anyway!
  404. 01.11 01:42:22 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=2043;guid=422993713;name=РА_ЦФТ_исключение10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1050.0]) one!
  405. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=2515;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!
  406. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_CFT-backed bonus discount action [AdvertisingActionEntity:[id=3154;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!
  407. 01.11 01:42:22 WARN [AdvertisingActionUtils] superfluous [3] BONUS_SR10-backed bonus actions were detected! Removing all of them from the further processing save for the first (arbitrary! namely: AdvertisingActionEntity:[id=2711;guid=1270000641;name=SET10BONUSES: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=10.0]) one!
  408. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3636;guid=61905;name=SR-2252_AdvAct_WriteoffBonuses;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1020.0]] from further processing 'cause it has no chance to trigger anyway!
  409. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=3172;guid=-833406141;name=SR-1177: Списание бонусных баллов с карты;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=false;priority=2000.0]] from further processing 'cause it has no chance to trigger anyway!
  410. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_SR10-backed bonus discount action [AdvertisingActionEntity:[id=1971;guid=64771;name=SR-2185: Автоматическое списание бонусов SET10;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2090.0]] from further processing 'cause it has no chance to trigger anyway!
  411. 01.11 01:42:22 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=3787;guid=62731;name=Бонусы Лента списание;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=1080.0]) one!
  412. 01.11 01:42:22 WARN [AdvertisingActionUtils] removing BONUS_INFORMIX-backed bonus discount action [AdvertisingActionEntity:[id=3245;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!
  413. 01.11 01:42:22 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=2621;guid=64860;name=Списание бонусных баллов LOYMAX;parentGuid=null;masterActions=[];mode=AUTOMATIC;worksAnyTime=true;useRestrictions=true;priority=2100.0]) one!
  414. 01.11 01:42:22 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  415. 01.11 01:42:22 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  416. 01.11 01:42:23 INFO [DocumentSender] OD found 2 documents
  417. 01.11 01:42:23 INFO [TransferManager] OD found 0 documents to register
  418. 01.11 01:42:23 INFO [DocumentSender] OD found 0 transactions to register
  419. 01.11 01:42:23 INFO [DocumentSender] response message = Created
  420. 01.11 01:42:23 INFO [TransferManager] Send document status : 9409 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20181101014223_184_1789_61.ser
  421. 01.11 01:42:23 INFO [CheckServiceImpl] SetSentToServerStatus : 9409 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20181101014223_184_1789_61.ser
  422. 01.11 01:42:23 INFO [DocumentSender] document 61/Document_20181101014223_184_1789_61.ser has been registered
  423. 01.11 01:42:23 INFO [TransferManager] Send document status : 9409 > SENT file name : 61/Document_20181101014223_184_1789_61.ser
  424. 01.11 01:42:23 INFO [CheckServiceImpl] SetSentToServerStatus : 9409 > SENT file name : 61/Document_20181101014223_184_1789_61.ser
  425. 01.11 01:42:23 INFO [DocumentSender] response message = Created
  426. 01.11 01:42:23 INFO [TransferManager] Send document status : 9450 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20181101014223_230_1789_61.ser
  427. 01.11 01:42:23 INFO [CheckServiceImpl] SetSentToServerStatus : 9450 > WAIT_ACKNOWLEDGEMENT file name : 61/Document_20181101014223_230_1789_61.ser
  428. 01.11 01:42:23 INFO [DocumentSender] document 61/Document_20181101014223_230_1789_61.ser has been registered
  429. 01.11 01:42:23 INFO [TransferManager] Send document status : 9450 > SENT file name : 61/Document_20181101014223_230_1789_61.ser
  430. 01.11 01:42:23 INFO [CheckServiceImpl] SetSentToServerStatus : 9450 > SENT file name : 61/Document_20181101014223_230_1789_61.ser
  431. 01.11 01:42:23 INFO [DocumentSender] OD found 0 payment transactions
  432. 01.11 01:42:23 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  433. 01.11 01:42:23 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  434. 01.11 01:42:23 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  435. 01.11 01:42:23 INFO [CommonLogger] Menu command: Аннулирование (class ru.crystals.pos.visualization.menu.commands.CancelCheckCommand)
  436. 01.11 01:42:24 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=37, command=kbdLeft]
  437. 01.11 01:42:24 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  438. 01.11 01:42:24 INFO [TechProcessShiftProxy] cancelCheck start
  439. 01.11 01:42:24 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
  440. 01.11 01:42:24 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
  441. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  442. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  443. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  444. 01.11 01:42:24 INFO [WaitComponent] [WaitComponent] MainFrame enabled - false
  445. 01.11 01:42:24 DEBUG [LoymaxUtils] Loymax request:
  446. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><CancelPurchases><CancelPurchaseRequest PurchaseID="17890611541025724714" OperationID="1a36b81aa9cf4ddfa0befdf4872da2bb" OperationDate="2018-11-01T01:42:24.638+03:00" ElementID="1"/></CancelPurchases></XMLRequest>
  447.  
  448. 01.11 01:42:24 DEBUG [LoymaxUtils] Loymax response:
  449. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><CancelPurchases><CancelPurchaseResponse PurchaseID="17890611541025724714" OperationID="1a36b81aa9cf4ddfa0befdf4872da2bb" OperationDate="2018-11-01T01:42:24.638+03:00" ErrorCode="0"/></CancelPurchases></XMLResponse>
  450.  
  451. 01.11 01:42:24 INFO [TechProcessShiftProxy] cancelCheck PurchaseEntity [id=9452, number=null, dateCreate=2018-11-01 01:41:59.052, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  452. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] Purchase cancelled: no implementation of PlastekServices.
  453. 01.11 01:42:24 TRACE [MLServiceImpl] entering getMlCardNo(PurchaseEntity). The argument is: PurchaseEntity [id=9452, number=4, dateCreate=2018-11-01 01:41:59.052, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  454. 01.11 01:42:24 TRACE [MLServiceImpl] leaving getMlCardNo(PurchaseEntity). The result is: null
  455. 01.11 01:42:24 INFO [FiscalPrinter] printCheck
  456. 01.11 01:42:24 INFO [FiscalPrinter] Fiscal interrupt: 7802781104
  457. 01.11 01:42:24 INFO [FiscalPrinter] Get document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale-annul.xml
  458. 01.11 01:42:24 INFO [DocumentTemplate] =====logo=====
  459. 01.11 01:42:24 INFO [DocumentTemplate] content: null
  460. 01.11 01:42:24 INFO [DocumentTemplate] =====header=====
  461. 01.11 01:42:24 INFO [DocumentTemplate] jr. name ||NORMAL
  462. 01.11 01:42:24 INFO [DocumentTemplate] 199100, Spb, Savushkina, 112 ||NORMAL
  463. 01.11 01:42:24 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  464. 01.11 01:42:24 INFO [DocumentTemplate] Смена:1 ЭКЛЗ:405702eb-065||NORMAL
  465. 01.11 01:42:24 INFO [DocumentTemplate] Номер кассы:61 ИНН: 7802781104||NORMAL
  466. 01.11 01:42:24 INFO [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  467. 01.11 01:42:24 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  468. 01.11 01:42:24 INFO [DocumentTemplate] КАССОВЫЙ ЧЕК 4 01-11-2018 01:42||NORMAL
  469. 01.11 01:42:24 INFO [DocumentTemplate] ---------------------------------------- ||NORMAL
  470. 01.11 01:42:24 INFO [DocumentTemplate] =====position=====
  471. 01.11 01:42:24 INFO [DocumentTemplate] Носки 2ого размера 50.70* 10 =507.00||SMALL
  472. 01.11 01:42:24 INFO [DocumentTemplate] =====fiscal=====
  473. 01.11 01:42:24 INFO [DocumentTemplate] content: null
  474. 01.11 01:42:24 INFO [DocumentTemplate] =====footer=====
  475. 01.11 01:42:24 INFO [DocumentTemplate] ******************************************** ||NORMAL
  476. 01.11 01:42:24 INFO [TextCustomerDisplayPluginAbstract] Screen updated:
  477. 01.11 01:42:24 INFO [TextCustomerDisplayPluginAbstract] **********************
  478. 01.11 01:42:24 INFO [TextCustomerDisplayPluginAbstract] *Аннулирование чека *
  479. 01.11 01:42:24 INFO [TextCustomerDisplayPluginAbstract] *Сумма чека 507.00*
  480. 01.11 01:42:24 INFO [TextCustomerDisplayPluginAbstract] **********************
  481. 01.11 01:42:24 INFO [TextCustomerDisplayPluginAbstract]
  482. 01.11 01:42:24 INFO [DocumentTemplate] ЧЕК No. 4 АННУЛИРОВАН||DOUBLEHEIGHT
  483. 01.11 01:42:24 INFO [DocumentTemplate] ******************************************** ||NORMAL
  484. 01.11 01:42:24 INFO [DocumentTemplate] =====cut=====
  485. 01.11 01:42:24 INFO [DocumentTemplate] content: null
  486. 01.11 01:42:24 INFO [FiscalPrinter] getCountAnnul
  487. 01.11 01:42:24 INFO [FiscalPrinter] CountAnnul = 3
  488. 01.11 01:42:24 INFO [FiscalPrinter] getLastDocNum
  489. 01.11 01:42:24 INFO [CheckServiceImpl] cancelCheck: PurchaseEntity [id=9452, number=4, dateCreate=2018-11-01 01:41:59.052, dateCommit=Thu Nov 01 01:42:24 MSK 2018, fiscalDocNum=3;9, sentToServerStatus=NO_SENT]
  490. 01.11 01:42:24 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
  491. 01.11 01:42:24 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
  492. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] Discounts cancelled: no implementation of PlastekServices.
  493. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] entering discountCancelledToSet5(PurchaseEntity)
  494. 01.11 01:42:24 TRACE [ExternalProcessingsImpl] leaving discountCancelledToSet5(PurchaseEntity): the SET5 loy processing is disabled
  495. 01.11 01:42:25 WARN [SetApiPluginLoyProvider] No loyal transaction entity found for purchase PurchaseEntity [id=9452, number=4, dateCreate=2018-11-01 01:41:59.052, dateCommit=Thu Nov 01 01:42:24 MSK 2018, fiscalDocNum=3;9, sentToServerStatus=NO_SENT]
  496. 01.11 01:42:25 ERROR [PrintXCommand] Unknown error:
  497. java.lang.NullPointerException
  498. at ru.crystals.pos.loymax.LoymaxUtils.lambda$getLoymaxCardEntity$1(LoymaxUtils.java:430)
  499. at java.util.Optional.map(Optional.java:215)
  500. at ru.crystals.pos.loymax.LoymaxUtils.getLoymaxCardEntity(LoymaxUtils.java:429)
  501. at ru.crystals.pos.loymax.LoymaxUtils.clearCardBalanceInfo(LoymaxUtils.java:425)
  502. at ru.crystals.pos.loyal.loymax.LoymaxLoyProvider.cancelDiscount(LoymaxLoyProvider.java:216)
  503. at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.cancelDiscount(LoyProvidersRegistryImpl.java:382)
  504. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.cancelDiscount(LoyalServiceImpl.java:1216)
  505. at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.checkCanceled(LoyalServiceImpl.java:1239)
  506. at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.checkCanceled(LoyalBridgeServiceImpl.java:429)
  507. at ru.crystals.pos.techprocess.TechProcessImpl.checkCanceled(TechProcessImpl.java:3863)
  508. at ru.crystals.pos.check.service.CheckServiceImpl.cancelCheckFinish(CheckServiceImpl.java:860)
  509. at ru.crystals.pos.techprocess.TechProcessShiftProxy.cancelCheckFinish(TechProcessShiftProxy.java:820)
  510. at ru.crystals.pos.techprocess.TechProcessShiftProxy.cancelCheck(TechProcessShiftProxy.java:783)
  511. at ru.crystals.pos.techprocess.TechProcessShiftProxy.cancelCheck(TechProcessShiftProxy.java:675)
  512. at ru.crystals.pos.techprocess.TechProcessImpl.cancelCheck(TechProcessImpl.java:754)
  513. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.tryCancelCheck(CancelCheckCommand.java:126)
  514. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.processReturnCheckThread(CancelCheckCommand.java:105)
  515. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.access$000(CancelCheckCommand.java:35)
  516. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand$1.onPressYes(CancelCheckCommand.java:64)
  517. at ru.crystals.pos.visualization.admin.components.CancelPurchaseComponent.onPressYes(CancelPurchaseComponent.java:296)
  518. at ru.crystals.pos.visualization.admin.components.CancelPurchaseComponent$1.onPressCommit(CancelPurchaseComponent.java:176)
  519. at ru.crystals.pos.visualization.admin.components.CommitCancelComponent.onPressCommit(CommitCancelComponent.java:148)
  520. at ru.crystals.pos.visualization.admin.components.CommitCancelComponent.enter(CommitCancelComponent.java:130)
  521. at ru.crystals.pos.visualization.admin.components.CancelPurchaseComponent.enter(CancelPurchaseComponent.java:277)
  522. at ru.crystals.pos.visualization.components.MainWindow.enter(MainWindow.java:1118)
  523. at ru.crystals.pos.visualization.Factory.eventControlKey(Factory.java:482)
  524. at ru.crystals.pos.techprocess.TechProcessImpl.eventControlKey(TechProcessImpl.java:4140)
  525. at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:167)
  526. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  527. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  528. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  529. at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  530. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  531. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  532. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  533. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  534. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  535. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  536. at java.lang.Thread.run(Thread.java:745)
  537. 01.11 01:42:25 ERROR [ErrorScenary] Произошла неопознанная ошибка.
  538. ru.crystals.pos.CashException: Произошла неопознанная ошибка.
  539. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.errorTryRepeat(CancelCheckCommand.java:140)
  540. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.tryCancelCheck(CancelCheckCommand.java:129)
  541. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.processReturnCheckThread(CancelCheckCommand.java:105)
  542. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand.access$000(CancelCheckCommand.java:35)
  543. at ru.crystals.pos.visualization.menu.commands.CancelCheckCommand$1.onPressYes(CancelCheckCommand.java:64)
  544. at ru.crystals.pos.visualization.admin.components.CancelPurchaseComponent.onPressYes(CancelPurchaseComponent.java:296)
  545. at ru.crystals.pos.visualization.admin.components.CancelPurchaseComponent$1.onPressCommit(CancelPurchaseComponent.java:176)
  546. at ru.crystals.pos.visualization.admin.components.CommitCancelComponent.onPressCommit(CommitCancelComponent.java:148)
  547. at ru.crystals.pos.visualization.admin.components.CommitCancelComponent.enter(CommitCancelComponent.java:130)
  548. at ru.crystals.pos.visualization.admin.components.CancelPurchaseComponent.enter(CancelPurchaseComponent.java:277)
  549. at ru.crystals.pos.visualization.components.MainWindow.enter(MainWindow.java:1118)
  550. at ru.crystals.pos.visualization.Factory.eventControlKey(Factory.java:482)
  551. at ru.crystals.pos.techprocess.TechProcessImpl.eventControlKey(TechProcessImpl.java:4140)
  552. at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:167)
  553. at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  554. at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  555. at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  556. at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  557. at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  558. at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  559. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  560. at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  561. at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  562. at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  563. at java.lang.Thread.run(Thread.java:745)
  564. 01.11 01:42:25 INFO [WaitComponent] [WaitComponent] MainFrame enabled - true
  565. 01.11 01:42:31 INFO [FilesChecker] Catalog goods transport event
  566. 01.11 01:42:31 INFO [FilesChecker] goods types for report count: 0
  567. 01.11 01:42:31 INFO [FilesChecker] catalog goods files to cash count: 0
  568. 01.11 01:42:33 DEBUG [TechProcessImpl] Server online mode
  569. 01.11 01:42:33 INFO [TransferManager] Nothing yet not processed on server to resend
  570. 01.11 01:42:40 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  571. 01.11 01:42:41 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  572. 01.11 01:42:41 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  573. 01.11 01:42:41 INFO [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  574. 01.11 01:42:41 INFO [FilesChecker] Catalog goods transport event
  575. 01.11 01:42:41 INFO [FilesChecker] goods types for report count: 0
  576. 01.11 01:42:41 INFO [FilesChecker] catalog goods files to cash count: 0
  577. 01.11 01:42:41 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
  578. 01.11 01:42:42 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=69, command=kbdMenu2]
  579. 01.11 01:42:42 INFO [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=87, command=kbdMenu1]
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!