API tools faq
paste
Advertisement
fedorm

Untitled

fedorm
Mar 27th, 2019
274
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
XML 230.41 KB | None | 0 0
raw download clone embed print report
  1. 27.03 14:25:10 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  2. 27.03 14:25:10 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253144; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142501_e30bbb37-ece7-4563-9761-cbe121543e4b.ser"]) were persisted successfully!
  3. 27.03 14:25:10 INFO  [SpeedLog] cards-catalog [file-id: 253144, file-url: http://172.29.17.216:8091/cards/cards_20190327_142501_e30bbb37-ece7-4563-9761-cbe121543e4b.ser] processing time (in millis): read-time: 2, processing-time: 6, last-id-update-time: 14
  4. 27.03 14:25:10 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,023 sec; average speed = 0,034 sec
  5. 27.03 14:25:10 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253216; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142503_668478a0-1a3b-4073-89fe-7ad98fbe370f.ser"]) were persisted successfully!
  6. 27.03 14:25:10 INFO  [SpeedLog] cards-catalog [file-id: 253216, file-url: http://172.29.17.216:8091/cards/cards_20190327_142503_668478a0-1a3b-4073-89fe-7ad98fbe370f.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 18
  7. 27.03 14:25:10 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,024 sec; average speed = 0,034 sec
  8. 27.03 14:25:16 INFO  [TransferManager] Nothing yet not processed on server to resend
  9. 27.03 14:25:19 INFO  [HttpClient] Set url - http://172.29.17.219:8090/SetXRMI/TransportServlet
  10. 27.03 14:25:20 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  11. 27.03 14:25:20 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253404; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142518_de0d2fbf-c8d2-4072-ab2e-b3a3fbcb30de.ser"]) were persisted successfully!
  12. 27.03 14:25:20 INFO  [SpeedLog] cards-catalog [file-id: 253404, file-url: http://172.29.17.216:8091/cards/cards_20190327_142518_de0d2fbf-c8d2-4072-ab2e-b3a3fbcb30de.ser] processing time (in millis): read-time: 12, processing-time: 7, last-id-update-time: 104
  13. 27.03 14:25:20 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,124 sec; average speed = 0,035 sec
  14. 27.03 14:25:20 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253476; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142518_9cc71c22-dab5-42b8-9e2d-75b3373b3178.ser"]) were persisted successfully!
  15. 27.03 14:25:20 INFO  [SpeedLog] cards-catalog [file-id: 253476, file-url: http://172.29.17.216:8091/cards/cards_20190327_142518_9cc71c22-dab5-42b8-9e2d-75b3373b3178.ser] processing time (in millis): read-time: 7, processing-time: 5, last-id-update-time: 132
  16. 27.03 14:25:20 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,144 sec; average speed = 0,035 sec
  17. 27.03 14:25:25 INFO  [CommonLogger] Starting cleaning. All log records older than 27 декабря 2018 14:25:25 will be deleted.
  18. 27.03 14:25:25 INFO  [CommonLogger] Cleaning done.
  19. 27.03 14:25:27 DEBUG [TechProcessImpl] Server online mode
  20. 27.03 14:25:28 INFO  [TransferManager] Nothing yet not processed on server to resend
  21. 27.03 14:25:30 INFO  [DocumentSender] ping = true
  22. 27.03 14:25:31 INFO  [CardsFilesReader2] [7] new card catalogs were detected on the server, and [7] of them we are about to process right now
  23. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253551; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_834c0ece-02bb-43a2-a64c-5b8e3c307086.ser"]) were persisted successfully!
  24. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253551, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_834c0ece-02bb-43a2-a64c-5b8e3c307086.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 20
  25. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,026 sec; average speed = 0,035 sec
  26. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253699; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_0c126442-a306-49f2-96fb-d869e70a2d9a.ser"]) were persisted successfully!
  27. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253699, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_0c126442-a306-49f2-96fb-d869e70a2d9a.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 13
  28. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,035 sec
  29. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253809; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_ffe89cf5-7f50-4430-a333-eab71d1a3ba9.ser"]) were persisted successfully!
  30. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253809, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_ffe89cf5-7f50-4430-a333-eab71d1a3ba9.ser] processing time (in millis): read-time: 1, processing-time: 3, last-id-update-time: 20
  31. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,024 sec; average speed = 0,035 sec
  32. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253826; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_7fbe9c12-ff74-41ec-ad37-9ece5360b48a.ser"]) were persisted successfully!
  33. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253826, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_7fbe9c12-ff74-41ec-ad37-9ece5360b48a.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 12
  34. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,035 sec
  35. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253827; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_ca2ca7ec-0c13-455f-8641-d322c4766405.ser"]) were persisted successfully!
  36. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253827, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_ca2ca7ec-0c13-455f-8641-d322c4766405.ser] processing time (in millis): read-time: 2, processing-time: 6, last-id-update-time: 12
  37. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,035 sec
  38. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253893; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_1073d7af-82be-45c0-a1d0-41274f3c77fe.ser"]) were persisted successfully!
  39. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253893, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_1073d7af-82be-45c0-a1d0-41274f3c77fe.ser] processing time (in millis): read-time: 2, processing-time: 4, last-id-update-time: 12
  40. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,035 sec
  41. 27.03 14:25:31 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 253962; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142527_832e01d2-e3d2-486e-b145-d3d231689a14.ser"]) were persisted successfully!
  42. 27.03 14:25:31 INFO  [SpeedLog] cards-catalog [file-id: 253962, file-url: http://172.29.17.216:8091/cards/cards_20190327_142527_832e01d2-e3d2-486e-b145-d3d231689a14.ser] processing time (in millis): read-time: 1, processing-time: 8, last-id-update-time: 11
  43. 27.03 14:25:31 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,035 sec
  44. 27.03 14:25:40 INFO  [TransferManager] Nothing yet not processed on server to resend
  45. 27.03 14:25:41 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  46. 27.03 14:25:41 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254036; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142531_1ed34a36-c580-4b1c-be24-8cd050c4354e.ser"]) were persisted successfully!
  47. 27.03 14:25:41 INFO  [SpeedLog] cards-catalog [file-id: 254036, file-url: http://172.29.17.216:8091/cards/cards_20190327_142531_1ed34a36-c580-4b1c-be24-8cd050c4354e.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 11
  48. 27.03 14:25:41 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,035 sec
  49. 27.03 14:25:41 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254108; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142533_08efe014-31d2-4a50-8316-56c0659b0f72.ser"]) were persisted successfully!
  50. 27.03 14:25:41 INFO  [SpeedLog] cards-catalog [file-id: 254108, file-url: http://172.29.17.216:8091/cards/cards_20190327_142533_08efe014-31d2-4a50-8316-56c0659b0f72.ser] processing time (in millis): read-time: 2, processing-time: 4, last-id-update-time: 10
  51. 27.03 14:25:41 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,017 sec; average speed = 0,034 sec
  52. 27.03 14:25:50 INFO  [MLServiceImpl] Number of pending operations (DISSOCIATING_CARD_MANZANA): 3
  53. 27.03 14:25:50 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  54.     Id: MQ==
  55.     Date: 2019-03-26 21:29:33.166
  56. 27.03 14:25:50 TRACE [MLServiceImpl] entering getServices()
  57. 27.03 14:25:50 INFO  [MLServiceImpl] About to create stubs to web-services
  58. 27.03 14:25:50 WARN  [MLServiceImpl] seems, that this processing (ML POS) was disabled: service urls is EMPTY!
  59. 27.03 14:25:50 TRACE [MLServiceImpl] leaving getServices(). The result size is: 0
  60. 27.03 14:25:50 ERROR [MLServiceImpl] it seems that all wsdl urls are INVALID 'cause the list of web services is EMPTY!
  61. 27.03 14:25:50 ERROR [MLServiceImpl] Could not send pending operation (id: 552; date: 2019-03-26 21:29:33.166)
  62. ru.crystals.pos.ml.exception.MLException: Ошибка конфигурации ML POS:<br>Invalid URLs
  63.     at ru.crystals.pos.ml.MLServiceImpl.throwExceptionIfNoValidServicesFound(MLServiceImpl.java:1497)
  64.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1446)
  65.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  66.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1538)
  67.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  68.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  69.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  70.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  71.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  72.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  73.     at java.lang.Thread.run(Thread.java:745)
  74. 27.03 14:25:50 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  75.     Id: MQ==
  76.     Date: 2019-03-26 21:28:52.132
  77. 27.03 14:25:50 TRACE [MLServiceImpl] entering getServices()
  78. 27.03 14:25:50 INFO  [MLServiceImpl] About to create stubs to web-services
  79. 27.03 14:25:50 WARN  [MLServiceImpl] seems, that this processing (ML POS) was disabled: service urls is EMPTY!
  80. 27.03 14:25:50 TRACE [MLServiceImpl] leaving getServices(). The result size is: 0
  81. 27.03 14:25:50 ERROR [MLServiceImpl] it seems that all wsdl urls are INVALID 'cause the list of web services is EMPTY!
  82. 27.03 14:25:50 ERROR [MLServiceImpl] Could not send pending operation (id: 550; date: 2019-03-26 21:28:52.132)
  83. ru.crystals.pos.ml.exception.MLException: Ошибка конфигурации ML POS:<br>Invalid URLs
  84.     at ru.crystals.pos.ml.MLServiceImpl.throwExceptionIfNoValidServicesFound(MLServiceImpl.java:1497)
  85.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1446)
  86.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  87.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1538)
  88.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  89.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  90.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  91.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  92.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  93.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  94.     at java.lang.Thread.run(Thread.java:745)
  95. 27.03 14:25:50 INFO  [MLServiceImpl] Try to cancel pending operation (DISSOCIATING_CARD_MANZANA):
  96.     Id: MQ==
  97.     Date: 2019-03-26 21:29:02.315
  98. 27.03 14:25:50 TRACE [MLServiceImpl] entering getServices()
  99. 27.03 14:25:50 INFO  [MLServiceImpl] About to create stubs to web-services
  100. 27.03 14:25:50 WARN  [MLServiceImpl] seems, that this processing (ML POS) was disabled: service urls is EMPTY!
  101. 27.03 14:25:50 TRACE [MLServiceImpl] leaving getServices(). The result size is: 0
  102. 27.03 14:25:50 ERROR [MLServiceImpl] it seems that all wsdl urls are INVALID 'cause the list of web services is EMPTY!
  103. 27.03 14:25:50 ERROR [MLServiceImpl] Could not send pending operation (id: 551; date: 2019-03-26 21:29:02.315)
  104. ru.crystals.pos.ml.exception.MLException: Ошибка конфигурации ML POS:<br>Invalid URLs
  105.     at ru.crystals.pos.ml.MLServiceImpl.throwExceptionIfNoValidServicesFound(MLServiceImpl.java:1497)
  106.     at ru.crystals.pos.ml.MLServiceImpl.dissociatingCard(MLServiceImpl.java:1446)
  107.     at ru.crystals.pos.ml.MLServiceImpl.access$200(MLServiceImpl.java:100)
  108.     at ru.crystals.pos.ml.MLServiceImpl$PendingDissociatingCardOperation.run(MLServiceImpl.java:1538)
  109.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  110.     at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
  111.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
  112.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
  113.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  114.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  115.     at java.lang.Thread.run(Thread.java:745)
  116. 27.03 14:25:51 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  117. 27.03 14:25:51 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254314; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142546_276325e8-c092-41a7-9540-7f6b31228422.ser"]) were persisted successfully!
  118. 27.03 14:25:51 INFO  [SpeedLog] cards-catalog [file-id: 254314, file-url: http://172.29.17.216:8091/cards/cards_20190327_142546_276325e8-c092-41a7-9540-7f6b31228422.ser] processing time (in millis): read-time: 4, processing-time: 6, last-id-update-time: 58
  119. 27.03 14:25:51 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,068 sec; average speed = 0,035 sec
  120. 27.03 14:25:51 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254386; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142547_46d837aa-634b-4db0-b556-ee32d7537856.ser"]) were persisted successfully!
  121. 27.03 14:25:51 INFO  [SpeedLog] cards-catalog [file-id: 254386, file-url: http://172.29.17.216:8091/cards/cards_20190327_142547_46d837aa-634b-4db0-b556-ee32d7537856.ser] processing time (in millis): read-time: 13, processing-time: 7, last-id-update-time: 45
  122. 27.03 14:25:51 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,065 sec; average speed = 0,035 sec
  123. ^C
  124. tc@box:/mnt/sda1/tce/storage/crystal-cash/logs$ cash restart
  125. Stopping POS softly....done
  126. Starting POS... done
  127. tc@box:/mnt/sda1/tce/storage/crystal-cash/logs$ tail -fn 500 root.log
  128.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  129.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  130.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  131.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  132.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  133.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  134.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  135.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  136.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  137.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  138.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  139.     at java.lang.reflect.Method.invoke(Method.java:498)
  140.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  141.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  142.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  143.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  144.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  145.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  146.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  147.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  148.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  149.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  150.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  151.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  152.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  153.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  154.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  155.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  156.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  157.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  158.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  159.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  160.     at java.lang.Class.newInstance(Class.java:442)
  161.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  162.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  163. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  164. java.lang.IllegalThreadStateException: process hasn't exited
  165.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  166.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  167.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  168.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  169.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  170.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  171.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  172.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  173.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  174.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  175.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  176.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  177.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  178.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  179.     at java.lang.reflect.Method.invoke(Method.java:498)
  180.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  181.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  182.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  183.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  184.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  185.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  186.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  187.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  188.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  189.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  190.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  191.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  192.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  193.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  194.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  195.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  196.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  197.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  198.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  199.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  200.     at java.lang.Class.newInstance(Class.java:442)
  201.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  202.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  203. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  204. java.lang.IllegalThreadStateException: process hasn't exited
  205.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  206.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  207.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  208.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  209.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  210.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  211.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  212.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  213.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  214.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  215.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  216.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  217.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  218.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  219.     at java.lang.reflect.Method.invoke(Method.java:498)
  220.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  221.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  222.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  223.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  224.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  225.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  226.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  227.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  228.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  229.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  230.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  231.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  232.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  233.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  234.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  235.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  236.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  237.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  238.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  239.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  240.     at java.lang.Class.newInstance(Class.java:442)
  241.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  242.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  243. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  244. java.lang.IllegalThreadStateException: process hasn't exited
  245.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  246.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  247.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  248.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  249.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  250.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  251.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  252.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  253.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  254.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  255.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  256.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  257.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  258.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  259.     at java.lang.reflect.Method.invoke(Method.java:498)
  260.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  261.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  262.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  263.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  264.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  265.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  266.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  267.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  268.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  269.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  270.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  271.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  272.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  273.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  274.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  275.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  276.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  277.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  278.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  279.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  280.     at java.lang.Class.newInstance(Class.java:442)
  281.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  282.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  283. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  284. java.lang.IllegalThreadStateException: process hasn't exited
  285.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  286.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  287.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  288.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  289.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  290.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  291.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  292.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  293.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  294.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  295.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  296.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  297.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  298.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  299.     at java.lang.reflect.Method.invoke(Method.java:498)
  300.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  301.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  302.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  303.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  304.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  305.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  306.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  307.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  308.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  309.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  310.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  311.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  312.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  313.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  314.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  315.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  316.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  317.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  318.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  319.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  320.     at java.lang.Class.newInstance(Class.java:442)
  321.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  322.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  323. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  324. java.lang.IllegalThreadStateException: process hasn't exited
  325.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  326.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  327.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  328.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  329.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  330.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  331.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  332.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  333.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  334.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  335.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  336.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  337.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  338.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  339.     at java.lang.reflect.Method.invoke(Method.java:498)
  340.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  341.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  342.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  343.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  344.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  345.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  346.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  347.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  348.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  349.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  350.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  351.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  352.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  353.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  354.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  355.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  356.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  357.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  358.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  359.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  360.     at java.lang.Class.newInstance(Class.java:442)
  361.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  362.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  363. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  364. java.lang.IllegalThreadStateException: process hasn't exited
  365.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  366.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  367.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  368.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  369.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  370.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  371.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  372.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  373.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  374.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  375.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  376.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  377.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  378.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  379.     at java.lang.reflect.Method.invoke(Method.java:498)
  380.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  381.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  382.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  383.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  384.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  385.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  386.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  387.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  388.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  389.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  390.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  391.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  392.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  393.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  394.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  395.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  396.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  397.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  398.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  399.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  400.     at java.lang.Class.newInstance(Class.java:442)
  401.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  402.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  403. 27.03 14:27:04 ERROR [CommonLogger] Error {}
  404. java.lang.IllegalThreadStateException: process hasn't exited
  405.     at java.lang.UNIXProcess.exitValue(UNIXProcess.java:423)
  406.     at ru.crystals.pos.utils.NixNativeUtils.execCommand(NixNativeUtils.java:119)
  407.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.init(NixCFTConnector.java:127)
  408.     at ru.crystals.pos.cftbridge.utils.connector.NixCFTConnector.connect(NixCFTConnector.java:132)
  409.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.connectAll(CFTBridgeImpl.java:320)
  410.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.startConnections(CFTBridgeImpl.java:294)
  411.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.lambda$start$0(CFTBridgeImpl.java:153)
  412.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:668)
  413.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:722)
  414.     at ru.crystals.bundles.BundleManager.addListener(BundleManager.java:735)
  415.     at ru.crystals.pos.cftbridge.CFTBridgeImpl.start(CFTBridgeImpl.java:150)
  416.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  417.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
  418.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
  419.     at java.lang.reflect.Method.invoke(Method.java:498)
  420.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1700)
  421.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1639)
  422.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1568)
  423.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
  424.     at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:476)
  425.     at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:303)
  426.     at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
  427.     at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:299)
  428.     at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)
  429.     at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:755)
  430.     at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:762)
  431.     at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:480)
  432.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
  433.     at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:93)
  434.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:429)
  435.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:101)
  436.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
  437.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
  438.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
  439.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
  440.     at java.lang.Class.newInstance(Class.java:442)
  441.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86)
  442.     at ru.crystals.pos.loader.Loader.main(Loader.java:145)
  443. 27.03 14:27:05 INFO  [CommonLogger] Process exited with code: 0
  444. 27.03 14:27:05 INFO  [CommonLogger] Command response:
  445. 27.03 14:27:05 INFO  [CommonLogger] ----Разбор аргументов командной строки-----------
  446. 27.03 14:27:05 INFO  [CommonLogger] Демонстрационный тест выполнения операций через PCX
  447. 27.03 14:27:05 INFO  [CommonLogger] Будут выполнены :
  448. 27.03 14:27:05 INFO  [CommonLogger]   - эхо запрос к ПЦ
  449. 27.03 14:27:05 INFO  [CommonLogger]   - запрос состояния счета бонусной карты
  450. 27.03 14:27:05 INFO  [CommonLogger]   - оплата товара баллами
  451. 27.03 14:27:05 INFO  [CommonLogger]   - операция начисления баллов
  452. 27.03 14:27:05 INFO  [CommonLogger]   - отмена операции оплаты баллов
  453. 27.03 14:27:05 INFO  [CommonLogger]   - отмена операции начисления баллов
  454. 27.03 14:27:05 INFO  [CommonLogger] Usage : test_linpcx [-P PartnerID] [-L Location] [-T Terminal] [-C ConnectionString] [-PH ProxyHost] [-PP ProxyPort] [-PU ProxyUserId] [-PW ProxyUserPass] [-CA CertFilePath] [-KF KeyFilePath] [-KP KeyPassword] [-CID ClientID] [-CT ClientIDType]
  455. 27.03 14:27:05 INFO  [CommonLogger] ----Инициализация PCX----------------------------------------------
  456. 27.03 14:27:05 INFO  [CommonLogger] Объект PCX создан !
  457. 27.03 14:27:05 INFO  [CommonLogger] Произошла ошибка
  458. 27.03 14:27:05 INFO  [CommonLogger] ErrorMessage : Ошибка инициализации SSL
  459. 27.03 14:27:05 INFO  [CommonLogger] ErrorInfo    : SSL/TLS error (Can't read CA file)
  460. 27.03 14:27:05 INFO  [CommonLogger] ----Завершение работы PCX---------------------------------------
  461. 27.03 14:27:05 INFO  [CommonLogger] PCX Finalize OK
  462. 27.03 14:27:05 INFO  [CommonLogger] --------------------------------------------------------------------------------
  463. 27.03 14:27:05 ERROR [CFTBridgeImpl] Error loading CFT bridge module: ru.crystals.pos.cftbridge.pcx.JavaPCXJNI.IPcxCore_ERR_UNKNOWN_SOAP_get()I
  464. 27.03 14:27:05 INFO  [SpeedLog] Loading time of CFTBridge module = 1112 ms
  465. 27.03 14:27:05 INFO  [SpeedLog] Time of full start Spring = 37144 ms
  466. 27.03 14:27:05 INFO  [MainWindow] Start create MainWindow
  467. 27.03 14:27:05 INFO  [MenuBuilder] Start buildMenuContainer
  468. 27.03 14:27:05 INFO  [MenuBuilder]      menu item - 4
  469. 27.03 14:27:05 INFO  [MenuBuilder]      menu item - 3
  470. 27.03 14:27:05 INFO  [MenuBuilder]      menu item - 1
  471. 27.03 14:27:06 INFO  [MenuBuilder]      menu item - 5
  472. 27.03 14:27:06 ERROR [MenuBuilder] No instance for menu command: command_existReturnFromBalance
  473. 27.03 14:27:06 ERROR [MenuBuilder] No instance for menu command: command_existBalanceReplenish
  474. 27.03 14:27:06 INFO  [MenuBuilder]      menu item - 2
  475. 27.03 14:27:06 INFO  [TransferManager] Message [userLogOut] has been sent
  476. 27.03 14:27:06 INFO  [MenuBuilder] Before create menu
  477. 27.03 14:27:06 INFO  [MenuBuilder] After create menu - ru.crystals.pos.visualization.components.VisualPanel[,0,0,0x0,invalid,layout=java.awt.FlowLayout,alignmentX=0.0,alignmentY=0.0,border=javax.swing.border.EmptyBorder@f287c5,flags=9,maximumSize=,minimumSize=,preferredSize=java.awt.Dimension[width=640,height=450]]
  478. 27.03 14:27:06 INFO  [MenuBuilder] Finish buildMenuContainer
  479. 27.03 14:27:06 INFO  [DocumentSender] ping = true
  480. 27.03 14:27:06 INFO  [CommonLogger] Time of starting visualization = 1 ms, totalMemory = 290521088, maxMemory = 510918656, freeMemory = 91057888
  481. 27.03 14:27:06 INFO  [Factory] Time of starting visualization = 1 ms, totalMemory = 290521088, maxMemory = 510918656, freeMemory = 91057888
  482. 27.03 14:27:06 INFO  [MainWindow] Menu panel - ru.crystals.pos.visualization.components.VisualPanel[,0,0,0x0,invalid,layout=java.awt.FlowLayout,alignmentX=0.0,alignmentY=0.0,border=javax.swing.border.EmptyBorder@f287c5,flags=9,maximumSize=,minimumSize=,preferredSize=java.awt.Dimension[width=640,height=450]]
  483. 27.03 14:27:08 INFO  [KeyboardConfigLoader] Keyboard loaded - qwerty , config/plugins/keyboard-qwerty-0-kbd.xml
  484. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  485. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  486. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  487. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  488. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  489. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  490. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  491. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  492. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  493. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  494. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  495. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  496. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  497. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  498. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  499. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  500. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  501. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  502. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  503. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  504. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  505. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  506. 27.03 14:27:08 WARN  [KeyboardLayoutLoader] ru.crystals.pos.keyboard.exception.KeyboardException: Element kbdAlphaNumeric/Ch of keyboard layout is empty
  507. 27.03 14:27:08 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  508. 27.03 14:27:08 INFO  [PasswordLoginContainer] Start LOGIN
  509. 27.03 14:27:08 INFO  [TechProcessImpl] userLogin ru.crystals.pos.user.UserEntity[id=null]
  510. 27.03 14:27:08 INFO  [TechProcessImpl] userLogout
  511. 27.03 14:27:08 INFO  [CommonLogger] getFactory().getTechProcess().isShiftOpen() = true
  512. 27.03 14:27:08 INFO  [CommonLogger] getFactory().getTechProcess().checkUserRight(Right.SHOW_MAIN_MODE)) = true
  513. 27.03 14:27:08 INFO  [PasswordLoginContainer] Finish LOGIN
  514. 27.03 14:27:08 INFO  [SpeedLog] TechProcess authenticating user time = 486 ms
  515. 27.03 14:27:08 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  516. 27.03 14:27:08 INFO  [TextCustomerDisplayPluginAbstract] **********************
  517. 27.03 14:27:08 INFO  [TextCustomerDisplayPluginAbstract] *Добро               *
  518. 27.03 14:27:08 INFO  [TextCustomerDisplayPluginAbstract] *пожаловать          *
  519. 27.03 14:27:08 INFO  [TextCustomerDisplayPluginAbstract] **********************
  520. 27.03 14:27:08 INFO  [TextCustomerDisplayPluginAbstract]
  521. 27.03 14:27:10 INFO  [GoodsDataSourceProviderImpl] looking up <PropertiesManager>
  522. 27.03 14:27:13 INFO  [CardsFilesReader2] [31] new card catalogs were detected on the server, and [31] of them we are about to process right now
  523. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254524; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_708a172b-1134-4439-b1dd-72cc61780fc3.ser"]) were persisted successfully!
  524. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254524, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_708a172b-1134-4439-b1dd-72cc61780fc3.ser] processing time (in millis): read-time: 6, processing-time: 377, last-id-update-time: 27
  525. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,410 sec; average speed = 0,410 sec
  526. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254580; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_636faf09-333a-46c9-8f2d-8a7e63516e19.ser"]) were persisted successfully!
  527. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254580, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_636faf09-333a-46c9-8f2d-8a7e63516e19.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 15
  528. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,022 sec; average speed = 0,216 sec
  529. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254599; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_8e46c244-e84d-497c-a05a-6516505f757c.ser"]) were persisted successfully!
  530. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254599, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_8e46c244-e84d-497c-a05a-6516505f757c.ser] processing time (in millis): read-time: 2, processing-time: 8, last-id-update-time: 12
  531. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,022 sec; average speed = 0,151 sec
  532. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254718; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_774ed562-a68d-434f-9027-5b9bd90bc0f9.ser"]) were persisted successfully!
  533. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254718, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_774ed562-a68d-434f-9027-5b9bd90bc0f9.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 13
  534. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,021 sec; average speed = 0,119 sec
  535. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254770; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_275ac777-5fd2-4936-ac2a-3c6aba812b87.ser"]) were persisted successfully!
  536. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254770, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_275ac777-5fd2-4936-ac2a-3c6aba812b87.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 17
  537. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,022 sec; average speed = 0,099 sec
  538. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254804; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_27295d16-c72b-44bb-840f-0ae642afbb35.ser"]) were persisted successfully!
  539. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254804, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_27295d16-c72b-44bb-840f-0ae642afbb35.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 18
  540. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,025 sec; average speed = 0,087 sec
  541. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254872; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142556_12e31676-3cca-47c1-a4ef-cfe2e237c83d.ser"]) were persisted successfully!
  542. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254872, file-url: http://172.29.17.216:8091/cards/cards_20190327_142556_12e31676-3cca-47c1-a4ef-cfe2e237c83d.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 14
  543. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,077 sec
  544. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 254946; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142600_318c58a3-727a-4027-b4e0-3fa8fcdf8714.ser"]) were persisted successfully!
  545. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 254946, file-url: http://172.29.17.216:8091/cards/cards_20190327_142600_318c58a3-727a-4027-b4e0-3fa8fcdf8714.ser] processing time (in millis): read-time: 1, processing-time: 9, last-id-update-time: 12
  546. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,022 sec; average speed = 0,071 sec
  547. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255018; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142601_8bdc9ae6-fd6c-4bad-817a-db604f841b46.ser"]) were persisted successfully!
  548. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255018, file-url: http://172.29.17.216:8091/cards/cards_20190327_142601_8bdc9ae6-fd6c-4bad-817a-db604f841b46.ser] processing time (in millis): read-time: 2, processing-time: 4, last-id-update-time: 12
  549. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,065 sec
  550. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255279; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142618_eb4887f5-ff20-43c1-83b2-0f3864e06eca.ser"]) were persisted successfully!
  551. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255279, file-url: http://172.29.17.216:8091/cards/cards_20190327_142618_eb4887f5-ff20-43c1-83b2-0f3864e06eca.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 13
  552. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,060 sec
  553. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255295; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142618_d9187450-311d-4043-97e3-3c10bb1efb0b.ser"]) were persisted successfully!
  554. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255295, file-url: http://172.29.17.216:8091/cards/cards_20190327_142618_d9187450-311d-4043-97e3-3c10bb1efb0b.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 12
  555. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,056 sec
  556. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255366; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142618_7555dce3-a75d-4671-9223-f55d79750162.ser"]) were persisted successfully!
  557. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255366, file-url: http://172.29.17.216:8091/cards/cards_20190327_142618_7555dce3-a75d-4671-9223-f55d79750162.ser] processing time (in millis): read-time: 3, processing-time: 4, last-id-update-time: 12
  558. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,053 sec
  559. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255462; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142626_ea5b7c59-ff54-401b-b92b-ecfb5f49624e.ser"]) were persisted successfully!
  560. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255462, file-url: http://172.29.17.216:8091/cards/cards_20190327_142626_ea5b7c59-ff54-401b-b92b-ecfb5f49624e.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 14
  561. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,051 sec
  562. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255528; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142626_52a8b4b3-4649-4155-a6cd-bbbb7f969fe2.ser"]) were persisted successfully!
  563. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255528, file-url: http://172.29.17.216:8091/cards/cards_20190327_142626_52a8b4b3-4649-4155-a6cd-bbbb7f969fe2.ser] processing time (in millis): read-time: 1, processing-time: 10, last-id-update-time: 13
  564. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,024 sec; average speed = 0,049 sec
  565. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255632; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142626_70677c45-5e94-4fa7-a9f1-0848b80aa1ed.ser"]) were persisted successfully!
  566. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255632, file-url: http://172.29.17.216:8091/cards/cards_20190327_142626_70677c45-5e94-4fa7-a9f1-0848b80aa1ed.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 12
  567. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,047 sec
  568. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255728; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142626_34b96d99-faad-4e98-8fe4-cc7a3d3285f6.ser"]) were persisted successfully!
  569. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255728, file-url: http://172.29.17.216:8091/cards/cards_20190327_142626_34b96d99-faad-4e98-8fe4-cc7a3d3285f6.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 12
  570. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,045 sec
  571. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255773; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142626_662fe628-bd89-4946-80e8-c939ca3d4cde.ser"]) were persisted successfully!
  572. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255773, file-url: http://172.29.17.216:8091/cards/cards_20190327_142626_662fe628-bd89-4946-80e8-c939ca3d4cde.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 12
  573. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,043 sec
  574. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255783; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142626_216bc9bb-6887-4401-b005-b643e3d5c9bd.ser"]) were persisted successfully!
  575. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255783, file-url: http://172.29.17.216:8091/cards/cards_20190327_142626_216bc9bb-6887-4401-b005-b643e3d5c9bd.ser] processing time (in millis): read-time: 3, processing-time: 5, last-id-update-time: 27
  576. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,035 sec; average speed = 0,043 sec
  577. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255857; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142631_fe527ac6-116a-4215-9b9e-b672c218c329.ser"]) were persisted successfully!
  578. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255857, file-url: http://172.29.17.216:8091/cards/cards_20190327_142631_fe527ac6-116a-4215-9b9e-b672c218c329.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 15
  579. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,021 sec; average speed = 0,042 sec
  580. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 255929; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142632_17431175-9c15-453d-bc4a-8648c70b2ac2.ser"]) were persisted successfully!
  581. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 255929, file-url: http://172.29.17.216:8091/cards/cards_20190327_142632_17431175-9c15-453d-bc4a-8648c70b2ac2.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 19
  582. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,025 sec; average speed = 0,041 sec
  583. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256144; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142651_8f733226-edb7-4375-97f3-7ddddb2f820d.ser"]) were persisted successfully!
  584. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256144, file-url: http://172.29.17.216:8091/cards/cards_20190327_142651_8f733226-edb7-4375-97f3-7ddddb2f820d.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 12
  585. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,017 sec; average speed = 0,040 sec
  586. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256509; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_17444140-67d7-46d5-95fc-48f9d87569de.ser"]) were persisted successfully!
  587. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256509, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_17444140-67d7-46d5-95fc-48f9d87569de.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 12
  588. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,017 sec; average speed = 0,039 sec
  589. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256531; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_28f254e8-b3a1-49d4-9fa4-2f0aef581798.ser"]) were persisted successfully!
  590. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256531, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_28f254e8-b3a1-49d4-9fa4-2f0aef581798.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 12
  591. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,038 sec
  592. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256532; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_6cc61dd6-cd51-4ac1-91a4-6bf948e9ab21.ser"]) were persisted successfully!
  593. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256532, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_6cc61dd6-cd51-4ac1-91a4-6bf948e9ab21.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 10
  594. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,017 sec; average speed = 0,037 sec
  595. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256677; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_643b5de0-fc40-4ef3-98b9-1da691c01c36.ser"]) were persisted successfully!
  596. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256677, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_643b5de0-fc40-4ef3-98b9-1da691c01c36.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 12
  597. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,036 sec
  598. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256678; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_4b2abe84-315c-41bf-b76a-ecf2c79aa9e5.ser"]) were persisted successfully!
  599. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256678, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_4b2abe84-315c-41bf-b76a-ecf2c79aa9e5.ser] processing time (in millis): read-time: 4, processing-time: 8, last-id-update-time: 13
  600. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,025 sec; average speed = 0,036 sec
  601. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256683; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_3ad1c7b5-077a-41f9-a411-817764ab46b9.ser"]) were persisted successfully!
  602. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256683, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_3ad1c7b5-077a-41f9-a411-817764ab46b9.ser] processing time (in millis): read-time: 2, processing-time: 5, last-id-update-time: 12
  603. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,035 sec
  604. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256684; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_61382d18-2314-40ea-9e4c-1284f28fa930.ser"]) were persisted successfully!
  605. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256684, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_61382d18-2314-40ea-9e4c-1284f28fa930.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 12
  606. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,035 sec
  607. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256686; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142701_27f3be91-2ae2-4b90-adb9-15676a232644.ser"]) were persisted successfully!
  608. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256686, file-url: http://172.29.17.216:8091/cards/cards_20190327_142701_27f3be91-2ae2-4b90-adb9-15676a232644.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 12
  609. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,034 sec
  610. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256774; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142704_3b24cbd6-57a3-4a41-99a2-f58ae47e1455.ser"]) were persisted successfully!
  611. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256774, file-url: http://172.29.17.216:8091/cards/cards_20190327_142704_3b24cbd6-57a3-4a41-99a2-f58ae47e1455.ser] processing time (in millis): read-time: 2, processing-time: 7, last-id-update-time: 15
  612. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,024 sec; average speed = 0,034 sec
  613. 27.03 14:27:14 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 256846; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142706_b14ad590-d427-421a-b091-8b4ae7dbeae2.ser"]) were persisted successfully!
  614. 27.03 14:27:14 INFO  [SpeedLog] cards-catalog [file-id: 256846, file-url: http://172.29.17.216:8091/cards/cards_20190327_142706_b14ad590-d427-421a-b091-8b4ae7dbeae2.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 12
  615. 27.03 14:27:14 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,017 sec; average speed = 0,033 sec
  616. 27.03 14:27:15 INFO  [TransferManager] OD found 0 documents to register
  617. 27.03 14:27:15 INFO  [DocumentSender] OD found 0 transactions to register
  618. 27.03 14:27:20 INFO  [TransferManager] Nothing yet not processed on server to resend
  619. 27.03 14:27:24 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  620. 27.03 14:27:24 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257077; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142722_b5586f1c-c943-4b98-990d-60cc81dc0d3c.ser"]) were persisted successfully!
  621. 27.03 14:27:24 INFO  [SpeedLog] cards-catalog [file-id: 257077, file-url: http://172.29.17.216:8091/cards/cards_20190327_142722_b5586f1c-c943-4b98-990d-60cc81dc0d3c.ser] processing time (in millis): read-time: 5, processing-time: 9, last-id-update-time: 36
  622. 27.03 14:27:24 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,050 sec; average speed = 0,034 sec
  623. 27.03 14:27:24 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257147; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142722_45b4b11e-2a70-457e-84aa-0fcaf6b52256.ser"]) were persisted successfully!
  624. 27.03 14:27:24 INFO  [SpeedLog] cards-catalog [file-id: 257147, file-url: http://172.29.17.216:8091/cards/cards_20190327_142722_45b4b11e-2a70-457e-84aa-0fcaf6b52256.ser] processing time (in millis): read-time: 4, processing-time: 4, last-id-update-time: 49
  625. 27.03 14:27:24 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,057 sec; average speed = 0,034 sec
  626. 27.03 14:27:25 INFO  [HttpClient] Set url - http://172.29.17.219:8090/SetXRMI/TransportServlet
  627. 27.03 14:27:25 INFO  [HttpClient] Set url - http://172.29.17.219:8090/SetXRMI/TransportServlet
  628. 27.03 14:27:32 INFO  [TransferManager] Nothing yet not processed on server to resend
  629. 27.03 14:27:32 INFO  [FiscalPrinter] resetException()
  630. 27.03 14:27:32 DEBUG [TechProcessImpl] Server online mode
  631. 27.03 14:27:33 INFO  [LoyTxCleanerWorkhorse] shopNo: 7216
  632. 27.03 14:27:33 INFO  [LoyTxCleanerWorkhorse] cashNo: 61
  633. 27.03 14:27:33 INFO  [LoyTxCleanerWorkhorse] shiftsToKeep: 0
  634. 27.03 14:27:33 INFO  [LoyTxCleanerWorkhorse] inn: 7802781104
  635. 27.03 14:27:34 INFO  [Scale] BarcodeScannerImpl - barcode = 4600074000003
  636. 27.03 14:27:34 INFO  [TechProcessImpl] eventBarcodeScanner 4600074000003
  637. 27.03 14:27:34 INFO  [LoyalServiceImpl] looking up Loy Providers Registry...
  638. 27.03 14:27:34 TRACE [MLServiceImpl] entering isEnabled()
  639. 27.03 14:27:34 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  640. 27.03 14:27:34 TRACE [SCService] entering isEnabled()
  641. 27.03 14:27:34 TRACE [SCService] leaving isEnabled(). the result is: false
  642. 27.03 14:27:34 TRACE [MLServiceImpl] entering isEnabled()
  643. 27.03 14:27:34 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  644. 27.03 14:27:34 TRACE [SCService] entering isEnabled()
  645. 27.03 14:27:34 TRACE [SCService] leaving isEnabled(). the result is: false
  646. 27.03 14:27:34 INFO  [CheckContainer] Barcode 4600074000003 has been received by visualisation
  647. 27.03 14:27:34 INFO  [TechProcessImpl] TP searchProduct 4600074000003
  648. 27.03 14:27:34 INFO  [CatalogService] getGoodsByBarCode barcode = 4600074000003
  649. 27.03 14:27:34 INFO  [CatalogService] get product by barcode '4600074000003' result: ProductPieceEntity[name=Хлеб банановый сладкий (Россия), item=00074]
  650. 27.03 14:27:34 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 4600074000003 time = 48 ms
  651. 27.03 14:27:34 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 4600074000003 time = 49 ms
  652. 27.03 14:27:35 INFO  [SpeedLog] getProductRestrictions  time = 8 ms
  653. 27.03 14:27:35 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 4600074000003 time = 91 ms
  654. 27.03 14:27:35 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 4600074000003 time = 92 ms
  655. 27.03 14:27:35 INFO  [SpeedLog] searchProduct time  time = 93 ms
  656. 27.03 14:27:35 INFO  [CardsFilesReader2] [7] new card catalogs were detected on the server, and [7] of them we are about to process right now
  657. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257227; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142731_518077c0-5514-4281-9fcc-5d340a03b509.ser"]) were persisted successfully!
  658. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257227, file-url: http://172.29.17.216:8091/cards/cards_20190327_142731_518077c0-5514-4281-9fcc-5d340a03b509.ser] processing time (in millis): read-time: 1, processing-time: 12, last-id-update-time: 11
  659. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,024 sec; average speed = 0,034 sec
  660. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257323; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142731_b4c3a709-34a2-4eee-8483-78c2d634cdca.ser"]) were persisted successfully!
  661. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257323, file-url: http://172.29.17.216:8091/cards/cards_20190327_142731_b4c3a709-34a2-4eee-8483-78c2d634cdca.ser] processing time (in millis): read-time: 3, processing-time: 5, last-id-update-time: 13
  662. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,021 sec; average speed = 0,034 sec
  663. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257390; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142732_62ceeef5-fe23-4408-8422-f1af60b988bc.ser"]) were persisted successfully!
  664. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257390, file-url: http://172.29.17.216:8091/cards/cards_20190327_142732_62ceeef5-fe23-4408-8422-f1af60b988bc.ser] processing time (in millis): read-time: 3, processing-time: 5, last-id-update-time: 21
  665. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,029 sec; average speed = 0,034 sec
  666. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257460; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142732_fcd28015-9f47-4d0d-af44-c3e83af09b72.ser"]) were persisted successfully!
  667. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257460, file-url: http://172.29.17.216:8091/cards/cards_20190327_142732_fcd28015-9f47-4d0d-af44-c3e83af09b72.ser] processing time (in millis): read-time: 3, processing-time: 7, last-id-update-time: 18
  668. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,028 sec; average speed = 0,033 sec
  669. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257509; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142732_7673881e-3968-4e56-a35f-dfab8c52c8e5.ser"]) were persisted successfully!
  670. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257509, file-url: http://172.29.17.216:8091/cards/cards_20190327_142732_7673881e-3968-4e56-a35f-dfab8c52c8e5.ser] processing time (in millis): read-time: 2, processing-time: 6, last-id-update-time: 14
  671. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,022 sec; average speed = 0,033 sec
  672. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257609; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142732_e77592f7-f8d2-4774-a716-09ce18f65fef.ser"]) were persisted successfully!
  673. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257609, file-url: http://172.29.17.216:8091/cards/cards_20190327_142732_e77592f7-f8d2-4774-a716-09ce18f65fef.ser] processing time (in millis): read-time: 1, processing-time: 15, last-id-update-time: 13
  674. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,029 sec; average speed = 0,033 sec
  675. 27.03 14:27:35 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257635; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142732_6d4629bd-57df-4775-b793-e66ee5d04582.ser"]) were persisted successfully!
  676. 27.03 14:27:35 INFO  [SpeedLog] cards-catalog [file-id: 257635, file-url: http://172.29.17.216:8091/cards/cards_20190327_142732_6d4629bd-57df-4775-b793-e66ee5d04582.ser] processing time (in millis): read-time: 2, processing-time: 10, last-id-update-time: 14
  677. 27.03 14:27:35 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,026 sec; average speed = 0,033 sec
  678. 27.03 14:27:35 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,512 sec; average speed = 0,512 sec; position test num = 1.0; product item 00074; <512>
  679. 27.03 14:27:35 INFO  [CommonLogger] busy = false
  680. 27.03 14:27:35 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  681. 27.03 14:27:35 INFO  [TextCustomerDisplayPluginAbstract] **********************
  682. 27.03 14:27:35 INFO  [TextCustomerDisplayPluginAbstract] *Хлеб бананов 1 62.33*
  683. 27.03 14:27:35 INFO  [TextCustomerDisplayPluginAbstract] *Итого          62.33*
  684. 27.03 14:27:35 INFO  [TextCustomerDisplayPluginAbstract] **********************
  685. 27.03 14:27:35 INFO  [TextCustomerDisplayPluginAbstract]
  686. 27.03 14:27:37 INFO  [Scale] BarcodeScannerImpl - barcode = 5004662000007
  687. 27.03 14:27:37 INFO  [TechProcessImpl] eventBarcodeScanner 5004662000007
  688. 27.03 14:27:37 TRACE [MLServiceImpl] entering isEnabled()
  689. 27.03 14:27:37 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  690. 27.03 14:27:37 TRACE [SCService] entering isEnabled()
  691. 27.03 14:27:37 TRACE [SCService] leaving isEnabled(). the result is: false
  692. 27.03 14:27:37 TRACE [MLServiceImpl] entering isEnabled()
  693. 27.03 14:27:37 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  694. 27.03 14:27:37 TRACE [SCService] entering isEnabled()
  695. 27.03 14:27:37 TRACE [SCService] leaving isEnabled(). the result is: false
  696. 27.03 14:27:37 INFO  [CheckContainer] Barcode 5004662000007 has been received by visualisation
  697. 27.03 14:27:37 INFO  [CheckContainer] All conditions are checked. Current position will be added
  698. 27.03 14:27:37 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 00074; type: ProductPieceEntity; count: 1000; sum: 6233; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  699. 27.03 14:27:37 INFO  [SpeedLog] TP addPosition time = 74 ms
  700. 27.03 14:27:37 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,120 sec; average speed = 0,120 sec; position test num = 1; <120>
  701. 27.03 14:27:37 INFO  [CheckContainer] visualization will start searching product
  702. 27.03 14:27:37 INFO  [TechProcessImpl] TP searchProduct 5004662000007
  703. 27.03 14:27:37 INFO  [CatalogService] getGoodsByBarCode barcode = 5004662000007
  704. 27.03 14:27:37 INFO  [CatalogService] get product by barcode '5004662000007' result: ProductPieceEntity[name=Йогурт грушевый кисло-сладкий (Великобритания), item=04662]
  705. 27.03 14:27:37 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5004662000007 time = 3 ms
  706. 27.03 14:27:37 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 5004662000007 time = 4 ms
  707. 27.03 14:27:37 INFO  [SpeedLog] getProductRestrictions  time = 2 ms
  708. 27.03 14:27:37 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 5004662000007 time = 6 ms
  709. 27.03 14:27:37 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5004662000007 time = 6 ms
  710. 27.03 14:27:37 INFO  [SpeedLog] searchProduct time  time = 7 ms
  711. 27.03 14:27:37 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,103 sec; average speed = 0,308 sec; position test num = 2.0; product item 04662; <103>
  712. 27.03 14:27:37 INFO  [CommonLogger] busy = false
  713. 27.03 14:27:37 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  714. 27.03 14:27:37 INFO  [TextCustomerDisplayPluginAbstract] **********************
  715. 27.03 14:27:37 INFO  [TextCustomerDisplayPluginAbstract] *Йогурт груш 1 555.81*
  716. 27.03 14:27:37 INFO  [TextCustomerDisplayPluginAbstract] *Итого         618.14*
  717. 27.03 14:27:37 INFO  [TextCustomerDisplayPluginAbstract] **********************
  718. 27.03 14:27:37 INFO  [TextCustomerDisplayPluginAbstract]
  719. 27.03 14:27:39 INFO  [Scale] BarcodeScannerImpl - barcode = 8805526000006
  720. 27.03 14:27:39 INFO  [TechProcessImpl] eventBarcodeScanner 8805526000006
  721. 27.03 14:27:39 TRACE [MLServiceImpl] entering isEnabled()
  722. 27.03 14:27:39 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  723. 27.03 14:27:39 TRACE [SCService] entering isEnabled()
  724. 27.03 14:27:39 TRACE [SCService] leaving isEnabled(). the result is: false
  725. 27.03 14:27:39 TRACE [MLServiceImpl] entering isEnabled()
  726. 27.03 14:27:39 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  727. 27.03 14:27:39 TRACE [SCService] entering isEnabled()
  728. 27.03 14:27:39 TRACE [SCService] leaving isEnabled(). the result is: false
  729. 27.03 14:27:39 INFO  [CheckContainer] Barcode 8805526000006 has been received by visualisation
  730. 27.03 14:27:39 INFO  [CheckContainer] All conditions are checked. Current position will be added
  731. 27.03 14:27:39 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 04662; type: ProductPieceEntity; count: 1000; sum: 55581; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  732. 27.03 14:27:39 INFO  [SpeedLog] TP addPosition time = 23 ms
  733. 27.03 14:27:39 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,035 sec; average speed = 0,078 sec; position test num = 2; <35>
  734. 27.03 14:27:39 INFO  [CheckContainer] visualization will start searching product
  735. 27.03 14:27:39 INFO  [TechProcessImpl] TP searchProduct 8805526000006
  736. 27.03 14:27:39 INFO  [CatalogService] getGoodsByBarCode barcode = 8805526000006
  737. 27.03 14:27:39 INFO  [CatalogService] get product by barcode '8805526000006' result: ProductPieceEntity[name=Напиток виноградный сочный (Ю. Корея), item=05526]
  738. 27.03 14:27:39 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 8805526000006 time = 2 ms
  739. 27.03 14:27:39 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 8805526000006 time = 2 ms
  740. 27.03 14:27:39 INFO  [SpeedLog] getProductRestrictions  time = 2 ms
  741. 27.03 14:27:39 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 8805526000006 time = 5 ms
  742. 27.03 14:27:39 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 8805526000006 time = 5 ms
  743. 27.03 14:27:39 INFO  [SpeedLog] searchProduct time  time = 6 ms
  744. 27.03 14:27:39 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,041 sec; average speed = 0,219 sec; position test num = 3.0; product item 05526; <41>
  745. 27.03 14:27:39 INFO  [CommonLogger] busy = false
  746. 27.03 14:27:39 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  747. 27.03 14:27:39 INFO  [TextCustomerDisplayPluginAbstract] **********************
  748. 27.03 14:27:39 INFO  [TextCustomerDisplayPluginAbstract] *Напиток вин 1 732.48*
  749. 27.03 14:27:39 INFO  [TextCustomerDisplayPluginAbstract] *Итого        1350.62*
  750. 27.03 14:27:39 INFO  [TextCustomerDisplayPluginAbstract] **********************
  751. 27.03 14:27:39 INFO  [TextCustomerDisplayPluginAbstract]
  752. 27.03 14:27:41 INFO  [Scale] BarcodeScannerImpl - barcode = 5409406000006
  753. 27.03 14:27:41 INFO  [TechProcessImpl] eventBarcodeScanner 5409406000006
  754. 27.03 14:27:41 TRACE [MLServiceImpl] entering isEnabled()
  755. 27.03 14:27:41 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  756. 27.03 14:27:41 TRACE [SCService] entering isEnabled()
  757. 27.03 14:27:41 TRACE [SCService] leaving isEnabled(). the result is: false
  758. 27.03 14:27:41 TRACE [MLServiceImpl] entering isEnabled()
  759. 27.03 14:27:41 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  760. 27.03 14:27:41 TRACE [SCService] entering isEnabled()
  761. 27.03 14:27:41 TRACE [SCService] leaving isEnabled(). the result is: false
  762. 27.03 14:27:41 INFO  [CheckContainer] Barcode 5409406000006 has been received by visualisation
  763. 27.03 14:27:41 INFO  [CheckContainer] All conditions are checked. Current position will be added
  764. 27.03 14:27:41 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 05526; type: ProductPieceEntity; count: 1000; sum: 73248; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  765. 27.03 14:27:41 INFO  [SpeedLog] TP addPosition time = 17 ms
  766. 27.03 14:27:41 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,038 sec; average speed = 0,064 sec; position test num = 3; <38>
  767. 27.03 14:27:41 INFO  [CheckContainer] visualization will start searching product
  768. 27.03 14:27:41 INFO  [TechProcessImpl] TP searchProduct 5409406000006
  769. 27.03 14:27:41 INFO  [CatalogService] getGoodsByBarCode barcode = 5409406000006
  770. 27.03 14:27:41 INFO  [CatalogService] get product by barcode '5409406000006' result: ProductPieceEntity[name=Напиток малиновый густой (Бельгия), item=09406]
  771. 27.03 14:27:41 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5409406000006 time = 3 ms
  772. 27.03 14:27:41 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 5409406000006 time = 3 ms
  773. 27.03 14:27:41 INFO  [SpeedLog] getProductRestrictions  time = 1 ms
  774. 27.03 14:27:41 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 5409406000006 time = 8 ms
  775. 27.03 14:27:41 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5409406000006 time = 8 ms
  776. 27.03 14:27:41 INFO  [SpeedLog] searchProduct time  time = 8 ms
  777. 27.03 14:27:41 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,104 sec; average speed = 0,190 sec; position test num = 4.0; product item 09406; <104>
  778. 27.03 14:27:41 INFO  [CommonLogger] busy = false
  779. 27.03 14:27:41 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  780. 27.03 14:27:41 INFO  [TextCustomerDisplayPluginAbstract] **********************
  781. 27.03 14:27:41 INFO  [TextCustomerDisplayPluginAbstract] *Напиток ма 1 1249.75*
  782. 27.03 14:27:41 INFO  [TextCustomerDisplayPluginAbstract] *Итого        2600.37*
  783. 27.03 14:27:41 INFO  [TextCustomerDisplayPluginAbstract] **********************
  784. 27.03 14:27:41 INFO  [TextCustomerDisplayPluginAbstract]
  785. 27.03 14:27:42 INFO  [Scale] BarcodeScannerImpl - barcode = 4600481000009
  786. 27.03 14:27:42 INFO  [TechProcessImpl] eventBarcodeScanner 4600481000009
  787. 27.03 14:27:42 TRACE [MLServiceImpl] entering isEnabled()
  788. 27.03 14:27:42 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  789. 27.03 14:27:42 TRACE [SCService] entering isEnabled()
  790. 27.03 14:27:42 TRACE [SCService] leaving isEnabled(). the result is: false
  791. 27.03 14:27:42 TRACE [MLServiceImpl] entering isEnabled()
  792. 27.03 14:27:42 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  793. 27.03 14:27:42 TRACE [SCService] entering isEnabled()
  794. 27.03 14:27:42 TRACE [SCService] leaving isEnabled(). the result is: false
  795. 27.03 14:27:42 INFO  [CheckContainer] Barcode 4600481000009 has been received by visualisation
  796. 27.03 14:27:42 INFO  [CheckContainer] All conditions are checked. Current position will be added
  797. 27.03 14:27:42 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 09406; type: ProductPieceEntity; count: 1000; sum: 124975; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  798. 27.03 14:27:42 INFO  [SpeedLog] TP addPosition time = 22 ms
  799. 27.03 14:27:42 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,044 sec; average speed = 0,059 sec; position test num = 4; <44>
  800. 27.03 14:27:42 INFO  [CheckContainer] visualization will start searching product
  801. 27.03 14:27:42 INFO  [TechProcessImpl] TP searchProduct 4600481000009
  802. 27.03 14:27:42 INFO  [CatalogService] getGoodsByBarCode barcode = 4600481000009
  803. 27.03 14:27:42 INFO  [CatalogService] get product by barcode '4600481000009' result: ProductPieceEntity[name=Кефир лимонный густой (Россия), item=00481]
  804. 27.03 14:27:42 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 4600481000009 time = 7 ms
  805. 27.03 14:27:42 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 4600481000009 time = 7 ms
  806. 27.03 14:27:42 INFO  [SpeedLog] getProductRestrictions  time = 3 ms
  807. 27.03 14:27:42 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 4600481000009 time = 10 ms
  808. 27.03 14:27:42 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 4600481000009 time = 10 ms
  809. 27.03 14:27:42 INFO  [SpeedLog] searchProduct time  time = 10 ms
  810. 27.03 14:27:43 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,032 sec; average speed = 0,158 sec; position test num = 5.0; product item 00481; <32>
  811. 27.03 14:27:43 INFO  [CommonLogger] busy = false
  812. 27.03 14:27:43 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  813. 27.03 14:27:43 INFO  [TextCustomerDisplayPluginAbstract] **********************
  814. 27.03 14:27:43 INFO  [TextCustomerDisplayPluginAbstract] *Кефир лимонн 1 25.15*
  815. 27.03 14:27:43 INFO  [TextCustomerDisplayPluginAbstract] *Итого        2625.52*
  816. 27.03 14:27:43 INFO  [TextCustomerDisplayPluginAbstract] **********************
  817. 27.03 14:27:43 INFO  [TextCustomerDisplayPluginAbstract]
  818. 27.03 14:27:44 INFO  [TransferManager] Nothing yet not processed on server to resend
  819. 27.03 14:27:44 INFO  [Scale] BarcodeScannerImpl - barcode = 3007763000008
  820. 27.03 14:27:44 INFO  [TechProcessImpl] eventBarcodeScanner 3007763000008
  821. 27.03 14:27:44 TRACE [MLServiceImpl] entering isEnabled()
  822. 27.03 14:27:44 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  823. 27.03 14:27:44 TRACE [SCService] entering isEnabled()
  824. 27.03 14:27:44 TRACE [SCService] leaving isEnabled(). the result is: false
  825. 27.03 14:27:44 TRACE [MLServiceImpl] entering isEnabled()
  826. 27.03 14:27:44 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  827. 27.03 14:27:44 TRACE [SCService] entering isEnabled()
  828. 27.03 14:27:44 TRACE [SCService] leaving isEnabled(). the result is: false
  829. 27.03 14:27:44 INFO  [CheckContainer] Barcode 3007763000008 has been received by visualisation
  830. 27.03 14:27:44 INFO  [CheckContainer] All conditions are checked. Current position will be added
  831. 27.03 14:27:44 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 00481; type: ProductPieceEntity; count: 1000; sum: 2515; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  832. 27.03 14:27:44 INFO  [SpeedLog] TP addPosition time = 17 ms
  833. 27.03 14:27:44 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,050 sec; average speed = 0,057 sec; position test num = 5; <50>
  834. 27.03 14:27:44 INFO  [CheckContainer] visualization will start searching product
  835. 27.03 14:27:44 INFO  [TechProcessImpl] TP searchProduct 3007763000008
  836. 27.03 14:27:44 INFO  [CatalogService] getGoodsByBarCode barcode = 3007763000008
  837. 27.03 14:27:44 INFO  [CatalogService] get product by barcode '3007763000008' result: ProductPieceEntity[name=Лимонад грушевый ароматный (Франция), item=07763]
  838. 27.03 14:27:44 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 3007763000008 time = 3 ms
  839. 27.03 14:27:44 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 3007763000008 time = 3 ms
  840. 27.03 14:27:44 INFO  [SpeedLog] getProductRestrictions  time = 10 ms
  841. 27.03 14:27:44 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 3007763000008 time = 17 ms
  842. 27.03 14:27:44 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 3007763000008 time = 17 ms
  843. 27.03 14:27:44 INFO  [SpeedLog] searchProduct time  time = 17 ms
  844. 27.03 14:27:44 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,037 sec; average speed = 0,138 sec; position test num = 6.0; product item 07763; <37>
  845. 27.03 14:27:44 INFO  [CommonLogger] busy = false
  846. 27.03 14:27:44 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  847. 27.03 14:27:44 INFO  [TextCustomerDisplayPluginAbstract] **********************
  848. 27.03 14:27:44 INFO  [TextCustomerDisplayPluginAbstract] *Лимонад гру 1 958.94*
  849. 27.03 14:27:44 INFO  [TextCustomerDisplayPluginAbstract] *Итого        3584.46*
  850. 27.03 14:27:44 INFO  [TextCustomerDisplayPluginAbstract] **********************
  851. 27.03 14:27:44 INFO  [TextCustomerDisplayPluginAbstract]
  852. 27.03 14:27:45 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  853. 27.03 14:27:45 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257709; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142735_8eba252a-be5f-40fc-a152-3866da29b987.ser"]) were persisted successfully!
  854. 27.03 14:27:45 INFO  [SpeedLog] cards-catalog [file-id: 257709, file-url: http://172.29.17.216:8091/cards/cards_20190327_142735_8eba252a-be5f-40fc-a152-3866da29b987.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 15
  855. 27.03 14:27:45 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,033 sec
  856. 27.03 14:27:45 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257781; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142736_fae3edf0-4bd5-49fe-b164-e6960cc40252.ser"]) were persisted successfully!
  857. 27.03 14:27:45 INFO  [SpeedLog] cards-catalog [file-id: 257781, file-url: http://172.29.17.216:8091/cards/cards_20190327_142736_fae3edf0-4bd5-49fe-b164-e6960cc40252.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 15
  858. 27.03 14:27:45 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,032 sec
  859. 27.03 14:27:46 INFO  [Scale] BarcodeScannerImpl - barcode = 4903496000003
  860. 27.03 14:27:46 INFO  [TechProcessImpl] eventBarcodeScanner 4903496000003
  861. 27.03 14:27:46 TRACE [MLServiceImpl] entering isEnabled()
  862. 27.03 14:27:46 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  863. 27.03 14:27:46 TRACE [SCService] entering isEnabled()
  864. 27.03 14:27:46 TRACE [SCService] leaving isEnabled(). the result is: false
  865. 27.03 14:27:46 TRACE [MLServiceImpl] entering isEnabled()
  866. 27.03 14:27:46 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  867. 27.03 14:27:46 TRACE [SCService] entering isEnabled()
  868. 27.03 14:27:46 TRACE [SCService] leaving isEnabled(). the result is: false
  869. 27.03 14:27:46 INFO  [CheckContainer] Barcode 4903496000003 has been received by visualisation
  870. 27.03 14:27:46 INFO  [CheckContainer] All conditions are checked. Current position will be added
  871. 27.03 14:27:46 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 07763; type: ProductPieceEntity; count: 1000; sum: 95894; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  872. 27.03 14:27:46 INFO  [SpeedLog] TP addPosition time = 20 ms
  873. 27.03 14:27:46 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,032 sec; average speed = 0,053 sec; position test num = 6; <32>
  874. 27.03 14:27:46 INFO  [CheckContainer] visualization will start searching product
  875. 27.03 14:27:46 INFO  [TechProcessImpl] TP searchProduct 4903496000003
  876. 27.03 14:27:46 INFO  [CatalogService] getGoodsByBarCode barcode = 4903496000003
  877. 27.03 14:27:46 INFO  [CatalogService] get product by barcode '4903496000003' result: ProductPieceEntity[name=Напиток вишневый густой (Япония), item=03496]
  878. 27.03 14:27:46 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 4903496000003 time = 1 ms
  879. 27.03 14:27:46 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 4903496000003 time = 1 ms
  880. 27.03 14:27:46 INFO  [SpeedLog] getProductRestrictions  time = 0 ms
  881. 27.03 14:27:46 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 4903496000003 time = 2 ms
  882. 27.03 14:27:46 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 4903496000003 time = 2 ms
  883. 27.03 14:27:46 INFO  [SpeedLog] searchProduct time  time = 2 ms
  884. 27.03 14:27:46 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,028 sec; average speed = 0,122 sec; position test num = 7.0; product item 03496; <28>
  885. 27.03 14:27:46 INFO  [CommonLogger] busy = false
  886. 27.03 14:27:46 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  887. 27.03 14:27:46 INFO  [TextCustomerDisplayPluginAbstract] **********************
  888. 27.03 14:27:46 INFO  [TextCustomerDisplayPluginAbstract] *Напиток виш 1 481.45*
  889. 27.03 14:27:46 INFO  [TextCustomerDisplayPluginAbstract] *Итого        4065.91*
  890. 27.03 14:27:46 INFO  [TextCustomerDisplayPluginAbstract] **********************
  891. 27.03 14:27:46 INFO  [TextCustomerDisplayPluginAbstract]
  892. 27.03 14:27:48 INFO  [Scale] BarcodeScannerImpl - barcode = 5004080000009
  893. 27.03 14:27:48 INFO  [TechProcessImpl] eventBarcodeScanner 5004080000009
  894. 27.03 14:27:48 TRACE [MLServiceImpl] entering isEnabled()
  895. 27.03 14:27:48 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  896. 27.03 14:27:48 TRACE [SCService] entering isEnabled()
  897. 27.03 14:27:48 TRACE [SCService] leaving isEnabled(). the result is: false
  898. 27.03 14:27:48 TRACE [MLServiceImpl] entering isEnabled()
  899. 27.03 14:27:48 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  900. 27.03 14:27:48 TRACE [SCService] entering isEnabled()
  901. 27.03 14:27:48 TRACE [SCService] leaving isEnabled(). the result is: false
  902. 27.03 14:27:48 INFO  [CheckContainer] Barcode 5004080000009 has been received by visualisation
  903. 27.03 14:27:48 INFO  [CheckContainer] All conditions are checked. Current position will be added
  904. 27.03 14:27:48 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 03496; type: ProductPieceEntity; count: 1000; sum: 48145; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  905. 27.03 14:27:48 INFO  [SpeedLog] TP addPosition time = 21 ms
  906. 27.03 14:27:48 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,050 sec; average speed = 0,053 sec; position test num = 7; <50>
  907. 27.03 14:27:48 INFO  [CheckContainer] visualization will start searching product
  908. 27.03 14:27:48 INFO  [TechProcessImpl] TP searchProduct 5004080000009
  909. 27.03 14:27:48 INFO  [CatalogService] getGoodsByBarCode barcode = 5004080000009
  910. 27.03 14:27:48 INFO  [CatalogService] get product by barcode '5004080000009' result: ProductPieceEntity[name=Шоколад лимонный сладкий (Великобритания), item=04080]
  911. 27.03 14:27:48 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5004080000009 time = 1 ms
  912. 27.03 14:27:48 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 5004080000009 time = 1 ms
  913. 27.03 14:27:48 INFO  [SpeedLog] getProductRestrictions  time = 2 ms
  914. 27.03 14:27:48 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 5004080000009 time = 4 ms
  915. 27.03 14:27:48 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5004080000009 time = 4 ms
  916. 27.03 14:27:48 INFO  [SpeedLog] searchProduct time  time = 4 ms
  917. 27.03 14:27:48 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,048 sec; average speed = 0,113 sec; position test num = 8.0; product item 04080; <48>
  918. 27.03 14:27:48 INFO  [CommonLogger] busy = false
  919. 27.03 14:27:48 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  920. 27.03 14:27:48 INFO  [TextCustomerDisplayPluginAbstract] **********************
  921. 27.03 14:27:48 INFO  [TextCustomerDisplayPluginAbstract] *Шоколад лим 1 531.63*
  922. 27.03 14:27:48 INFO  [TextCustomerDisplayPluginAbstract] *Итого        4597.54*
  923. 27.03 14:27:48 INFO  [TextCustomerDisplayPluginAbstract] **********************
  924. 27.03 14:27:48 INFO  [TextCustomerDisplayPluginAbstract]
  925. 27.03 14:27:50 INFO  [Scale] BarcodeScannerImpl - barcode = 5004844000009
  926. 27.03 14:27:50 INFO  [TechProcessImpl] eventBarcodeScanner 5004844000009
  927. 27.03 14:27:50 TRACE [MLServiceImpl] entering isEnabled()
  928. 27.03 14:27:50 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  929. 27.03 14:27:50 TRACE [SCService] entering isEnabled()
  930. 27.03 14:27:50 TRACE [SCService] leaving isEnabled(). the result is: false
  931. 27.03 14:27:50 TRACE [MLServiceImpl] entering isEnabled()
  932. 27.03 14:27:50 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  933. 27.03 14:27:50 TRACE [SCService] entering isEnabled()
  934. 27.03 14:27:50 TRACE [SCService] leaving isEnabled(). the result is: false
  935. 27.03 14:27:50 INFO  [CheckContainer] Barcode 5004844000009 has been received by visualisation
  936. 27.03 14:27:50 INFO  [CheckContainer] All conditions are checked. Current position will be added
  937. 27.03 14:27:50 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 04080; type: ProductPieceEntity; count: 1000; sum: 53163; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  938. 27.03 14:27:50 INFO  [SpeedLog] TP addPosition time = 20 ms
  939. 27.03 14:27:50 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,039 sec; average speed = 0,051 sec; position test num = 8; <39>
  940. 27.03 14:27:50 INFO  [CheckContainer] visualization will start searching product
  941. 27.03 14:27:50 INFO  [TechProcessImpl] TP searchProduct 5004844000009
  942. 27.03 14:27:50 INFO  [CatalogService] getGoodsByBarCode barcode = 5004844000009
  943. 27.03 14:27:50 INFO  [CatalogService] get product by barcode '5004844000009' result: ProductPieceEntity[name=Хлеб клубничный пряный (Великобритания), item=04844]
  944. 27.03 14:27:50 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 5004844000009 time = 6 ms
  945. 27.03 14:27:50 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 5004844000009 time = 6 ms
  946. 27.03 14:27:50 INFO  [SpeedLog] getProductRestrictions  time = 7 ms
  947. 27.03 14:27:50 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 5004844000009 time = 18 ms
  948. 27.03 14:27:50 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 5004844000009 time = 19 ms
  949. 27.03 14:27:50 INFO  [SpeedLog] searchProduct time  time = 19 ms
  950. 27.03 14:27:50 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,030 sec; average speed = 0,104 sec; position test num = 9.0; product item 04844; <30>
  951. 27.03 14:27:50 INFO  [CommonLogger] busy = false
  952. 27.03 14:27:50 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  953. 27.03 14:27:50 INFO  [TextCustomerDisplayPluginAbstract] **********************
  954. 27.03 14:27:50 INFO  [TextCustomerDisplayPluginAbstract] *Хлеб клубни 1 579.47*
  955. 27.03 14:27:50 INFO  [TextCustomerDisplayPluginAbstract] *Итого        5177.01*
  956. 27.03 14:27:50 INFO  [TextCustomerDisplayPluginAbstract] **********************
  957. 27.03 14:27:50 INFO  [TextCustomerDisplayPluginAbstract]
  958. 27.03 14:27:52 INFO  [Scale] BarcodeScannerImpl - barcode = 8402910000002
  959. 27.03 14:27:52 INFO  [TechProcessImpl] eventBarcodeScanner 8402910000002
  960. 27.03 14:27:52 TRACE [MLServiceImpl] entering isEnabled()
  961. 27.03 14:27:52 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  962. 27.03 14:27:52 TRACE [SCService] entering isEnabled()
  963. 27.03 14:27:52 TRACE [SCService] leaving isEnabled(). the result is: false
  964. 27.03 14:27:52 TRACE [MLServiceImpl] entering isEnabled()
  965. 27.03 14:27:52 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  966. 27.03 14:27:52 TRACE [SCService] entering isEnabled()
  967. 27.03 14:27:52 TRACE [SCService] leaving isEnabled(). the result is: false
  968. 27.03 14:27:52 INFO  [CheckContainer] Barcode 8402910000002 has been received by visualisation
  969. 27.03 14:27:52 INFO  [CheckContainer] All conditions are checked. Current position will be added
  970. 27.03 14:27:52 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 04844; type: ProductPieceEntity; count: 1000; sum: 57947; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  971. 27.03 14:27:52 INFO  [SpeedLog] TP addPosition time = 21 ms
  972. 27.03 14:27:52 INFO  [SpeedLog] (CHECK_CONTAINER) product and product container was found = 0,032 sec; average speed = 0,049 sec; position test num = 9; <32>
  973. 27.03 14:27:52 INFO  [CheckContainer] visualization will start searching product
  974. 27.03 14:27:52 INFO  [TechProcessImpl] TP searchProduct 8402910000002
  975. 27.03 14:27:52 INFO  [CatalogService] getGoodsByBarCode barcode = 8402910000002
  976. 27.03 14:27:52 INFO  [CatalogService] get product by barcode '8402910000002' result: ProductPieceEntity[name=Шоколад яблочный горький (Испания), item=02910]
  977. 27.03 14:27:52 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode, barcode = 8402910000002 time = 3 ms
  978. 27.03 14:27:52 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode before check restrictions, barcode = 8402910000002 time = 3 ms
  979. 27.03 14:27:52 INFO  [SpeedLog] getProductRestrictions  time = 3 ms
  980. 27.03 14:27:52 INFO  [SpeedLog] CatalogGoods: dataFinder.getProductByBarcode after check restrictions, barcode = 8402910000002 time = 7 ms
  981. 27.03 14:27:52 INFO  [SpeedLog] CatalogGoods#getGoodsByBarCode, barcode = 8402910000002 time = 7 ms
  982. 27.03 14:27:52 INFO  [SpeedLog] searchProduct time  time = 7 ms
  983. 27.03 14:27:52 INFO  [SpeedLog] (CHECK_CONTAINER) show product container time = 0,039 sec; average speed = 0,097 sec; position test num = 10.0; product item 02910; <39>
  984. 27.03 14:27:52 INFO  [CommonLogger] busy = false
  985. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  986. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] **********************
  987. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] *Шоколад ябл 1 263.70*
  988. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] *Итого        5440.71*
  989. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] **********************
  990. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract]
  991. 27.03 14:27:52 INFO  [TechProcessImpl] TechProcess add position id: null; number: 1; item: 02910; type: ProductPieceEntity; count: 1000; sum: 26370; sumDiscount: 0; dateTime: (NULL); num-in-original: null
  992. 27.03 14:27:52 INFO  [SpeedLog] TP addPosition time = 20 ms
  993. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  994. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] **********************
  995. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] *Шоколад ябл 1 263.70*
  996. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] *Итого        5440.71*
  997. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract] **********************
  998. 27.03 14:27:52 INFO  [TextCustomerDisplayPluginAbstract]
  999. 27.03 14:27:52 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1000. 27.03 14:27:52 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=32, command=kbdSubtotal]
  1001. 27.03 14:27:52 INFO  [CommonLogger] busy = true
  1002. 27.03 14:27:52 TRACE [TechProcessImpl] Start warn divide alco
  1003. 27.03 14:27:52 TRACE [TechProcessImpl] End warn devide alco
  1004. 27.03 14:27:52 INFO  [TechProcessImpl] goToDiscounts
  1005. 27.03 14:27:52 INFO  [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  1006. 27.03 14:27:52 INFO  [FiscalPrinter] getFactoryNum
  1007. 27.03 14:27:52 INFO  [FiscalPrinter] FactoryNum = 00000721661
  1008. 27.03 14:27:52 INFO  [FiscalPrinter] getRegNum
  1009. 27.03 14:27:52 INFO  [FiscalPrinter] RegNum = NFM.7216.61.0.1553620240053
  1010. 27.03 14:27:52 INFO  [FiscalPrinter] getEklzNum
  1011. 27.03 14:27:52 INFO  [FiscalPrinter] EklzNum = a131ccfd-1b7a-480b-bd6b-ca13344c98ac
  1012. 27.03 14:27:52 INFO  [FiscalPrinter] getVerBios
  1013. 27.03 14:27:52 INFO  [FiscalPrinter] VerBios = 27
  1014. 27.03 14:27:53 INFO  [LoyalProductFinder] Time finding items (10) = 105 ms
  1015. 27.03 14:27:53 INFO  [LoyalProductsConverter] Time searching loyal goods for 10 productItems = 105 ms
  1016. 27.03 14:27:53 INFO  [LoyalCalculatorConverter] Time converting 10 positions = 16 ms
  1017. 27.03 14:27:53 ERROR [LoyaltyRestrictionsServiceImpl] checkRestrictions: failed
  1018. org.springframework.web.client.HttpServerErrorException: 500 Server Error
  1019.     at org.springframework.web.client.DefaultResponseErrorHandler.handleError(DefaultResponseErrorHandler.java:94)
  1020.     at org.springframework.web.client.RestTemplate.handleResponse(RestTemplate.java:616)
  1021.     at org.springframework.web.client.RestTemplate.doExecute(RestTemplate.java:572)
  1022.     at org.springframework.web.client.RestTemplate.execute(RestTemplate.java:532)
  1023.     at org.springframework.web.client.RestTemplate.postForObject(RestTemplate.java:332)
  1024.     at ru.crystals.pos.loyalty.LoyaltyRestrictionsServiceImpl.checkRestrictions(LoyaltyRestrictionsServiceImpl.java:196)
  1025.     at ru.crystals.loyal.providers.ClassicSetLoyProvider.process(ClassicSetLoyProvider.java:91)
  1026.     at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:117)
  1027.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:763)
  1028.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:688)
  1029.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:800)
  1030.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:403)
  1031.     at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3726)
  1032.     at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3290)
  1033.     at ru.crystals.lenta.techprocess.LentaTechProcessImpl.subtotalScenary(LentaTechProcessImpl.java:91)
  1034.     at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1545)
  1035.     at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1516)
  1036.     at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1413)
  1037.     at ru.crystals.pos.visualization.components.MainWindow.subTotal(MainWindow.java:846)
  1038.     at ru.crystals.pos.visualization.Factory.eventFunctionKey(Factory.java:537)
  1039.     at ru.crystals.pos.techprocess.TechProcessImpl.eventFunctionKey(TechProcessImpl.java:4340)
  1040.     at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:165)
  1041.     at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  1042.     at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  1043.     at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  1044.     at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  1045.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  1046.     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  1047.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  1048.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  1049.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  1050.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  1051.     at java.lang.Thread.run(Thread.java:745)
  1052. 27.03 14:27:53 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1053. 27.03 14:27:53 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1054. 27.03 14:27:54 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1055. 27.03 14:27:54 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1056. 27.03 14:27:54 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The receipt does not have cards applied
  1057. 27.03 14:27:54 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1058. 27.03 14:27:54 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The receipt does not have cards applied
  1059. 27.03 14:27:54 TRACE [SCService] entering isEnabled()
  1060. 27.03 14:27:54 TRACE [SCService] leaving isEnabled(). the result is: false
  1061. 27.03 14:27:55 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  1062. 27.03 14:27:55 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 257988; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142752_201e116a-1dc9-4a90-8ed2-adae1219fef5.ser"]) were persisted successfully!
  1063. 27.03 14:27:55 INFO  [SpeedLog] cards-catalog [file-id: 257988, file-url: http://172.29.17.216:8091/cards/cards_20190327_142752_201e116a-1dc9-4a90-8ed2-adae1219fef5.ser] processing time (in millis): read-time: 16, processing-time: 4, last-id-update-time: 80
  1064. 27.03 14:27:55 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,100 sec; average speed = 0,034 sec
  1065. 27.03 14:27:55 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258059; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142752_2941fa8a-ca63-49f9-887c-5df874f29988.ser"]) were persisted successfully!
  1066. 27.03 14:27:55 INFO  [SpeedLog] cards-catalog [file-id: 258059, file-url: http://172.29.17.216:8091/cards/cards_20190327_142752_2941fa8a-ca63-49f9-887c-5df874f29988.ser] processing time (in millis): read-time: 12, processing-time: 9, last-id-update-time: 52
  1067. 27.03 14:27:55 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,073 sec; average speed = 0,035 sec
  1068. 27.03 14:27:55 DEBUG [LoymaxUtils] Loymax request:
  1069. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><Calculates><CalculateRequest PurchaseID="72160611553686074913" OperationID="d7dfbef307044388a4d8198d40f3227d" OperationDate="2019-03-27T14:27:54.924+03:00" ElementID="1"><Cheque ChequeDate="2019-03-27T14:27:37.362+03:00"><ChequeLine PosID="9430" Amount="43.63" Name="Хлеб банановый сладкий (Россия)" GoodsId="00074" Price="62.33" Quantity="1.000"/><ChequeLine PosID="9431" Amount="389.07" Name="Йогурт грушевый кисло-сладкий (Великобритания)" GoodsId="04662" Price="555.81" Quantity="1.000"/><ChequeLine PosID="9432" Amount="512.74" Name="Напиток виноградный сочный (Ю. Корея)" GoodsId="05526" Price="732.48" Quantity="1.000"/><ChequeLine PosID="9433" Amount="874.82" Name="Напиток малиновый густой (Бельгия)" GoodsId="09406" Price="1249.75" Quantity="1.000"/><ChequeLine PosID="9434" Amount="17.60" Name="Кефир лимонный густой (Россия)" GoodsId="00481" Price="25.15" Quantity="1.000"/><ChequeLine PosID="9435" Amount="671.26" Name="Лимонад грушевый ароматный (Франция)" GoodsId="07763" Price="958.94" Quantity="1.000"/><ChequeLine PosID="9436" Amount="337.01" Name="Напиток вишневый густой (Япония)" GoodsId="03496" Price="481.45" Quantity="1.000"/><ChequeLine PosID="9437" Amount="372.14" Name="Шоколад лимонный сладкий (Великобритания)" GoodsId="04080" Price="531.63" Quantity="1.000"/><ChequeLine PosID="9438" Amount="405.63" Name="Хлеб клубничный пряный (Великобритания)" GoodsId="04844" Price="579.47" Quantity="1.000"/><ChequeLine PosID="9439" Amount="184.59" Name="Шоколад яблочный горький (Испания)" GoodsId="02910" Price="263.70" Quantity="1.000"/></Cheque></CalculateRequest></Calculates></XMLRequest>
  1070.  
  1071. 27.03 14:27:56 ERROR [LoymaxEmulatorUtils] Failed to create unmarshaller!
  1072. java.lang.NullPointerException
  1073.     at javax.xml.validation.SchemaFactory.newSchema(SchemaFactory.java:670)
  1074.     at ru.crystals.pos.emulator.loymax.LoymaxEmulatorUtils.getLoymaxUnmarshaller(LoymaxEmulatorUtils.java:1011)
  1075.     at ru.crystals.pos.emulator.loymax.LoymaxEmulatorUtils.unmarshalObject(LoymaxEmulatorUtils.java:1030)
  1076.     at ru.crystals.pos.emulator.loymax.LoymaxMessageBodyReader.readFrom(LoymaxMessageBodyReader.java:40)
  1077.     at ru.crystals.pos.emulator.loymax.LoymaxMessageBodyReader.readFrom(LoymaxMessageBodyReader.java:21)
  1078.     at org.glassfish.jersey.message.internal.ReaderInterceptorExecutor$TerminalReaderInterceptor.invokeReadFrom(ReaderInterceptorExecutor.java:256)
  1079.     at org.glassfish.jersey.message.internal.ReaderInterceptorExecutor$TerminalReaderInterceptor.aroundReadFrom(ReaderInterceptorExecutor.java:235)
  1080.     at org.glassfish.jersey.message.internal.ReaderInterceptorExecutor.proceed(ReaderInterceptorExecutor.java:155)
  1081.     at org.glassfish.jersey.server.internal.MappableExceptionWrapperInterceptor.aroundReadFrom(MappableExceptionWrapperInterceptor.java:74)
  1082.     at org.glassfish.jersey.message.internal.ReaderInterceptorExecutor.proceed(ReaderInterceptorExecutor.java:155)
  1083.     at org.glassfish.jersey.message.internal.MessageBodyFactory.readFrom(MessageBodyFactory.java:1085)
  1084.     at org.glassfish.jersey.message.internal.InboundMessageContext.readEntity(InboundMessageContext.java:874)
  1085.     at org.glassfish.jersey.server.ContainerRequest.readEntity(ContainerRequest.java:271)
  1086.     at org.glassfish.jersey.server.internal.inject.EntityParamValueFactoryProvider$EntityValueFactory.provide(EntityParamValueFactoryProvider.java:96)
  1087.     at org.glassfish.jersey.server.spi.internal.ParamValueFactoryWithSource.provide(ParamValueFactoryWithSource.java:71)
  1088.     at org.glassfish.jersey.server.spi.internal.ParameterValueHelper.getParameterValues(ParameterValueHelper.java:94)
  1089.     at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$AbstractMethodParamInvoker.getParamValues(JavaResourceMethodDispatcherProvider.java:127)
  1090.     at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$TypeOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:205)
  1091.     at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99)
  1092.     at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389)
  1093.     at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347)
  1094.     at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102)
  1095.     at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326)
  1096.     at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271)
  1097.     at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267)
  1098.     at org.glassfish.jersey.internal.Errors.process(Errors.java:315)
  1099.     at org.glassfish.jersey.internal.Errors.process(Errors.java:297)
  1100.     at org.glassfish.jersey.internal.Errors.process(Errors.java:267)
  1101.     at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317)
  1102.     at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305)
  1103.     at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154)
  1104.     at org.glassfish.jersey.grizzly2.httpserver.GrizzlyHttpContainer.service(GrizzlyHttpContainer.java:384)
  1105.     at org.glassfish.grizzly.http.server.HttpHandler$1.run(HttpHandler.java:224)
  1106.     at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:593)
  1107.     at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.run(AbstractThreadPool.java:573)
  1108.     at java.lang.Thread.run(Thread.java:745)
  1109. 27.03 14:27:56 INFO  [TransferManager] Nothing yet not processed on server to resend
  1110. 27.03 14:27:56 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  1111. 27.03 14:27:56 INFO  [DocumentSender] ping = true
  1112. 27.03 14:27:56 DEBUG [LoymaxUtils] Loymax response:
  1113. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><Calculates><CalculateResponse AvailableAmount="3808.49" AvailableBonusAmount="0" ErrorCode="0"><Cheque ChequeDate="2019-03-27T14:27:37.362+03:00"><ChequeLine PosID="9430" Amount="43.63" Name="Хлеб банановый сладкий (Россия)" GoodsId="00074" Price="62.33" Quantity="1.000" Discount="0"/><ChequeLine PosID="9431" Amount="389.07" Name="Йогурт грушевый кисло-сладкий (Великобритания)" GoodsId="04662" Price="555.81" Quantity="1.000" Discount="0"/><ChequeLine PosID="9432" Amount="512.74" Name="Напиток виноградный сочный (Ю. Корея)" GoodsId="05526" Price="732.48" Quantity="1.000" Discount="0"/><ChequeLine PosID="9433" Amount="874.82" Name="Напиток малиновый густой (Бельгия)" GoodsId="09406" Price="1249.75" Quantity="1.000" Discount="0"/><ChequeLine PosID="9434" Amount="17.60" Name="Кефир лимонный густой (Россия)" GoodsId="00481" Price="25.15" Quantity="1.000" Discount="0"/><ChequeLine PosID="9435" Amount="671.26" Name="Лимонад грушевый ароматный (Франция)" GoodsId="07763" Price="958.94" Quantity="1.000" Discount="0"/><ChequeLine PosID="9436" Amount="337.01" Name="Напиток вишневый густой (Япония)" GoodsId="03496" Price="481.45" Quantity="1.000" Discount="0"/><ChequeLine PosID="9437" Amount="372.14" Name="Шоколад лимонный сладкий (Великобритания)" GoodsId="04080" Price="531.63" Quantity="1.000" Discount="0"/><ChequeLine PosID="9438" Amount="405.63" Name="Хлеб клубничный пряный (Великобритания)" GoodsId="04844" Price="579.47" Quantity="1.000" Discount="0"/><ChequeLine PosID="9439" Amount="184.59" Name="Шоколад яблочный горький (Испания)" GoodsId="02910" Price="263.70" Quantity="1.000" Discount="0"/></Cheque></CalculateResponse></Calculates></XMLResponse>
  1114.  
  1115. 27.03 14:27:56 INFO  [SetMachineLoyProvider] looking-up the SM Service
  1116. 27.03 14:27:56 INFO  [SMServiceImpl] updating settings!
  1117. 27.03 14:27:56 TRACE [SMServiceImpl] entering readLocalSettingsIntoObject()
  1118. 27.03 14:27:56 WARN  [SMServiceImpl] unknown property in the settings file was detected: key: "authToken"; value: f728b4fa-4248-5e3a-0a5d-2f346baa9455
  1119. 27.03 14:27:56 TRACE [SMServiceImpl] leaving readLocalSettingsIntoObject(). the result is: sm-conn [calc-url: "null"; feedback-url: "null"; cc-timeout: 2000; cr-timeout: 2000]
  1120. 27.03 14:27:56 TRACE [SMServiceImpl] settings were reloaded. The result is: sm-conn [calc-url: "null"; feedback-url: "null"; cc-timeout: 2000; cr-timeout: 2000]
  1121. 27.03 14:27:56 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1122. 27.03 14:27:56 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1123. 27.03 14:27:56 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1124. 27.03 14:27:56 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1125. 27.03 14:27:56 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1126. 27.03 14:27:56 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1127. 27.03 14:27:57 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1128. 27.03 14:27:57 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1129. 27.03 14:27:57 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1130. 27.03 14:27:57 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1131. 27.03 14:27:57 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1132. 27.03 14:27:57 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The receipt does not have cards applied
  1133. 27.03 14:27:57 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1134. 27.03 14:27:57 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The receipt does not have cards applied
  1135. 27.03 14:27:57 INFO  [LoyalServiceImpl] Discount calculation (Purchase #6; Positions: 10
  1136. 27.03 14:28:00 INFO  [GoodsFeedbackTask] looking up <PropertiesManager>
  1137. 27.03 14:28:01 WARN  [LoyalServiceImpl] extractNonDiscountRelatedFields: the argument is NULL! Null will be returned!
  1138. 27.03 14:28:02 DEBUG [TechProcessImpl] Server online mode
  1139. 27.03 14:28:03 INFO  [LoyalBridgeServiceImpl] Total time of discount calculation = 10950 ms
  1140. 27.03 14:28:03 INFO  [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  1141. 27.03 14:28:03 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  1142. 27.03 14:28:03 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1143. 27.03 14:28:03 INFO  [TextCustomerDisplayPluginAbstract] *Скидка       1632.22*
  1144. 27.03 14:28:03 INFO  [TextCustomerDisplayPluginAbstract] *Итого        3808.49*
  1145. 27.03 14:28:03 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1146. 27.03 14:28:03 INFO  [TextCustomerDisplayPluginAbstract]
  1147. 27.03 14:28:03 INFO  [SpeedLog] TechProcess discounts processing on cheque with 10 positions [sale] time = 11067 ms
  1148. 27.03 14:28:03 INFO  [CommonLogger] value = 163222
  1149. 27.03 14:28:04 TRACE [TechProcessImpl] Start warn divide amount
  1150. 27.03 14:28:04 INFO  [TechProcessImpl] goToPayments
  1151. 27.03 14:28:04 INFO  [CheckContainer] startPayments() surcharge = 380849
  1152. 27.03 14:28:04 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  1153. 27.03 14:28:04 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1154. 27.03 14:28:04 INFO  [TextCustomerDisplayPluginAbstract] *Скидка       1632.22*
  1155. 27.03 14:28:04 INFO  [TextCustomerDisplayPluginAbstract] *Итого        3808.49*
  1156. 27.03 14:28:04 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1157. 27.03 14:28:04 INFO  [TextCustomerDisplayPluginAbstract]
  1158. 27.03 14:28:04 INFO  [CheckContainer] isPaymentComplete = false
  1159. 27.03 14:28:04 INFO  [CheckContainer] prevState = ADD_POSITION
  1160. 27.03 14:28:04 INFO  [CheckContainer] paid = 0
  1161. 27.03 14:28:04 INFO  [CheckContainer] surcharge = 380849
  1162. 27.03 14:28:04 INFO  [CheckContainer] getPaymentFiscalizeError = NONE
  1163. 27.03 14:28:04 INFO  [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.cash.CashPaymentContainer
  1164. 27.03 14:28:04 INFO  [CommonLogger] busy = false
  1165. 27.03 14:28:04 INFO  [SpeedLog] (SUBTOTAL) time = 11,521;
  1166. 27.03 14:28:04 INFO  [CommonLogger] busy = false
  1167. 27.03 14:28:04 INFO  [TechProcessImpl] eventFunctionKey: FunctionKey [scanCode=80, command=kbdMenu5]
  1168. 27.03 14:28:04 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1169. 27.03 14:28:04 INFO  [CommonLogger] Menu command: Типы оплат (class ru.crystals.pos.visualization.menu.commands.PaymentTypeCommand)
  1170. 27.03 14:28:05 ERROR [CommonLogger] isAllowedToApplySelectedPayment: the  selected payment [id: BonusCardPaymentEntity; bank-id: ] is NOT allowed to be used (maybe it was not configured properly yet)
  1171. 27.03 14:28:05 ERROR [CommonLogger] isAllowedToApplySelectedPayment: the  selected payment [id: CFTGiftCardPaymentEntity; bank-id: ] is NOT allowed to be used (maybe it was not configured properly yet)
  1172. 27.03 14:28:05 INFO  [CardsFilesReader2] [7] new card catalogs were detected on the server, and [7] of them we are about to process right now
  1173. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258250; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_f94d6719-3246-4ea7-a83e-581b6a61c483.ser"]) were persisted successfully!
  1174. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258250, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_f94d6719-3246-4ea7-a83e-581b6a61c483.ser] processing time (in millis): read-time: 5, processing-time: 12, last-id-update-time: 22
  1175. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,039 sec; average speed = 0,035 sec
  1176. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258266; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_4ddea84f-85ff-49e7-a53a-4248e6b034aa.ser"]) were persisted successfully!
  1177. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258266, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_4ddea84f-85ff-49e7-a53a-4248e6b034aa.ser] processing time (in millis): read-time: 6, processing-time: 5, last-id-update-time: 27
  1178. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,038 sec; average speed = 0,035 sec
  1179. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258345; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_54c40cec-8273-4953-bf75-99f7ae6a4036.ser"]) were persisted successfully!
  1180. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258345, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_54c40cec-8273-4953-bf75-99f7ae6a4036.ser] processing time (in millis): read-time: 2, processing-time: 19, last-id-update-time: 14
  1181. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,035 sec; average speed = 0,035 sec
  1182. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258363; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_eb9a111d-20c7-44a6-aace-0c2dde2d0b1c.ser"]) were persisted successfully!
  1183. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258363, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_eb9a111d-20c7-44a6-aace-0c2dde2d0b1c.ser] processing time (in millis): read-time: 1, processing-time: 14, last-id-update-time: 14
  1184. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,030 sec; average speed = 0,035 sec
  1185. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258457; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_6cf36ca2-3db0-4056-bba7-573fed804cc2.ser"]) were persisted successfully!
  1186. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258457, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_6cf36ca2-3db0-4056-bba7-573fed804cc2.ser] processing time (in millis): read-time: 2, processing-time: 12, last-id-update-time: 16
  1187. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,030 sec; average speed = 0,035 sec
  1188. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258477; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_b21940ac-ca58-4a59-8106-3e3eca798e04.ser"]) were persisted successfully!
  1189. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258477, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_b21940ac-ca58-4a59-8106-3e3eca798e04.ser] processing time (in millis): read-time: 2, processing-time: 7, last-id-update-time: 19
  1190. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,029 sec; average speed = 0,035 sec
  1191. 27.03 14:28:05 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258546; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142801_8cd0f6a3-3219-4b9c-8d03-69a786fdb26c.ser"]) were persisted successfully!
  1192. 27.03 14:28:05 INFO  [SpeedLog] cards-catalog [file-id: 258546, file-url: http://172.29.17.216:8091/cards/cards_20190327_142801_8cd0f6a3-3219-4b9c-8d03-69a786fdb26c.ser] processing time (in millis): read-time: 1, processing-time: 14, last-id-update-time: 17
  1193. 27.03 14:28:05 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,032 sec; average speed = 0,034 sec
  1194. 27.03 14:28:05 ERROR [CommonLogger] isAllowedToApplySelectedPayment: the  selected payment [id: CFTEGCPaymentEntity; bank-id: ] is NOT allowed to be used (maybe it was not configured properly yet)
  1195. 27.03 14:28:06 ERROR [CommonLogger] isAllowedToApplySelectedPayment: the  selected payment [id: ChildrenCardPaymentEntity; bank-id: Эмулятор детских карт] is NOT allowed to be used (maybe it was not configured properly yet)
  1196. 27.03 14:28:06 INFO  [SpeedLog] CheckPaymentTypesContainer#getPaymentStrings return   Наличные payment types in  time = 1920 ms
  1197. 27.03 14:28:06 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=40, command=kbdDown]
  1198. 27.03 14:28:06 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=10, command=kbdEnter]
  1199. 27.03 14:28:06 INFO  [CheckContainer] isPaymentComplete = false
  1200. 27.03 14:28:06 INFO  [CheckContainer] prevState = ADD_PAYMENT
  1201. 27.03 14:28:06 INFO  [CheckContainer] paid = 0
  1202. 27.03 14:28:06 INFO  [CheckContainer] surcharge = 380849
  1203. 27.03 14:28:06 INFO  [CheckContainer] getPaymentFiscalizeError = NONE
  1204. 27.03 14:28:06 INFO  [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.bankcard.integration.BankCardPaymentPluginAdapter
  1205. 27.03 14:28:06 INFO  [AbstractPaymentModel] Setting payment: PaymentEntity{, number=null, dateCreate=null, dateCommit=null, sumPay=null, sumPayBaseCurrency=null, purchase=null, paymentType='BankCardPaymentEntity', baseCurrency='null', paymentStatus=null, currency='null', successProcessed=true, originalPaymentNumber=null}
  1206. 27.03 14:28:06 INFO  [CommonLogger] busy = true
  1207. 27.03 14:28:06 TRACE [TechProcessImpl] Start warn divide alco
  1208. 27.03 14:28:06 TRACE [TechProcessImpl] End warn devide alco
  1209. 27.03 14:28:07 INFO  [TechProcessImpl] goToDiscounts
  1210. 27.03 14:28:07 INFO  [LoyalBridgeServiceImpl] --------------- begin discount calculation ---------------
  1211. 27.03 14:28:07 INFO  [FiscalPrinter] getFactoryNum
  1212. 27.03 14:28:07 INFO  [FiscalPrinter] FactoryNum = 00000721661
  1213. 27.03 14:28:07 INFO  [FiscalPrinter] getRegNum
  1214. 27.03 14:28:07 INFO  [FiscalPrinter] RegNum = NFM.7216.61.0.1553620240053
  1215. 27.03 14:28:07 INFO  [FiscalPrinter] getEklzNum
  1216. 27.03 14:28:07 INFO  [FiscalPrinter] EklzNum = a131ccfd-1b7a-480b-bd6b-ca13344c98ac
  1217. 27.03 14:28:07 INFO  [FiscalPrinter] getVerBios
  1218. 27.03 14:28:07 INFO  [FiscalPrinter] VerBios = 27
  1219. 27.03 14:28:07 INFO  [LoyalProductFinder] Time finding items (10) = 10 ms
  1220. 27.03 14:28:07 INFO  [LoyalProductsConverter] Time searching loyal goods for 10 productItems = 10 ms
  1221. 27.03 14:28:07 INFO  [LoyalCalculatorConverter] Time converting 10 positions = 2 ms
  1222. 27.03 14:28:07 ERROR [LoyaltyRestrictionsServiceImpl] checkRestrictions: failed
  1223. org.springframework.web.client.HttpServerErrorException: 500 Server Error
  1224.     at org.springframework.web.client.DefaultResponseErrorHandler.handleError(DefaultResponseErrorHandler.java:94)
  1225.     at org.springframework.web.client.RestTemplate.handleResponse(RestTemplate.java:616)
  1226.     at org.springframework.web.client.RestTemplate.doExecute(RestTemplate.java:572)
  1227.     at org.springframework.web.client.RestTemplate.execute(RestTemplate.java:532)
  1228.     at org.springframework.web.client.RestTemplate.postForObject(RestTemplate.java:332)
  1229.     at ru.crystals.pos.loyalty.LoyaltyRestrictionsServiceImpl.checkRestrictions(LoyaltyRestrictionsServiceImpl.java:196)
  1230.     at ru.crystals.loyal.providers.ClassicSetLoyProvider.process(ClassicSetLoyProvider.java:91)
  1231.     at ru.crystals.loyal.providers.LoyProvidersRegistryImpl.process(LoyProvidersRegistryImpl.java:117)
  1232.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.calcDiscounts(LoyalServiceImpl.java:763)
  1233.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doLoyalty(LoyalServiceImpl.java:688)
  1234.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.doDiscount(LoyalServiceImpl.java:800)
  1235.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.doDiscount(LoyalBridgeServiceImpl.java:403)
  1236.     at ru.crystals.pos.techprocess.TechProcessImpl.applyDiscountsAndGetResult(TechProcessImpl.java:3726)
  1237.     at ru.crystals.pos.techprocess.TechProcessImpl.subtotalScenary(TechProcessImpl.java:3290)
  1238.     at ru.crystals.lenta.techprocess.LentaTechProcessImpl.subtotalScenary(LentaTechProcessImpl.java:91)
  1239.     at ru.crystals.pos.visualization.check.CheckContainer.startSubtotalScenary(CheckContainer.java:1545)
  1240.     at ru.crystals.pos.visualization.check.CheckContainer.subtotalProcessPurchaseState(CheckContainer.java:1528)
  1241.     at ru.crystals.pos.visualization.check.CheckContainer.subtotal(CheckContainer.java:1424)
  1242.     at ru.crystals.pos.visualization.check.CheckPaymentTypesContainer.lambda$null$0(CheckPaymentTypesContainer.java:276)
  1243.     at ru.crystals.pos.loyal.cash.service.LoyalServiceImpl.processRecalculateAfterPaymentTypeChange(LoyalServiceImpl.java:2204)
  1244.     at ru.crystals.pos.loyal.bridge.service.LoyalBridgeServiceImpl.processRecalculateAfterPaymentTypeChange(LoyalBridgeServiceImpl.java:759)
  1245.     at ru.crystals.pos.visualization.check.CheckPaymentTypesContainer.lambda$enter$1(CheckPaymentTypesContainer.java:272)
  1246.     at java.util.Optional.ifPresent(Optional.java:159)
  1247.     at ru.crystals.pos.visualization.check.CheckPaymentTypesContainer.enter(CheckPaymentTypesContainer.java:272)
  1248.     at ru.crystals.pos.visualization.components.CompositeContainer.enter(CompositeContainer.java:97)
  1249.     at ru.crystals.pos.visualization.check.CheckContainer.enter(CheckContainer.java:1908)
  1250.     at ru.crystals.pos.visualization.components.MainWindow.enter(MainWindow.java:1120)
  1251.     at ru.crystals.pos.visualization.Factory.eventControlKey(Factory.java:481)
  1252.     at ru.crystals.pos.techprocess.TechProcessImpl.eventControlKey(TechProcessImpl.java:4311)
  1253.     at ru.crystals.pos.CashEventSource.sentOldKey(CashEventSource.java:167)
  1254.     at ru.crystals.pos.CashEventSource.process(CashEventSource.java:138)
  1255.     at ru.crystals.pos.CashEventSource.processEvent(CashEventSource.java:118)
  1256.     at ru.crystals.pos.CashEventSource.run(CashEventSource.java:101)
  1257.     at ru.crystals.pos.InternalCashPoolExecutorImpl.lambda$submit$1(InternalCashPoolExecutorImpl.java:120)
  1258.     at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
  1259.     at java.util.concurrent.FutureTask.run(FutureTask.java:266)
  1260.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
  1261.     at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
  1262.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  1263.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  1264.     at java.lang.Thread.run(Thread.java:745)
  1265. 27.03 14:28:07 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1266. 27.03 14:28:07 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1267. 27.03 14:28:07 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1268. 27.03 14:28:07 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1269. 27.03 14:28:07 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The receipt does not have cards applied
  1270. 27.03 14:28:07 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1271. 27.03 14:28:07 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The receipt does not have cards applied
  1272. 27.03 14:28:07 TRACE [SCService] entering isEnabled()
  1273. 27.03 14:28:07 TRACE [SCService] leaving isEnabled(). the result is: false
  1274. 27.03 14:28:07 DEBUG [LoymaxUtils] Loymax request:
  1275. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><CancelPurchases><CancelPurchaseRequest PurchaseID="72160611553686074913" OperationID="dc46120ea6a64fe0b81c81041f60795f" OperationDate="2019-03-27T14:28:07.296+03:00" ElementID="1"/></CancelPurchases></XMLRequest>
  1276.  
  1277. 27.03 14:28:07 DEBUG [LoymaxUtils] Loymax response:
  1278. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><CancelPurchases><CancelPurchaseResponse PurchaseID="72160611553686074913" OperationID="dc46120ea6a64fe0b81c81041f60795f" OperationDate="2019-03-27T14:28:07.296+03:00" ErrorCode="0"/></CancelPurchases></XMLResponse>
  1279.  
  1280. 27.03 14:28:07 DEBUG [LoymaxUtils] Loymax request:
  1281. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><Calculates><CalculateRequest PurchaseID="72160611553686087585" OperationID="491bb1d36e7243ec88dde22b3bb3e446" OperationDate="2019-03-27T14:28:07.585+03:00" ElementID="1"><Cheque ChequeDate="2019-03-27T14:27:37.362+03:00"><ChequeLine PosID="9430" Amount="43.63" Name="Хлеб банановый сладкий (Россия)" GoodsId="00074" Price="62.33" Quantity="1.000"/><ChequeLine PosID="9431" Amount="389.07" Name="Йогурт грушевый кисло-сладкий (Великобритания)" GoodsId="04662" Price="555.81" Quantity="1.000"/><ChequeLine PosID="9432" Amount="512.74" Name="Напиток виноградный сочный (Ю. Корея)" GoodsId="05526" Price="732.48" Quantity="1.000"/><ChequeLine PosID="9433" Amount="874.82" Name="Напиток малиновый густой (Бельгия)" GoodsId="09406" Price="1249.75" Quantity="1.000"/><ChequeLine PosID="9434" Amount="17.60" Name="Кефир лимонный густой (Россия)" GoodsId="00481" Price="25.15" Quantity="1.000"/><ChequeLine PosID="9435" Amount="671.26" Name="Лимонад грушевый ароматный (Франция)" GoodsId="07763" Price="958.94" Quantity="1.000"/><ChequeLine PosID="9436" Amount="337.01" Name="Напиток вишневый густой (Япония)" GoodsId="03496" Price="481.45" Quantity="1.000"/><ChequeLine PosID="9437" Amount="372.14" Name="Шоколад лимонный сладкий (Великобритания)" GoodsId="04080" Price="531.63" Quantity="1.000"/><ChequeLine PosID="9438" Amount="405.63" Name="Хлеб клубничный пряный (Великобритания)" GoodsId="04844" Price="579.47" Quantity="1.000"/><ChequeLine PosID="9439" Amount="184.59" Name="Шоколад яблочный горький (Испания)" GoodsId="02910" Price="263.70" Quantity="1.000"/></Cheque></CalculateRequest></Calculates></XMLRequest>
  1282.  
  1283. 27.03 14:28:07 ERROR [ConnectionEmulatorUtils] toString(XmlEmulatorException): the argument is null
  1284. 27.03 14:28:07 DEBUG [LoymaxUtils] Loymax response:
  1285. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><Calculates><CalculateResponse AvailableAmount="3808.49" AvailableBonusAmount="0" ErrorCode="0"><Cheque ChequeDate="2019-03-27T14:27:37.362+03:00"><ChequeLine PosID="9430" Amount="43.63" Name="Хлеб банановый сладкий (Россия)" GoodsId="00074" Price="62.33" Quantity="1.000" Discount="0"/><ChequeLine PosID="9431" Amount="389.07" Name="Йогурт грушевый кисло-сладкий (Великобритания)" GoodsId="04662" Price="555.81" Quantity="1.000" Discount="0"/><ChequeLine PosID="9432" Amount="512.74" Name="Напиток виноградный сочный (Ю. Корея)" GoodsId="05526" Price="732.48" Quantity="1.000" Discount="0"/><ChequeLine PosID="9433" Amount="874.82" Name="Напиток малиновый густой (Бельгия)" GoodsId="09406" Price="1249.75" Quantity="1.000" Discount="0"/><ChequeLine PosID="9434" Amount="17.60" Name="Кефир лимонный густой (Россия)" GoodsId="00481" Price="25.15" Quantity="1.000" Discount="0"/><ChequeLine PosID="9435" Amount="671.26" Name="Лимонад грушевый ароматный (Франция)" GoodsId="07763" Price="958.94" Quantity="1.000" Discount="0"/><ChequeLine PosID="9436" Amount="337.01" Name="Напиток вишневый густой (Япония)" GoodsId="03496" Price="481.45" Quantity="1.000" Discount="0"/><ChequeLine PosID="9437" Amount="372.14" Name="Шоколад лимонный сладкий (Великобритания)" GoodsId="04080" Price="531.63" Quantity="1.000" Discount="0"/><ChequeLine PosID="9438" Amount="405.63" Name="Хлеб клубничный пряный (Великобритания)" GoodsId="04844" Price="579.47" Quantity="1.000" Discount="0"/><ChequeLine PosID="9439" Amount="184.59" Name="Шоколад яблочный горький (Испания)" GoodsId="02910" Price="263.70" Quantity="1.000" Discount="0"/></Cheque></CalculateResponse></Calculates></XMLResponse>
  1286.  
  1287. 27.03 14:28:07 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1288. 27.03 14:28:07 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1289. 27.03 14:28:07 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1290. 27.03 14:28:07 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1291. 27.03 14:28:07 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1292. 27.03 14:28:07 WARN  [AdvancedMessagesActionResult] Calculator returns empty result. Do not generate slips or messages
  1293. 27.03 14:28:07 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1294. 27.03 14:28:07 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount start ============
  1295. 27.03 14:28:07 INFO  [DoProcessing] GeneralInteractionMethod: MAXIMUM_DISCOUNT
  1296. 27.03 14:28:07 INFO  [DoProcessing] ============ LoyalCalculation: doDiscount success ============
  1297. 27.03 14:28:07 TRACE [MLServiceImpl] entering getMlCardNo(Purchase). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1298. 27.03 14:28:07 TRACE [MLServiceImpl] leaving getMlCardNo(Purchase). The receipt does not have cards applied
  1299. 27.03 14:28:07 TRACE [MLServiceImpl] entering getMlCouponNo(PurchaseEntity). The argument is: Purchase:[number=6;dateCreate=2019-03-27 14:27:37.362;operationType=true;checkSum=544071;discount=0]
  1300. 27.03 14:28:07 TRACE [MLServiceImpl] leaving getMlCouponNo(PurchaseEntity). The receipt does not have cards applied
  1301. 27.03 14:28:07 INFO  [LoyalServiceImpl] Discount calculation (Purchase #6; Positions: 10
  1302. 27.03 14:28:08 INFO  [TransferManager] Nothing yet not processed on server to resend
  1303. 27.03 14:28:13 INFO  [LoyalBridgeServiceImpl] Total time of discount calculation = 5992 ms
  1304. 27.03 14:28:13 INFO  [LoyalBridgeServiceImpl] ---------------- end discount calculation ----------------
  1305. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  1306. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1307. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] *Скидка       1632.22*
  1308. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] *Итого        3808.49*
  1309. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1310. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract]
  1311. 27.03 14:28:13 INFO  [SpeedLog] TechProcess discounts processing on cheque with 10 positions [sale] time = 6068 ms
  1312. 27.03 14:28:13 INFO  [CommonLogger] value = 163222
  1313. 27.03 14:28:13 TRACE [TechProcessImpl] Start warn divide amount
  1314. 27.03 14:28:13 INFO  [TechProcessImpl] goToPayments
  1315. 27.03 14:28:13 INFO  [CheckContainer] startPayments() surcharge = 380849
  1316. 27.03 14:28:13 INFO  [CheckContainer] isPaymentComplete = false
  1317. 27.03 14:28:13 INFO  [CheckContainer] prevState = SELECT_PAYMENT_TYPE
  1318. 27.03 14:28:13 INFO  [CheckContainer] paid = 0
  1319. 27.03 14:28:13 INFO  [CheckContainer] surcharge = 380849
  1320. 27.03 14:28:13 INFO  [CheckContainer] getPaymentFiscalizeError = NONE
  1321. 27.03 14:28:13 INFO  [CheckContainer] Current payment container is ru.crystals.pos.visualization.payments.bankcard.integration.BankCardPaymentPluginAdapter
  1322. 27.03 14:28:13 INFO  [AbstractPaymentModel] Setting payment: PaymentEntity{, number=null, dateCreate=null, dateCommit=null, sumPay=null, sumPayBaseCurrency=null, purchase=null, paymentType='BankCardPaymentEntity', baseCurrency='null', paymentStatus=null, currency='null', successProcessed=true, originalPaymentNumber=null}
  1323. 27.03 14:28:13 INFO  [CommonLogger] busy = false
  1324. 27.03 14:28:13 INFO  [SpeedLog] (SUBTOTAL) time = 6,192;
  1325. 27.03 14:28:13 INFO  [CommonLogger] busy = false
  1326. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  1327. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1328. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] *Скидка       1632.22*
  1329. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] *Итого        3808.49*
  1330. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1331. 27.03 14:28:13 INFO  [TextCustomerDisplayPluginAbstract]
  1332. 27.03 14:28:14 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  1333. 27.03 14:28:14 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1334. 27.03 14:28:14 INFO  [TextCustomerDisplayPluginAbstract] *Банковская карта    *
  1335. 27.03 14:28:14 INFO  [TextCustomerDisplayPluginAbstract] *К оплате     3808.49*
  1336. 27.03 14:28:14 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1337. 27.03 14:28:14 INFO  [TextCustomerDisplayPluginAbstract]
  1338. 27.03 14:28:14 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - false
  1339. 27.03 14:28:14 INFO  [BankCardPaymentController] sale: amount = 380849
  1340. 27.03 14:28:14 INFO  [BankImpl] Sale amount=380849 (Сбербанк)
  1341. 27.03 14:28:14 INFO  [AbstractFileBasedBank] Operation SALE will be processed
  1342. 27.03 14:28:14 INFO  [AbstractFileBasedBank] Executable command: /mnt/sda1/tce/storage/crystal-cash/banks/sberbank/linux/sb_pilot 1 380849 0 0
  1343. 27.03 14:28:15 INFO  [HttpClient] Set url - http://172.29.17.219:8090/SetXRMI/TransportServlet
  1344. 27.03 14:28:15 INFO  [TransferManager] OD found 0 documents to register
  1345. 27.03 14:28:15 INFO  [DocumentSender] OD found 0 transactions to register
  1346. 27.03 14:28:15 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  1347. 27.03 14:28:15 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258620; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142805_34e27a1a-d77a-4e84-aa95-e52205ffcab3.ser"]) were persisted successfully!
  1348. 27.03 14:28:15 INFO  [SpeedLog] cards-catalog [file-id: 258620, file-url: http://172.29.17.216:8091/cards/cards_20190327_142805_34e27a1a-d77a-4e84-aa95-e52205ffcab3.ser] processing time (in millis): read-time: 26, processing-time: 9, last-id-update-time: 18
  1349. 27.03 14:28:15 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,053 sec; average speed = 0,035 sec
  1350. 27.03 14:28:15 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258692; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142807_b41aad18-23e5-492a-a734-b47c28db5601.ser"]) were persisted successfully!
  1351. 27.03 14:28:15 INFO  [SpeedLog] cards-catalog [file-id: 258692, file-url: http://172.29.17.216:8091/cards/cards_20190327_142807_b41aad18-23e5-492a-a734-b47c28db5601.ser] processing time (in millis): read-time: 5, processing-time: 6, last-id-update-time: 19
  1352. 27.03 14:28:15 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,031 sec; average speed = 0,035 sec
  1353. 27.03 14:28:20 INFO  [TransferManager] Nothing yet not processed on server to resend
  1354. 27.03 14:28:21 INFO  [AbstractFileBasedBank] runExecutableAndGetResponseData Operation processed.
  1355. 27.03 14:28:21 INFO  [Bank] Response file:
  1356. 0,ОДОБРЕНО
  1357. ************3071 (masked by cash)
  1358. 03/19
  1359. 269026
  1360. 649616
  1361. VISA
  1362. 0
  1363. 10000001
  1364. 20190327142815
  1365. 871181727877
  1366. null
  1367. 10000001
  1368. 27.03 14:28:21 INFO  [AbstractFileBasedBank] Operation SALE completed
  1369. 27.03 14:28:21 INFO  [AbstractFileBasedBank] fillCommonFields finished
  1370. 27.03 14:28:21 INFO  [AbstractFileBasedBank] fillSpecificFields finished
  1371. 27.03 14:28:21 INFO  [AbstractFileBasedBank] Slip file:
  1372.             СберБанк России
  1373. Номер терминала                 10000001
  1374. Дата и время         27.03.2019 14:28:15
  1375. Тип операции                     ПРОДАЖА
  1376. Сумма операции:                  3808,49
  1377. Номер карты             ************3071
  1378. Тип карты                           VISA
  1379. Номер ссылки (RRN)          871181727877
  1380. Номер транзакции                       2
  1381. Код авторизации                   269026
  1382. Код ответа                            00
  1383.  
  1384.  
  1385.                 ОДОБРЕНО
  1386. AID                       A0000000041010
  1387.  
  1388.  
  1389. ________________________________________
  1390.             Подпись кассира
  1391. ========================================
  1392.  
  1393.  
  1394.  
  1395.             СберБанк России
  1396. Номер терминала                 10000001
  1397. Дата и время         27.03.2019 14:28:15
  1398. Тип операции                     ПРОДАЖА
  1399. Сумма операции:                  3808,49
  1400. Номер карты             ************3071
  1401. Тип карты                           VISA
  1402. Номер ссылки (RRN)          871181727877
  1403. Номер транзакции                       2
  1404. Код авторизации                   269026
  1405. Код ответа                            00
  1406.  
  1407.  
  1408.                 ОДОБРЕНО
  1409. AID                       A0000000041010
  1410.  
  1411.  
  1412. ________________________________________
  1413.             Подпись кассира
  1414. 27.03 14:28:21 INFO  [AbstractFileBasedBank] makeSlip finished
  1415. 27.03 14:28:21 INFO  [AbstractFileBasedBank] Operation SALE successful
  1416. 27.03 14:28:21 INFO  [BankImpl] AuthorizationData: AuthorizationData
  1417. amount=380849
  1418. currencyCode=RUB
  1419. date=Wed Mar 27 14:28:21 MSK 2019
  1420. authCode=269026
  1421. refNumber=871181727877
  1422. cashTransId=345030
  1423. card=Number: ************3071, Number hash: null, Type: VISA, Expiration: Fri Mar 01 00:00:00 MSK 2019, Operator: UNKNOWN.
  1424. operationCode=1
  1425. terminalId=10000001
  1426. responseCode=0
  1427. message=ОДОБРЕНО
  1428. status=true
  1429.  
  1430. 27.03 14:28:21 INFO  [TechProcessImpl] addPayment BankCardPaymentEntity 380849
  1431. 27.03 14:28:21 TRACE [TechProcessEventsImpl] entering purchaseChangedAtPaymentsStage(PurchaseEntity, List). The arguments are: purchase [PurchaseEntity [id=9420, number=null, dateCreate=Wed Mar 27 14:27:37 MSK 2019, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]], notifications [[]]
  1432. 27.03 14:28:21 INFO  [CommonLogger] value = 163222
  1433. 27.03 14:28:21 TRACE [TechProcessEventsImpl] leaving purchaseChangedAtPaymentsStage(PurchaseEntity, List). It took 141 [ms]
  1434. 27.03 14:28:21 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  1435. 27.03 14:28:21 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1436. 27.03 14:28:21 INFO  [TextCustomerDisplayPluginAbstract] *Оплачено     3808.49*
  1437. 27.03 14:28:21 INFO  [TextCustomerDisplayPluginAbstract] *Cдача           0.00*
  1438. 27.03 14:28:21 INFO  [TextCustomerDisplayPluginAbstract] **********************
  1439. 27.03 14:28:21 INFO  [TextCustomerDisplayPluginAbstract]
  1440. 27.03 14:28:21 INFO  [TechProcessShift] fiscalizeCheck PurchaseEntity [id=9420, number=null, dateCreate=Wed Mar 27 14:27:37 MSK 2019, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  1441. 27.03 14:28:22 INFO  [FiscalPrinter] getLastKpk
  1442. 27.03 14:28:22 INFO  [FiscalPrinter] LastKpk = 964
  1443. 27.03 14:28:22 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - true
  1444. 27.03 14:28:22 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - false
  1445. 27.03 14:28:22 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - true
  1446. 27.03 14:28:22 INFO  [FiscalPrinter] printCheck
  1447. 27.03 14:28:22 INFO  [FiscalPrinter] getLastKpk
  1448. 27.03 14:28:22 INFO  [FiscalPrinter] LastKpk = 964
  1449. 27.03 14:28:22 INFO  [FiscalPrinter] Fiscal interrupt: null
  1450. 27.03 14:28:22 INFO  [FiscalPrinter] Load document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/check-sale.xml
  1451. 27.03 14:28:22 INFO  [DocumentTemplate] generate(File xmlTemplate) method gets invoked...
  1452. 27.03 14:28:22 INFO  [DocumentTemplate] Length of a xmlTemplate object array is: 17731
  1453. 27.03 14:28:22 INFO  [DocumentTemplate] Creating JAXB context...
  1454. 27.03 14:28:22 INFO  [DocumentTemplate] Creating unmarshaller...
  1455. 27.03 14:28:22 INFO  [DocumentTemplate] Trying to read data from the array...
  1456. 27.03 14:28:22 INFO  [DocumentTemplate] Unmarshalling was complete successfully!
  1457. 27.03 14:28:22 INFO  [DocumentTemplate] =====logo=====
  1458. 27.03 14:28:22 INFO  [DocumentTemplate] content: null
  1459. 27.03 14:28:22 INFO  [DocumentTemplate] =====header=====
  1460. 27.03 14:28:22 INFO  [DocumentTemplate]                    jr. name                   ||NORMAL
  1461. 27.03 14:28:22 INFO  [DocumentTemplate]          199100, Spb, Savushkina, 112         ||NORMAL
  1462. 27.03 14:28:22 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  1463. 27.03 14:28:22 INFO  [DocumentTemplate] ЗВД.N:00000721661         ФН:      a131ccfd-1b||NORMAL
  1464. 27.03 14:28:22 INFO  [DocumentTemplate] РЕГ.N:NFM.7216.61.0.155362ИНН:     7802781104 ||NORMAL
  1465. 27.03 14:28:22 INFO  [DocumentTemplate] 01    Администраторов Адми     КАССА: 61 ОТД01||NORMAL
  1466. 27.03 14:28:22 INFO  [DocumentTemplate]   ЧЕК НА ПРОДАЖУ N:          6              ||NORMAL
  1467. 27.03 14:28:22 INFO  [DocumentTemplate]                                 СМЕНА N:      ||NORMAL
  1468. 27.03 14:28:22 INFO  [DocumentTemplate] =====text=====
  1469. 27.03 14:28:22 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  1470. 27.03 14:28:22 INFO  [DocumentTemplate] Кассовый чек (ПРИХОД)                         ||NORMAL
  1471. 27.03 14:28:22 INFO  [DocumentTemplate]                *ПРОДАЖА ТОВАРА*               ||NORMAL
  1472. 27.03 14:28:22 INFO  [DocumentTemplate] =====positionSectionWithGoodSets=====
  1473. 27.03 14:28:22 INFO  [DocumentTemplate] ------------------------------------------||NORMAL
  1474. 27.03 14:28:22 INFO  [DocumentTemplate] Хлеб банановый слад    43.63     *1     =43.63||SMALL
  1475. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без    62.33     *1     =62.33||SMALL
  1476. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1477. 27.03 14:28:22 INFO  [DocumentTemplate] Йогурт грушевый кис   389.07     *1    =389.07||SMALL
  1478. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   555.81     *1    =555.81||SMALL
  1479. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1480. 27.03 14:28:22 INFO  [DocumentTemplate] Напиток виноградный   512.74     *1    =512.74||SMALL
  1481. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   732.48     *1    =732.48||SMALL
  1482. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1483. 27.03 14:28:22 INFO  [DocumentTemplate] Напиток малиновый г   874.82     *1    =874.82||SMALL
  1484. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без  1249.75     *1   =1249.75||SMALL
  1485. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1486. 27.03 14:28:22 INFO  [DocumentTemplate] Кефир лимонный густ    17.60     *1     =17.60||SMALL
  1487. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без    25.15     *1     =25.15||SMALL
  1488. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1489. 27.03 14:28:22 INFO  [DocumentTemplate] Лимонад грушевый ар   671.26     *1    =671.26||SMALL
  1490. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   958.94     *1    =958.94||SMALL
  1491. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1492. 27.03 14:28:22 INFO  [DocumentTemplate] Напиток вишневый гу   337.01     *1    =337.01||SMALL
  1493. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   481.45     *1    =481.45||SMALL
  1494. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1495. 27.03 14:28:22 INFO  [DocumentTemplate] Шоколад лимонный сл   372.14     *1    =372.14||SMALL
  1496. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   531.63     *1    =531.63||SMALL
  1497. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1498. 27.03 14:28:22 INFO  [DocumentTemplate] Хлеб клубничный пря   405.63     *1    =405.63||SMALL
  1499. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   579.47     *1    =579.47||SMALL
  1500. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1501. 27.03 14:28:22 INFO  [DocumentTemplate] Шоколад яблочный го   184.59     *1    =184.59||SMALL
  1502. 27.03 14:28:22 INFO  [DocumentTemplate]     Цена товара без   263.70     *1    =263.70||SMALL
  1503. 27.03 14:28:22 INFO  [DocumentTemplate]     в т.ч. НДС 20%   ||SMALL
  1504. 27.03 14:28:22 INFO  [DocumentTemplate] ------------------------------------------||NORMAL
  1505. 27.03 14:28:22 INFO  [DocumentTemplate] =====payment=====
  1506. 27.03 14:28:22 INFO  [DocumentTemplate] ПОДИТОГ                               =3808.49||SMALL
  1507. 27.03 14:28:22 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  1508. 27.03 14:28:22 INFO  [DocumentTemplate]          ИТОГО к ОПЛАТЕ         =3808.49      ||NORMAL
  1509. 27.03 14:28:22 INFO  [DocumentTemplate]        БАНКОВСКАЯ КАРТА         =3808.49      ||NORMAL
  1510. 27.03 14:28:22 INFO  [DocumentTemplate] =====text=====
  1511. 27.03 14:28:22 INFO  [DocumentTemplate] ----------------------------------------------||NORMAL
  1512. 27.03 14:28:22 INFO  [DocumentTemplate] СКИДКА ПО ДАННОМУ ЧЕКУ, руб           =1632.22||DOUBLEHEIGHT
  1513. 27.03 14:28:22 INFO  [DocumentTemplate]  ||NORMAL
  1514. 27.03 14:28:22 INFO  [DocumentTemplate]                *ПРОДАЖА ТОВАРА*               ||NORMAL
  1515. 27.03 14:28:22 INFO  [DocumentTemplate]                                               ||NORMAL
  1516. 27.03 14:28:22 INFO  [DocumentTemplate]              СПАСИБО ЗА ПОКУПКУ!              ||NORMAL
  1517. 27.03 14:28:22 INFO  [DocumentTemplate]                                               ||NORMAL
  1518. 27.03 14:28:22 INFO  [DocumentTemplate]                                               ||NORMAL
  1519. 27.03 14:28:22 INFO  [DocumentTemplate] =====text=====
  1520. 27.03 14:28:22 INFO  [DocumentTemplate] =====bonuses=====
  1521. 27.03 14:28:22 INFO  [DocumentTemplate] =====fiscal=====
  1522. 27.03 14:28:22 INFO  [DocumentTemplate]                                            ||NORMAL
  1523. 27.03 14:28:22 INFO  [DocumentTemplate]        Вся информация на www.lenta.com     ||NORMAL
  1524. 27.03 14:28:22 INFO  [DocumentTemplate]           и по тел. 8-800-700-4111         ||NORMAL
  1525. 27.03 14:28:22 INFO  [DocumentTemplate]          звонок по России бесплатный       ||NORMAL
  1526. 27.03 14:28:22 INFO  [DocumentTemplate] =====cut=====
  1527. 27.03 14:28:22 INFO  [DocumentTemplate] content: null
  1528. 27.03 14:28:23 INFO  [FiscalPrinter] getLastKpk
  1529. 27.03 14:28:23 INFO  [FiscalPrinter] LastKpk = 965
  1530. 27.03 14:28:23 INFO  [FiscalPrinter] getLastDocNum
  1531. 27.03 14:28:23 INFO  [CheckService] fiscalizeCheck: PurchaseEntity [id=9420, number=6, dateCreate=Wed Mar 27 14:27:37 MSK 2019, dateCommit=Wed Mar 27 14:28:23 MSK 2019, fiscalDocNum=965;1973, sentToServerStatus=UNCOMMITED]
  1532. 27.03 14:28:23 TRACE [ExternalProcessingsImpl] entering purchaseFiscalized(PurchaseEntity)
  1533. 27.03 14:28:23 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of PlastekServices.
  1534. 27.03 14:28:23 TRACE [ExternalProcessingsImpl] Purchase fiscalized: no implementation of externalLoyaltyService.
  1535. 27.03 14:28:23 TRACE [ExternalProcessingsImpl] about to notify ext-loy-providers that the current receipt [PurchaseEntity [id=9420, number=6, dateCreate=Wed Mar 27 14:27:37 MSK 2019, dateCommit=Wed Mar 27 14:28:23 MSK 2019, fiscalDocNum=965;1973, sentToServerStatus=UNCOMMITED]] was fiscalized...
  1536. 27.03 14:28:23 DEBUG [LoymaxUtils] Loymax request:
  1537. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLRequest><Version>3.2</Version><ConfirmPurchases><ConfirmPurchaseRequest PurchaseID="72160611553686087585" OperationID="f0f3ce16ec0f465da63528bdae94aae2" OperationDate="2019-03-27T14:28:23.450+03:00" ElementID="1"/></ConfirmPurchases></XMLRequest>
  1538.  
  1539. 27.03 14:28:23 DEBUG [LoymaxUtils] Loymax response:
  1540. <?xml version="1.0" encoding="UTF-8" standalone="yes"?><XMLResponse><ErrorCode>0</ErrorCode><ConfirmPurchases><ConfirmPurchaseResponse OperationID="f0f3ce16ec0f465da63528bdae94aae2" ErrorCode="0"/></ConfirmPurchases></XMLResponse>
  1541.  
  1542. 27.03 14:28:23 TRACE [ExternalProcessingsImpl] leaving purchaseFiscalized(PurchaseEntity). It took 263 [ms]
  1543. 27.03 14:28:25 INFO  [CardsFilesReader2] [1] new card catalogs were detected on the server, and [1] of them we are about to process right now
  1544. 27.03 14:28:25 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258905; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142825_2f78d933-73ef-42c1-a817-f04168894bca.ser"]) were persisted successfully!
  1545. 27.03 14:28:26 INFO  [SpeedLog] cards-catalog [file-id: 258905, file-url: http://172.29.17.216:8091/cards/cards_20190327_142825_2f78d933-73ef-42c1-a817-f04168894bca.ser] processing time (in millis): read-time: 10, processing-time: 5, last-id-update-time: 34
  1546. 27.03 14:28:26 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,050 sec; average speed = 0,035 sec
  1547. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1548. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1549. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1550. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1551. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1552. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1553. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1554. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1555. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1556. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1557. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1558. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1559. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1560. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1561. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1562. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1563. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1564. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1565. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1566. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1567. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1568. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1569. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1570. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1571. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1572. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1573. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1574. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1575. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1576. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1577. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1578. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1579. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1580. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1581. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1582. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1583. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1584. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1585. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1586. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1587. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1588. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1589. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1590. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1591. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1592. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1593. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1594. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1595. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1596. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1597. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1598. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1599. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1600. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1601. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1602. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1603. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1604. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1605. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1606. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1607. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1608. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1609. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1610. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1611. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1612. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1613. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1614. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1615. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1616. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1617. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1618. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1619. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1620. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1621. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1622. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1623. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1624. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1625. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1626. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1627. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1628. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1629. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1630. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1631. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1632. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1633. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1634. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1635. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1636. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1637. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1638. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1639. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1640. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1641. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1642. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1643. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1644. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1645. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1646. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1647. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1648. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1649. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1650. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1651. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1652. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1653. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1654. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1655. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1656. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1657. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1658. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1659. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1660. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1661. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1662. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1663. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1664. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1665. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1666. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1667. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1668. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1669. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1670. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1671. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1672. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1673. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1674. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1675. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1676. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1677. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1678. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1679. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1680. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1681. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1682. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1683. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1684. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1685. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1686. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1687. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1688. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1689. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1690. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1691. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1692. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1693. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1694. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1695. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1696. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1697. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1698. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1699. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1700. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1701. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1702. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1703. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1704. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1705. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1706. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1707. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1708. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1709. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1710. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1711. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1712. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1713. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1714. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1715. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1716. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1717. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1718. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1719. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1720. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1721. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1722. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1723. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1724. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1725. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1726. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1727. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1728. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1729. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1730. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1731. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1732. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1733. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1734. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1735. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1736. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1737. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1738. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1739. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1740. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1741. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1742. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1743. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1744. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1745. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1746. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1747. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1748. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1749. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1750. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1751. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1752. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1753. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1754. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1755. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1756. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1757. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1758. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1759. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1760. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1761. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1762. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1763. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1764. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1765. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1766. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1767. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1768. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1769. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1770. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1771. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1772. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1773. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1774. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1775. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1776. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1777. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1778. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1779. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1780. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1781. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1782. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1783. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1784. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1785. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1786. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1787. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1788. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1789. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1790. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1791. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1792. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1793. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1794. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1795. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1796. 27.03 14:28:26 INFO  [DocumentTemplate]  Уважаемый(ая)  .                             ||NORMAL
  1797. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1798. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00. Размер скидки в чеке           равен =  ||NORMAL
  1799. 27.03 14:28:26 INFO  [DocumentTemplate] 1632.22. Размер скидки по купону: 0.00.   Дата||NORMAL
  1800. 27.03 14:28:26 INFO  [DocumentTemplate] покупки:  27 марта 2019.                      ||NORMAL
  1801. 27.03 14:28:26 INFO  [DocumentTemplate]                                               ||NORMAL
  1802. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1803. 27.03 14:28:26 INFO  [FiscalPrinter] printServiceDocument
  1804. 27.03 14:28:26 INFO  [DocumentTemplate] =====0=====
  1805. 27.03 14:28:26 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1806. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1807. 27.03 14:28:26 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1808. 27.03 14:28:26 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1809. 27.03 14:28:26 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1810. 27.03 14:28:26 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1811. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1812. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1813. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1814. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1815. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1816. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1817. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1818. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1819. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1820. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1821. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1822. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1823. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1824. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1825. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1826. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1827. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1828. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1829. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1830. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1831. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1832. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1833. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1834. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1835. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1836. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1837. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1838. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1839. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1840. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1841. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1842. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1843. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1844. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1845. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1846. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1847. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1848. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1849. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1850. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1851. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1852. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1853. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1854. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1855. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1856. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1857. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1858. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1859. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1860. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1861. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1862. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1863. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1864. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1865. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1866. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1867. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1868. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1869. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1870. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1871. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1872. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1873. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1874. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1875. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1876. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1877. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1878. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1879. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1880. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1881. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1882. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1883. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1884. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1885. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1886. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1887. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1888. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1889. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1890. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1891. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1892. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1893. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1894. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1895. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1896. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1897. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1898. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1899. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1900. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1901. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1902. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1903. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1904. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1905. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1906. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1907. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1908. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1909. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1910. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1911. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1912. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1913. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1914. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1915. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1916. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1917. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1918. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1919. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1920. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1921. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1922. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1923. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1924. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1925. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1926. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1927. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1928. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1929. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1930. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1931. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1932. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1933. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1934. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1935. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1936. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1937. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1938. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1939. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1940. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1941. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1942. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1943. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1944. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1945. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1946. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1947. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1948. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1949. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1950. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1951. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1952. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1953. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1954. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1955. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1956. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1957. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1958. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1959. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1960. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1961. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1962. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1963. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1964. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1965. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1966. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1967. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1968. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1969. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1970. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1971. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1972. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1973. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1974. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1975. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1976. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1977. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1978. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1979. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1980. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1981. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1982. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1983. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1984. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1985. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1986. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1987. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1988. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1989. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1990. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1991. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  1992. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  1993. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  1994. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  1995. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  1996. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  1997. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  1998. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  1999. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  2000. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  2001. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  2002. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  2003. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  2004. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  2005. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  2006. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  2007. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  2008. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  2009. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  2010. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  2011. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  2012. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  2013. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  2014. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  2015. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  2016. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  2017. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  2018. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  2019. 27.03 14:28:27 INFO  [FiscalPrinter] printServiceDocument
  2020. 27.03 14:28:27 INFO  [DocumentTemplate] =====0=====
  2021. 27.03 14:28:27 INFO  [DocumentTemplate] Уважаемый(ая) .                               ||NORMAL
  2022. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки по данной акции составляет:     ||NORMAL
  2023. 27.03 14:28:27 INFO  [DocumentTemplate] 0.00.                                         ||NORMAL
  2024. 27.03 14:28:27 INFO  [DocumentTemplate] Размер скидки в чеке равен =1632.22.          ||NORMAL
  2025. 27.03 14:28:27 INFO  [DocumentTemplate] Дата покупки: 27 марта 2019.                  ||NORMAL
  2026. 27.03 14:28:27 INFO  [AbstractFiscalPrinterEmulator] printServiceDocument
  2027. 27.03 14:28:27 INFO  [FiscalPrinter] printDiscountsReport
  2028. 27.03 14:28:27 INFO  [FiscalPrinter] Load document template /mnt/sda1/tce/storage/crystal-cash/modules/fiscalPrinter/templates/discounts-report.xml
  2029. 27.03 14:28:27 INFO  [DocumentTemplate] generate(File xmlTemplate) method gets invoked...
  2030. 27.03 14:28:27 INFO  [DocumentTemplate] Length of a xmlTemplate object array is: 4391
  2031. 27.03 14:28:27 INFO  [DocumentTemplate] Creating JAXB context...
  2032. 27.03 14:28:27 INFO  [DocumentTemplate] Creating unmarshaller...
  2033. 27.03 14:28:27 INFO  [DocumentTemplate] Trying to read data from the array...
  2034. 27.03 14:28:27 INFO  [DocumentTemplate] Unmarshalling was complete successfully!
  2035. 27.03 14:28:27 INFO  [DocumentTemplate] =====logo=====
  2036. 27.03 14:28:27 INFO  [DocumentTemplate] content: null
  2037. 27.03 14:28:27 INFO  [DocumentTemplate] =====header=====
  2038. 27.03 14:28:27 INFO  [DocumentTemplate]                                               ||NORMAL
  2039. 27.03 14:28:27 INFO  [DocumentTemplate]                                               ||NORMAL
  2040. 27.03 14:28:27 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  2041. 27.03 14:28:27 INFO  [DocumentTemplate] Смена:49                     ЭКЛЗ:a131ccfd-1b7||NORMAL
  2042. 27.03 14:28:27 INFO  [DocumentTemplate] Номер кассы:61                ИНН:  7802781104||NORMAL
  2043. 27.03 14:28:27 INFO  [DocumentTemplate] Кассир:Администраторов Администратор Администр||NORMAL
  2044. 27.03 14:28:27 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  2045. 27.03 14:28:27 INFO  [DocumentTemplate] ОТЧЕТ ПО СКИДКАМ        6    27-03-2019  14:28||NORMAL
  2046. 27.03 14:28:27 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  2047. 27.03 14:28:27 INFO  [DocumentTemplate] =====groupByGoods=====
  2048. 27.03 14:28:27 INFO  [DocumentTemplate] content: null
  2049. 27.03 14:28:27 INFO  [DocumentTemplate] =====groupByAA=====
  2050. 27.03 14:28:27 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  2051. 27.03 14:28:27 INFO  [DocumentTemplate] По акциям           ||NORMAL
  2052. 27.03 14:28:27 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  2053. 27.03 14:28:27 INFO  [DocumentTemplate] Корректное условие                            ||SMALL
  2054. 27.03 14:28:27 INFO  [DocumentTemplate]   Хлеб банановый сладкий (Росси*1       =18.70||SMALL
  2055. 27.03 14:28:27 INFO  [DocumentTemplate]   Йогурт грушевый кисло-сладкий*1      =166.74||SMALL
  2056. 27.03 14:28:27 INFO  [DocumentTemplate]   Напиток виноградный сочный (Ю*1      =219.74||SMALL
  2057. 27.03 14:28:27 INFO  [DocumentTemplate]   Напиток малиновый густой (Бел*1      =374.93||SMALL
  2058. 27.03 14:28:27 INFO  [DocumentTemplate]   Кефир лимонный густой (Россия*1        =7.55||SMALL
  2059. 27.03 14:28:27 INFO  [DocumentTemplate]   Лимонад грушевый ароматный (Ф*1      =287.68||SMALL
  2060. 27.03 14:28:27 INFO  [DocumentTemplate]   Напиток вишневый густой (Япон*1      =144.44||SMALL
  2061. 27.03 14:28:27 INFO  [DocumentTemplate]   Шоколад лимонный сладкий (Вел*1      =159.49||SMALL
  2062. 27.03 14:28:27 INFO  [DocumentTemplate]   Хлеб клубничный пряный (Велик*1      =173.84||SMALL
  2063. 27.03 14:28:27 INFO  [DocumentTemplate]   Шоколад яблочный горький (Исп*1       =79.11||SMALL
  2064. 27.03 14:28:27 INFO  [DocumentTemplate] =====checkDiscounts=====
  2065. 27.03 14:28:27 INFO  [DocumentTemplate] content: null
  2066. 27.03 14:28:27 INFO  [DocumentTemplate] =====total=====
  2067. 27.03 14:28:27 INFO  [DocumentTemplate] ----------------------------------------      ||NORMAL
  2068. 27.03 14:28:27 INFO  [DocumentTemplate] ИТОГО СКИДКА НА ЧЕК                   =1632.22||NORMAL
  2069. 27.03 14:28:27 INFO  [DocumentTemplate] =====fiscal=====
  2070. 27.03 14:28:27 INFO  [DocumentTemplate] content: null
  2071. 27.03 14:28:27 INFO  [DocumentTemplate] =====footer=====
  2072. 27.03 14:28:27 INFO  [DocumentTemplate] Док N:6                     27-03-2019 14:28  ||NORMAL
  2073. 27.03 14:28:27 INFO  [DocumentTemplate] Спасибо за покупку!                           ||NORMAL
  2074. 27.03 14:28:27 INFO  [DocumentTemplate] =====cut=====
  2075. 27.03 14:28:27 INFO  [DocumentTemplate] content: null
  2076. 27.03 14:28:27 TRACE [MLServiceImpl] entering isEnabled()
  2077. 27.03 14:28:27 TRACE [MLServiceImpl] leaving isEnabled(). the result is: false
  2078. 27.03 14:28:27 TRACE [SCService] entering isEnabled()
  2079. 27.03 14:28:27 TRACE [SCService] leaving isEnabled(). the result is: false
  2080. 27.03 14:28:27 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - false
  2081. 27.03 14:28:27 INFO  [TechProcessShift] Check = PurchaseEntity [id=9420, number=6, dateCreate=Wed Mar 27 14:27:37 MSK 2019, dateCommit=Wed Mar 27 14:28:23 MSK 2019, fiscalDocNum=965;1973, sentToServerStatus=NO_SENT]
  2082.  checkNum = 0
  2083.  checkModule.getCurrentPurchaseEntry().getCurrentState() = REG_CHECK
  2084. 27.03 14:28:27 TRACE [ExternalProcessingsImpl] afterFiscalize: no implementation of externalLoyaltyService
  2085. 27.03 14:28:27 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - false
  2086. 27.03 14:28:27 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - true
  2087. 27.03 14:28:27 INFO  [WaitComponent] [WaitComponent] MainFrame enabled - true
  2088. 27.03 14:28:27 INFO  [TextCustomerDisplayPluginAbstract] Screen updated:
  2089. 27.03 14:28:27 INFO  [TextCustomerDisplayPluginAbstract] **********************
  2090. 27.03 14:28:27 INFO  [TextCustomerDisplayPluginAbstract] *                    *
  2091. 27.03 14:28:27 INFO  [TextCustomerDisplayPluginAbstract] *                    *
  2092. 27.03 14:28:27 INFO  [TextCustomerDisplayPluginAbstract] **********************
  2093. 27.03 14:28:27 INFO  [TextCustomerDisplayPluginAbstract]
  2094. 27.03 14:28:27 INFO  [SpeedLog] TechProcess fiscalizeCheck operationType = true, positions count = 10  time = 5979 ms
  2095. 27.03 14:28:27 INFO  [FiscalPrinter] getPrintedDocumentWithOffset() invoked
  2096. 27.03 14:28:27 INFO  [FiscalPrinter] FilePrinter.getLastPrinted() invoked
  2097. 27.03 14:28:28 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  2098. 27.03 14:28:28 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  2099. 27.03 14:28:29 INFO  [TechProcessImpl] eventControlKey: ControlKey [scanCode=27, command=kbdCancel]
  2100. 27.03 14:28:32 INFO  [TransferManager] Nothing yet not processed on server to resend
  2101. 27.03 14:28:32 WARN  [MainWindow] Focus has been lost (FOCUS_LOST,permanent,opposite=javax.swing.JTextPane[,0,69,600x74,layout=javax.swing.plaf.basic.BasicTextUI$UpdateHandler,alignmentX=0.0,alignmentY=0.0,border=javax.swing.plaf.basic.BasicBorders$MarginBorder@182a16b,flags=16777504,maximumSize=,minimumSize=,preferredSize=,caretColor=sun.swing.PrintColorUIResource[r=51,g=51,b=51],disabledTextColor=javax.swing.plaf.ColorUIResource[r=184,g=207,b=229],editable=false,margin=ru.crystals.pos.visualization.utils.ScaleInsets[top=0,left=0,bottom=0,right=0],selectedTextColor=sun.swing.PrintColorUIResource[r=51,g=51,b=51],selectionColor=javax.swing.plaf.ColorUIResource[r=184,g=207,b=229],kit=javax.swing.text.StyledEditorKit@1cf9ab0,typeHandlers={text/plain=javax.swing.text.StyledEditorKit@1cf9ab0}])
  2102. 27.03 14:28:32 DEBUG [TechProcessImpl] Server online mode
  2103. 27.03 14:28:36 INFO  [CardsFilesReader2] [8] new card catalogs were detected on the server, and [8] of them we are about to process right now
  2104. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 258977; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142826_bdfa20b2-a037-46c9-8102-339900d3aa80.ser"]) were persisted successfully!
  2105. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 258977, file-url: http://172.29.17.216:8091/cards/cards_20190327_142826_bdfa20b2-a037-46c9-8102-339900d3aa80.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 12
  2106. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,035 sec
  2107. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259051; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_f0372ccf-a553-4ae5-87c9-f512dbf303f1.ser"]) were persisted successfully!
  2108. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259051, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_f0372ccf-a553-4ae5-87c9-f512dbf303f1.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 10
  2109. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,016 sec; average speed = 0,034 sec
  2110. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259163; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_69ec20c0-78e3-4f35-9366-bf11379a9f49.ser"]) were persisted successfully!
  2111. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259163, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_69ec20c0-78e3-4f35-9366-bf11379a9f49.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 11
  2112. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,034 sec
  2113. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259368; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_c55a82c6-b35b-45b8-b1e6-e81ae348baa7.ser"]) were persisted successfully!
  2114. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259368, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_c55a82c6-b35b-45b8-b1e6-e81ae348baa7.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 10
  2115. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,016 sec; average speed = 0,034 sec
  2116. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259371; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_09af610c-7bb5-4979-aa55-c377546e9d15.ser"]) were persisted successfully!
  2117. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259371, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_09af610c-7bb5-4979-aa55-c377546e9d15.ser] processing time (in millis): read-time: 3, processing-time: 4, last-id-update-time: 29
  2118. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,036 sec; average speed = 0,034 sec
  2119. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259395; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_5c460a57-6eb6-4ead-8cf2-6d9aef5da9c9.ser"]) were persisted successfully!
  2120. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259395, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_5c460a57-6eb6-4ead-8cf2-6d9aef5da9c9.ser] processing time (in millis): read-time: 1, processing-time: 7, last-id-update-time: 12
  2121. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,020 sec; average speed = 0,034 sec
  2122. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259451; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_0668bd4f-1d1e-436e-a0cc-fa8fb3394229.ser"]) were persisted successfully!
  2123. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259451, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_0668bd4f-1d1e-436e-a0cc-fa8fb3394229.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 12
  2124. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,033 sec
  2125. 27.03 14:28:36 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259463; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142834_d714afaf-5957-421a-ab86-d7d1f8964248.ser"]) were persisted successfully!
  2126. 27.03 14:28:36 INFO  [SpeedLog] cards-catalog [file-id: 259463, file-url: http://172.29.17.216:8091/cards/cards_20190327_142834_d714afaf-5957-421a-ab86-d7d1f8964248.ser] processing time (in millis): read-time: 1, processing-time: 3, last-id-update-time: 10
  2127. 27.03 14:28:36 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,014 sec; average speed = 0,033 sec
  2128. 27.03 14:28:44 INFO  [TransferManager] Nothing yet not processed on server to resend
  2129. 27.03 14:28:46 INFO  [CardsFilesReader2] [2] new card catalogs were detected on the server, and [2] of them we are about to process right now
  2130. 27.03 14:28:46 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259537; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142839_d9015eae-c72f-4939-81b2-82765ded4561.ser"]) were persisted successfully!
  2131. 27.03 14:28:46 INFO  [SpeedLog] cards-catalog [file-id: 259537, file-url: http://172.29.17.216:8091/cards/cards_20190327_142839_d9015eae-c72f-4939-81b2-82765ded4561.ser] processing time (in millis): read-time: 5, processing-time: 5, last-id-update-time: 11
  2132. 27.03 14:28:46 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,021 sec; average speed = 0,033 sec
  2133. 27.03 14:28:46 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259609; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142840_0c7126f7-fb72-447d-a24f-3f2acb2195f7.ser"]) were persisted successfully!
  2134. 27.03 14:28:46 INFO  [SpeedLog] cards-catalog [file-id: 259609, file-url: http://172.29.17.216:8091/cards/cards_20190327_142840_0c7126f7-fb72-447d-a24f-3f2acb2195f7.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 10
  2135. 27.03 14:28:46 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,015 sec; average speed = 0,033 sec
  2136. 27.03 14:28:46 INFO  [DocumentSender] ping = true
  2137. 27.03 14:28:56 INFO  [TransferManager] Nothing yet not processed on server to resend
  2138. 27.03 14:29:02 DEBUG [TechProcessImpl] Server online mode
  2139. 27.03 14:29:03 INFO  [PendingOperationQueue] Pending cards operation queue is empty. Populating from DB...
  2140. 27.03 14:29:03 INFO  [PendingOperationQueue] No pending card operations found.
  2141. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Resend stale feedback
  2142. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] looking up LoyFeedbackDao...
  2143. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = SetApiLoyaltyPlugin
  2144. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for SetApiLoyaltyPlugin
  2145. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = set-loy-provider
  2146. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for set-loy-provider
  2147. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = loymax
  2148. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for loymax
  2149. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = sm
  2150. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for sm
  2151. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = kopilka
  2152. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for kopilka
  2153. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = ml
  2154. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for ml
  2155. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] Search for stale feedbacks provider = smch
  2156. 27.03 14:29:03 INFO  [LoyProvidersRegistryImpl] There is no stale feedback for smch
  2157. 27.03 14:29:06 INFO  [CardsFilesReader2] [9] new card catalogs were detected on the server, and [9] of them we are about to process right now
  2158. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259840; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142856_f2102ada-7da4-4573-ba14-b517f5400fff.ser"]) were persisted successfully!
  2159. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 259840, file-url: http://172.29.17.216:8091/cards/cards_20190327_142856_f2102ada-7da4-4573-ba14-b517f5400fff.ser] processing time (in millis): read-time: 5, processing-time: 6, last-id-update-time: 13
  2160. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,024 sec; average speed = 0,032 sec
  2161. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 259912; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142857_f0418c3a-cf04-4b37-85f3-cc617078e3d2.ser"]) were persisted successfully!
  2162. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 259912, file-url: http://172.29.17.216:8091/cards/cards_20190327_142857_f0418c3a-cf04-4b37-85f3-cc617078e3d2.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 12
  2163. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,032 sec
  2164. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260095; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_a0d500ee-8d57-4e2c-a870-b9360389adc9.ser"]) were persisted successfully!
  2165. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260095, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_a0d500ee-8d57-4e2c-a870-b9360389adc9.ser] processing time (in millis): read-time: 1, processing-time: 6, last-id-update-time: 10
  2166. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,017 sec; average speed = 0,032 sec
  2167. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260109; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_247b748e-47e4-4660-9e68-d606d10063c8.ser"]) were persisted successfully!
  2168. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260109, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_247b748e-47e4-4660-9e68-d606d10063c8.ser] processing time (in millis): read-time: 2, processing-time: 3, last-id-update-time: 13
  2169. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,018 sec; average speed = 0,032 sec
  2170. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260177; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_b9acd0e6-3985-4334-8909-c1dd8ae5e6ce.ser"]) were persisted successfully!
  2171. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260177, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_b9acd0e6-3985-4334-8909-c1dd8ae5e6ce.ser] processing time (in millis): read-time: 2, processing-time: 4, last-id-update-time: 13
  2172. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,032 sec
  2173. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260205; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_3ac4e7c8-11d3-4535-b205-1c4f2ab7e2b4.ser"]) were persisted successfully!
  2174. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260205, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_3ac4e7c8-11d3-4535-b205-1c4f2ab7e2b4.ser] processing time (in millis): read-time: 2, processing-time: 6, last-id-update-time: 15
  2175. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,023 sec; average speed = 0,032 sec
  2176. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260302; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_7ce4eeaf-77a0-4297-b0d2-4a134be5dd06.ser"]) were persisted successfully!
  2177. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260302, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_7ce4eeaf-77a0-4297-b0d2-4a134be5dd06.ser] processing time (in millis): read-time: 1, processing-time: 4, last-id-update-time: 14
  2178. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,031 sec
  2179. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260329; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_fe8c9736-e70a-4867-87a1-b9cf76e1c608.ser"]) were persisted successfully!
  2180. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260329, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_fe8c9736-e70a-4867-87a1-b9cf76e1c608.ser] processing time (in millis): read-time: 2, processing-time: 4, last-id-update-time: 13
  2181. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,021 sec; average speed = 0,031 sec
  2182. 27.03 14:29:06 INFO  [CardsFilesReader2] [1] card-relating entities (from card-catalog [file-id: 260398; file-url: "http://172.29.17.216:8091/cards/cards_20190327_142905_b5b376ec-9817-4781-97f5-da11123251c6.ser"]) were persisted successfully!
  2183. 27.03 14:29:06 INFO  [SpeedLog] cards-catalog [file-id: 260398, file-url: http://172.29.17.216:8091/cards/cards_20190327_142905_b5b376ec-9817-4781-97f5-da11123251c6.ser] processing time (in millis): read-time: 1, processing-time: 5, last-id-update-time: 13
  2184. 27.03 14:29:06 INFO  [SpeedLog] (CARDS TRANSPORT) process file time = 0,019 sec; average speed = 0,031 sec
  2185. 27.03 14:29:08 INFO  [TransferManager] Nothing yet not processed on server to resend
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!